Alright, some progress. It seems the rild, i.e. the low level daemon connecting to radios is working and calls are coming through, but then the phone framework doesn't understand the message that rild is sending. Got this out by running:
Have to debug more tomorrow.
Code:
adb logcat -b radio
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_process_event: Exit QCRIL_EVT_QMI_VOICE_HANDLE_INDICATIONS, err_no 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_event_main: Waiting...
V/use-Rlog/RLOG-RILC( 296): processWakeupCallback
D/RILJ ( 993): [0269]> GET_CURRENT_CALLS
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: UI --- RIL_REQUEST_GET_CURRENT_CALLS (9) ---> RIL [RID 0, token id 269, data len 0]
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_create_android_live_params_copy: a-r-id 9, a-data 0, a-data-len 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_create_android_live_params_copy: .. params for Android request id 9 require no copy action (usually means empty payload)
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: method 3, datalen 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: already waiting for exec 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: android_request_param_holder b7f5d724 hex
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: pending req kind 0 hex, awaitingexec req kind 0 hex
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: token under exec 269
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: pre-exec token id 269, a-r-id 9, audit 0, nrender 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_android_request_render_execution: rendering exec for token id 269
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_nas_get_reported_voice_radio_tech: completed with voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_nas_control_get_reported_voice_radio_tech: completed with voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_nas_control_convert_radio_tech_to_radio_tech_family: entered voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_get_call_list_to_send: iteration through call objects start
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_voip_call_info_dump: param -1208520440
I/use-Rlog/RLOG-RILQ( 296): (0/296):RIL[0][main] qcril_qmi_voice_voip_call_info_dump: .. call android id 1, call qmi id 1, elaboration 0, 140012 hex
I/use-Rlog/RLOG-RILQ( 296): (0/296):RIL[0][main] qcril_qmi_voice_voip_call_info_dump: .. call state 8, call type 0, call mode 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_call_to_atel: call_to_atel: 1
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_map_qmi_to_ril_num_pi: qmi_num_pi = 0, number_len = 10
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_get_call_list_to_send: Call state 4, IsMT=1
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_get_call_type_info: result = 1, modem call type = 0, ril call type = 0, ril call domain = 1
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_get_call_type_info: audio_attrib_valid = 0, audio_attrib = 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_get_call_type_info: video_attrib_valid = 0, video_attrib = 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_handle_ril_call_entry: call type to be considered 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_is_cdma_voice_emergency_calls_present: nof calls 1, nof cdma voice calls 0, nof 1x/no_srv emergency calls 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_handle_ril_call_entry: ril call entry unchanged
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_nas_control_set_current_calls_number: entered 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_request_get_current_atel_calls: Reply to RIL --> Number of calls : 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_request_get_current_atel_calls: Reply to RIL --> call[0] :state Incoming index 1, toa 129, isMpty 0, isMT 1, als 0, isVoice 1, isVoicePrivacy 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_request_get_current_atel_calls: ...num CANTSEETHIS, num presentation 0, name (null), name presentation 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_qmi_voice_request_get_current_atel_calls: call_type = 0, call_domain = 1
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qcril_send_request_response: UI <--- RIL_REQUEST_GET_CURRENT_CALLS (9) Complete --- RIL [RID 0, Token id 269, Success, Len 4 ]
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_send_request_response_epilog: token under exec 269, completion token id 269
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_send_request_response_epilog: postage resp to token id 269, a-r-id 9 (intermed. 9)
D/use-Rlog/RLOG-RILC( 296): RIL onRequestComplete: Command channel closed
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: dispatch outcome = 0
E/RILJ ( 993): Exception processing unsol response: 11010Exception:java.lang.RuntimeException: Unrecognized unsol response: 11010
W/RILJ ( 993): [0269]< GET_CURRENT_CALLS exception, possible invalid RIL response
W/RILJ ( 993): com.android.internal.telephony.ATParseEx: illegal presentation 3407920
W/RILJ ( 993): at com.android.internal.telephony.DriverCall.presentationFromCLIP(DriverCall.java:142)
W/RILJ ( 993): at com.android.internal.telephony.RIL.responseCallList(RIL.java:3189)
W/RILJ ( 993): at com.android.internal.telephony.RIL.processSolicited(RIL.java:2313)
W/RILJ ( 993): at com.android.internal.telephony.RIL.processResponse(RIL.java:2225)
W/RILJ ( 993): at com.android.internal.telephony.RIL.access$400(RIL.java:211)
W/RILJ ( 993): at com.android.internal.telephony.RIL$RILReceiver.run(RIL.java:582)
W/RILJ ( 993): at java.lang.Thread.run(Thread.java:841)
E/use-Rlog/RLOG-RIL-RAWDATA( 333): 01 2F 00 80 09 01 04 00 00 2E 00 ..
D/use-Rlog/RLOG-RIL-QMI( 333): qmuxd: TX message on fd=19, to clientID = 2, len=82
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][qmi_cb] qcril_event_queue: RID 0 MID 0 Queued event QCRIL_EVT_QMI_VOICE_HANDLE_INDICATIONS (20 bytes)(obj 0xb7f71d48)
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_event_main: 1 items on queue
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_event_main: RID 0 MID 0 De-queued event QCRIL_EVT_QMI_VOICE_HANDLE_INDICATIONS (655376)(obj 0xb7f71d48)
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_process_event: RIL <=== QCRIL_EVT_QMI_VOICE_HANDLE_INDICATIONS(655376), RID 0, MID 0 === AMSS
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_unsol_ind_cb_helper: .. operational state 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_find_call_info_entry_by_elaboration_any_subset: entry with pattern 33554432
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_nas_get_reported_voice_radio_tech: completed with voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: qcril_qmi_voice_all_call_status_ind_hdlr - 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: call control for conn id 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: call control call_state 9
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: call state 9, IsMT=2
I/use-Rlog/RLOG-RILQ( 296): (0/296):RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: call state END for conn id 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_find_call_info_entry_by_call_qmi_id: entry with id 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_update_call_info_entry_mainstream: param -1208520440
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_update_call_info_entry_mainstream: call android id 1, call qmi id 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_update_call_info_entry_mainstream: .. call state 9, call type 0, call mode 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: call mode 3, call type 0, call got connected 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_setup_timed_callback: Set timer with ID 22
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: call failure cause 145
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_map_qmi_to_ril_last_call_failure_cause: map qmi reason: 145 to ril reason: 16
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_store_last_call_failure_cause: store ril reason: 16
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_respond_ril_last_call_failure_request: last_call_failure_cause is valid. Will send the response if there is any pending ril request.
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_reqlist_find_by_requests: [RID 0] Not found ReqList entry for RIL_REQUEST_LAST_CALL_FAIL_CAUSE(18)
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_reqlist_find_by_requests: [RID 0] Not found ReqList entry for <Unknown event> ?(851973)
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_find_call_info_entry_by_call_qmi_id: entry with id 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: [CallState] broadcast -a android.intent.action.PHONE_EXSTATE_CHANGED --ei STATE 9
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_send_unsol_response: [DEADLOCK-DEBUG] >> get the peding LOCK - qcril_send_unsol_response
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qmi_ril_android_pending_unsol_resp_lock: [DEADLOCK-DEBUG] qmi_ril_android_pending_unsol_resp_lock - Before Count : 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_send_unsol_response_epilog: UI <--- RIL_UNSOL_UNKOWN (11010) --- RIL [RID 0, Len 69, (null)]
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_send_unsol_response: [DEADLOCK-DEBUG] << get the peding UNLOCK - qcril_send_unsol_response
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qmi_ril_android_pending_unsol_resp_unlock: [DEADLOCK-DEBUG] qmi_ril_android_pending_unsol_resp_unlock - Before Count : 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_convert_call_mode_to_radio_tech_family: entered call_mode 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_nas_get_reported_voice_radio_tech: completed with voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_nas_control_get_reported_voice_radio_tech: completed with voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_nas_control_convert_radio_tech_to_radio_tech_family: entered voice_radio_tech 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: .. is deviant call pre 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qmi_ril_nw_reg_voice_is_voice_call_mode_reasonable_against_dev_cfg: .. mode_pref valid 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qmi_ril_nw_reg_voice_is_voice_call_mode_reasonable_against_dev_cfg: .. mode_pref 28
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: .. is deviant call final 0, is mode reasonable 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: .. imperative report 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_call_to_ims: qcril_qmi_voice_info.jbims: 0, call_to_ims: 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_call_to_atel: call_to_atel: 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: -- final call dump start --
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: - dumping call 1 out of 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_voip_call_info_dump: param -1208520440
I/use-Rlog/RLOG-RILQ( 296): (0/296):RIL[0][event] qcril_qmi_voice_voip_call_info_dump: .. call android id 1, call qmi id 255, elaboration 0, 140112 hex
I/use-Rlog/RLOG-RILQ( 296): (0/296):RIL[0][event] qcril_qmi_voice_voip_call_info_dump: .. call state 9, call type 0, call mode 3
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_all_call_status_ind_hdlr: -- final call dump end --
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_qmi_voice_is_cdma_voice_emergency_calls_present: nof calls 1, nof cdma voice calls 0, nof 1x/no_srv emergency calls 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_send_unsol_response: [DEADLOCK-DEBUG] >> get the peding LOCK - qcril_send_unsol_response
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qmi_ril_android_pending_unsol_resp_lock: [DEADLOCK-DEBUG] qmi_ril_android_pending_unsol_resp_lock - Before Count : 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_send_unsol_response_epilog: UI <--- RIL_UNSOL_RESPONSE_CALL_STATE_CHANGED (1001) --- RIL [RID 0, Len 0, (null)]
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_send_unsol_response: [DEADLOCK-DEBUG] << get the peding UNLOCK - qcril_send_unsol_response
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qmi_ril_android_pending_unsol_resp_unlock: [DEADLOCK-DEBUG] qmi_ril_android_pending_unsol_resp_unlock - Before Count : 1
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_process_event: Exit QCRIL_EVT_QMI_VOICE_HANDLE_INDICATIONS, err_no 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][event] qcril_event_main: Waiting...
V/use-Rlog/RLOG-RILC( 296): processWakeupCallback
E/RILJ ( 993): Exception processing unsol response: 11010Exception:java.lang.RuntimeException: Unrecognized unsol response: 11010
D/RILJ ( 993): [UNSL]< UNSOL_RESPONSE_CALL_STATE_CHANGED
D/RILJ ( 993): [0270]> GET_CURRENT_CALLS
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: UI --- RIL_REQUEST_GET_CURRENT_CALLS (9) ---> RIL [RID 0, token id 270, data len 0]
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_create_android_live_params_copy: a-r-id 9, a-data 0, a-data-len 0
I/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] qmi_ril_fw_create_android_live_params_copy: .. params for Android request id 9 require no copy action (usually means empty payload)
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: method 3, datalen 0
W/use-Rlog/RLOG-RILQ( 296): (0/296): RIL[0][main] onRequest: already waiting for exec 0
Have to debug more tomorrow.