Xperia X: Fatal error on the modem

REPRODUCIBILITY: daily
OS VERSION: 4.4.0.68
HARDWARE: Xperia X
UI LANGUAGE: german
REGRESSION:

DESCRIPTION:

After working properly for years, since a few days the modem crashes nearly daily. If this happens mobile network connection (voice, data, SMS) is gone.

The phone still shows e.g. “4G” in the upper right corner, but there’s no notification on incoming calls. When trying an to do an outoing call, phone shows “Network error”. SMS also do not work.

Enabling and disabling Flight Mode does not solve the issue, but only removes the “4G” icon on the upper right corner. Restarting the network also does not help.

Phone needs to be turned off and on again to get the modem working again.

See below for dmesg output. I haven’t installed any updates or software during the last few weeks, so it’s unlikely related to that.

PRECONDITIONS:

STEPS TO REPRODUCE:

Wait.

EXPECTED RESULT:

Modem does not crash, or at least recovers automatically.

ACTUAL RESULT:

Mobile network connection (voice, data, SMS) is gone.

MODIFICATIONS:

Xperia X image on a Xperia X compact (GitHub - g7/sailfishx-patcher-f5321: Patcher script that applies the f5321 (Xperia X Compact) compatibility layer on top of official Sailfish X f5121 images)

ADDITIONAL INFORMATION:

dmesg output:

[Sep26 15:50] clearpad clearpad: (clearpad_set_irq:969) irq was disabled
[  +0,063722] mdss_dsi_panel_off: ctrl=ffffffc0ad4c1018 ndx=0
[  +0,135991] @@@@ panel power off @@@@
[Sep26 15:52] healthd: battery l=83 v=4045 t=29.2 h=2 st=3 c=196 chg=
[Sep26 15:57] healthd: battery l=82 v=4038 t=30.7 h=2 st=3 c=246 chg=
[Sep26 16:01] Fatal error on the modem.
[  +0,000067] modem subsystem failure reason: lte_ml1_dlm_ca.c:2755:Assert lte_ml1_dlm_ca_check_any_pend_mask_for_scc(instan.
[  +0,000042] subsys-restart: subsystem_restart_dev(): Restart sequence requested for modem, restart_level = RELATED.
[  +0,002165] IPA received MPSS BEFORE_SHUTDOWN
[  +0,008020] rmnet_ipa started deinitialization
[  +0,005602] ipa-wan __ipa_wwan_close:961 [rmnet_ipa0]: ipa_deregister_intf failed -22
[  +0,003332] [RMNET:HI] rmnet_config_notify_cb(): Kernel is trying to unregister rmnet_ipa0
[  +0,081737] ipa-wan ipa_del_dflt_wan_rt_tables:373 Deleting Route hdl:(0xb) with ip type: 0
[  +0,006637] ipa-wan ipa_del_dflt_wan_rt_tables:382 Deleting Route hdl:(0xd) with ip type: 1
[  +0,069709] telepathy-gabbl[4985]: unhandled level 3 translation fault (11) at 0x0000000c, esr 0x92000007
[  +0,000009] pgd = ffffffc063df9000
[  +0,000019] [0000000c] *pgd=0000000083ec4003, *pmd=0000000083ec1003, *pte=0000000000000000

[  +0,000030] CPU: 1 PID: 4985 Comm: telepathy-gabbl Tainted: G        W    3.10.84 #1
[  +0,000006] task: ffffffc099298000 ti: ffffffc0649e0000 task.ti: ffffffc0649e0000
[  +0,000004] pc : [<00000000f73d269e>] lr : [<00000000f73aa1d7>] pstate: 80070030
[  +0,000004] sp : 00000000fff36f6c
[  +0,000004] x12: 00000000f73d2699 
[  +0,000003] x11: 0000000000000000 x10: 00000000001642b0 
[  +0,000006] x9 : 000000000015caa8 x8 : 0000000000000000 
[  +0,000005] x7 : 00000000001860d0 x6 : 0000000000000000 
[  +0,000005] x5 : 00000000001860d0 x4 : 0000000000186058 
[  +0,000005] x3 : 00000000001860d0 x2 : 00000000f73aa22d 
[  +0,000005] x1 : 0000000000000000 x0 : 0000000000000000 

[  +0,140561] rmnet_ipa completed deinitialization
[  +0,000129] IPA BEFORE_SHUTDOWN handling is complete
[  +0,000009] subsys-restart: subsystem_shutdown(): [ffffffc06d913d40]: Shutting down modem
[  +0,100092] pil-q6v5-mss 4080000.qcom,mss: Port ffffff8002388000 halt timeout
[  +0,003164] IPA received MPSS AFTER_SHUTDOWN
[  +0,000087] smd_channel_probe_now: allocation table not initialized
[  +0,000462] smd_pkt_read notifying reset for smd_pkt_dev id:0
[  +0,024426] ipa ipa_uc_reset_pipe:700 Invalid IPA client
[  +0,000150] IPA AFTER_SHUTDOWN handling is complete
[  +0,083024] M-Notify: General: 4
[  +0,000476] IPA received MPSS BEFORE_POWERUP
[  +0,005403] IPA BEFORE_POWERUP handling is complete
[  +0,000013] subsys-restart: subsystem_powerup(): [ffffffc06d913d40]: Powering up modem
[  +0,028892] pil-q6v5-mss 4080000.qcom,mss: modem: loading from 0x0000000086c00000 to 0x000000008c200000
[  +0,001462] M-Notify: General: 6
[  +0,028973] pil-q6v5-mss 4080000.qcom,mss: MBA: loading from 0x00000000d4e00000 to 0x00000000d4f00000
[  +0,016648] firmware msadp: firmware_loading_store: unexpected value (0)
[  +0,000547] pil-q6v5-mss 4080000.qcom,mss: MBA: Debug policy not present - msadp
[  +0,126738] pil-q6v5-mss 4080000.qcom,mss: MBA boot done
[  +0,778292] pil-q6v5-mss 4080000.qcom,mss: modem: Brought out of reset
[  +0,104023] pil-q6v5-mss 4080000.qcom,mss: Subsystem error monitoring/handling services are up
[  +0,000089] pil-q6v5-mss 4080000.qcom,mss: modem: Power/Clock ready interrupt received
[  +0,002102] M-Notify: General: 7
[  +0,000036] memshare: Modem Restart has happened
[  +0,000013] IPA received MPSS AFTER_POWERUP
[  +0,000527] rmnet_ipa started initialization
[  +0,000007] IPA SSR support = True
[  +0,000004] IPA ipa-loaduC = True
[  +0,034591] rmnet_ipa completed initialization
[  +0,000707] platform 184c030.qcom,gdsc: Driver gdsc requests probe deferral
[  +0,000201] IPA AFTER_POWERUP handling is complete
[  +0,000010] subsys-restart: subsystem_restart_wq_func(): [ffffffc06d913d40]: Restart sequence for modem completed.
[  +0,062946] sysmon-qmi: sysmon_clnt_svc_arrive: Connection established between QMI handle and modem's SSCTL service
[  +0,117294] ipa-wan handle_indication_req:133 not send indication
[  +0,000121] Sending QMI_IPA_INIT_MODEM_DRIVER_REQ_V01
[  +0,006149] QMI_IPA_INIT_MODEM_DRIVER_REQ_V01 response received
[  +0,000058] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,006132] ipa-wan ipa_check_qmi_response:382 Got bad response 49 from request id 1 (error 48)
[  +0,001871] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,003332] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +3,693886] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004585] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,018168] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,003938] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,016915] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004402] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,017125] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004900] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,018661] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,008226] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,014150] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005743] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,016468] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,002143] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,014222] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005129] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,019815] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,002133] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,017078] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004579] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,019616] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005582] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,017682] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005910] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,017089] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004045] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,019715] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,008465] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,017595] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,003899] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,020131] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004952] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,016338] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004837] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,020421] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005438] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,018484] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005676] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,014466] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,004873] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,019645] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005101] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,006546] ipa-wan ipa_wwan_ioctl:1491 get AGG size 8192 count 10
[  +0,001378] ipa ipa_sps_irq_control_all:869 EP (5) not allocated.
[  +0,005041] ipa ipa_assign_policy:2783 get close-by 8192
[  +0,000042] ipa ipa_assign_policy:2789 set rx_buff_sz 7808
[  +0,000024] ipa ipa_assign_policy:2811 set aggr_limit 6
[  +0,009073] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data0) register to IPA
[  +0,183422] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data1) register to IPA
[  +0,144980] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data2) register to IPA
[  +0,180134] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data3) register to IPA
[  +0,172580] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data4) register to IPA
[  +0,177973] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data5) register to IPA
[  +0,157353] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data6) register to IPA
[  +0,161797] ipa-wan ipa_wwan_ioctl:1399 dev(rmnet_data7) register to IPA
[Sep26 16:02] @@@@ panel power on @@@@
[  +0,183712] mdss_dsi_panel_on: ctrl=ffffffc0ad4c1018 ndx=0
[  +0,638707] clearpad clearpad: (clearpad_initialize:2236) initialize device
[  +0,015274] clearpad clearpad: (clearpad_initialize:2251) device status 0x81
[  +0,008708] clearpad clearpad: (clearpad_initialize:2259) bootloader revision 7.003
[  +0,015649] clearpad clearpad: (clearpad_initialize:2281) FW status 0x40
[  +0,000016] clearpad clearpad: (clearpad_update_chip_id:5713) chip_id=0x3a
[  +0,000014] clearpad clearpad: (clearpad_touch_config_dt_for_chip_id:5538) read settings for S3330
[  +0,087356] clearpad clearpad: (clearpad_prepare_f12_2d:2209) x_max=719, y_max=1279, n_fingers=10, n_bytes_per_object=8
[  +0,056374] clearpad clearpad: (clearpad_initialize:2349) result: S3330, family 0xa0, fw rev 0x05.03, extra 0x06, (no fw update)
[  +0,000016] clearpad clearpad: (clearpad_process_F01_RMI:4032) device reset
[  +0,020409] clearpad clearpad: (clearpad_set_resume_mode:3254) ignore interrupt 0x04
[  +0,000087] clearpad clearpad: (clearpad_set_irq:966) irq was enabled
[  +0,000005] clearpad clearpad: (clearpad_set_resume_mode:3259) set resume mode (rc=0)
[  +0,010040] clearpad clearpad: (clearpad_process_irq:4152) no work, interrupt=[0x00]
[ +31,908968] healthd: battery l=82 v=4015 t=31.0 h=2 st=3 c=454 chg=
[Sep26 16:03] msm_vidc: info: Opening video instance: 0000000000000000, 0
[  +0,000937] subsys-restart: __subsystem_get(): Changing subsys fw_name to venus-v1
[  +0,046912] subsys-pil-tz 1de0000.qcom,venus: venus: loading from 0x000000008f800000 to 0x000000008fd00000
[  +0,140225] subsys-pil-tz 1de0000.qcom,venus: venus: Brought out of reset
[  +0,023574] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,001237] msm_vidc: info: Opening video instance: 0000000000000000, 0
[  +0,006885] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,001838] msm_vidc: info: Opening video instance: 0000000000000000, 0
[  +0,005421] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,001750] msm_vidc: info: Opening video instance: 0000000000000000, 0
[  +0,008098] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,008122] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,015270] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,002057] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,017713] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,004166] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,011984] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,001098] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,007979] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,001087] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,010045] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,006885] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,010293] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,001431] msm_vidc: info: Opening video instance: 0000000000000000, 1
[  +0,021347] msm_vidc: info: Closed video instance: 0000000000000000
[  +0,094661] msm_csid_init: CSID_VERSION = 0x30060000
[Sep26 16:01] IPC_RTR: ipc_router_create_log_ctx: Unable to create IPC logging for [modem_IPCRTR]
[Sep26 16:03]  msm_csid_init: Failed in getting TOP gdscr regulator handle
[  +0,003108] MSM-CPP cpp_init_hardware:1045 CPP HW Version: 0x40030000
[  +0,000019] MSM-CPP cpp_init_hardware:1066 stream_cnt:0
[  +0,004351] MSM-CPP cpp_load_fw:1191 MC Bootloader Version: 0x312e3176
[  +0,000819] MSM-CPP cpp_load_fw:1204 CPP FW Version: 0x10050010
[  +0,045601]  msm_cci_init: Failed in getting TOP gdscr regulator handle
[  +0,000626] msm_cci_init:1321: hw_version = 0x10030000
[  +0,037868] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,082319] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +2,502597] msm_csid_init: CSID_VERSION = 0x30060000
[  +0,008335] MSM-CPP cpp_init_hardware:1045 CPP HW Version: 0x40030000
[  +0,000022] MSM-CPP cpp_init_hardware:1066 stream_cnt:0
[  +0,004181] MSM-CPP cpp_load_fw:1191 MC Bootloader Version: 0x312e3176
[  +0,000829] MSM-CPP cpp_load_fw:1204 CPP FW Version: 0x10050010
[  -0,011636]  msm_csid_init: Failed in getting TOP gdscr regulator handle
[Sep26 16:04]  msm_cci_init: Failed in getting TOP gdscr regulator handle
[  +0,000683] msm_cci_init:1321: hw_version = 0x10030000
[  +0,037896] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,081982] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,139170] msm_csid_init: CSID_VERSION = 0x30060000
[  +0,011206] MSM-CPP cpp_init_hardware:1045 CPP HW Version: 0x40030000
[  +0,000028] MSM-CPP cpp_init_hardware:1066 stream_cnt:0
[  +0,014596] MSM-CPP cpp_load_fw:1191 MC Bootloader Version: 0x312e3176
[  +0,003758] MSM-CPP cpp_load_fw:1204 CPP FW Version: 0x10050010
[  -0,028673]  msm_csid_init: Failed in getting TOP gdscr regulator handle
[  +0,069421]  msm_cci_init: Failed in getting TOP gdscr regulator handle
[  +0,000651] msm_cci_init:1321: hw_version = 0x10030000
[  +0,040974] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,060391] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,381862] msm_csid_init: CSID_VERSION = 0x30060000
[  +0,013576] MSM-CPP cpp_init_hardware:1045 CPP HW Version: 0x40030000
[  +0,000020] MSM-CPP cpp_init_hardware:1066 stream_cnt:0
[  +0,005294] MSM-CPP cpp_load_fw:1191 MC Bootloader Version: 0x312e3176
[  +0,001529] MSM-CPP cpp_load_fw:1204 CPP FW Version: 0x10050010
[  -0,019035]  msm_csid_init: Failed in getting TOP gdscr regulator handle
[  +0,064790]  msm_cci_init: Failed in getting TOP gdscr regulator handle
[  +0,000652] msm_cci_init:1321: hw_version = 0x10030000
[  +0,039299] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,600438] ispif_process_irq: PIX0 frame id: 0
[  +0,000032] ispif_process_irq: PIX0 frame id: 0
[  +0,000248] msm_isp_notify: PIX0 frame id: 1
[  +0,000027] msm_isp_notify: PIX0 frame id: 1
[  +0,038379] ispif_process_irq: PIX0 frame id: 1
[  +0,000026] ispif_process_irq: PIX0 frame id: 1
[  +0,000275] msm_isp_notify: PIX0 frame id: 2
[  +0,000091] msm_isp_notify: PIX0 frame id: 2
[  +0,038341] ispif_process_irq: PIX0 frame id: 2
[  +0,000252] msm_isp_notify: PIX0 frame id: 3
[  +0,000025] msm_isp_notify: PIX0 frame id: 3
[  +0,038675] msm_isp_notify: PIX0 frame id: 4
[  +0,000047] msm_isp_notify: PIX0 frame id: 4
[  +0,000401] MSM-CPP msm_enqueue:262 queue frame new max is 1
[  +0,001477] MSM-CPP msm_enqueue:262 queue eventdata new max is 1
[  +0,036785] msm_isp_notify: PIX0 frame id: 5
[  +0,000036] msm_isp_notify: PIX0 frame id: 5
[  +0,304801] MSM-CPP msm_cpp_subdev_ioctl:2833 stream_cnt:0
[  +0,011187] SMBCHG: smbchg_battery_set_property: prop 86, val = 0
[  +0,069375] SMBCHG: smbchg_battery_set_property: prop 86, val = 0

Remembered I swapped the micro SD card shortly before those daily modem resets started.
Replaced the new micro SD card with the old one, no more modem failures since a few days.

The working card is a no name card with 128 GByte.
The card that seems to have caused the issue is a Samsung EVO Plus with 512 GByte. It works flawless in another device.

Will reopen this report if those modem hangs happens again.