jkc120
Android Expert
Well, the bad news is it still happened again so the data roaming didn't fix it (I was surprised if it would, but it did go a while without doing it again).
I caught it on logcat. I think this is the start of the "crash" of the radio (for lack of a better word at the moment):
This has pretty much convinced me it's an LTE radio hardware problem, but I am going to let my logcat run over night and see if I catch any more of these. If I do, I'll try CDMA only to see if it's just the LTE radio or some sort of handoff thing.
I caught it on logcat. I think this is the start of the "crash" of the radio (for lack of a better word at the moment):
I then looked at the dmesg and found roughly the same corresponding timestamp there, and bingo:01-12 23:17:13.727 D/RIL[C] ( 114): OnFMTDataReceived STATE_CRASH_EXIT on Modem #1
01-12 23:17:14.070 D/RILJ ( 376): [0367]> SCREEN_STATE: false
01-12 23:17:14.070 D/RILJ ( 376): [0367]< SCREEN_STATE error: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-12 23:17:14.086 D/GSM ( 376): [GsmDCT] onReceive: action=android.intent.action.SCREEN_OFF
01-12 23:17:14.086 D/GSM ( 376): [GsmDCT] stopNetStatPoll
01-12 23:17:14.086 D/GSM ( 376): [GsmDCT] overall state is CONNECTED
01-12 23:17:14.086 D/GSM ( 376): [GsmDCT] startNetStatPoll
01-12 23:17:14.125 W/ActivityManager( 182): finishReceiver called but no pending broadcasts
01-12 23:17:14.211 E/NotificationService( 182): Ignoring notification with icon==0: Notification(contentView=null vibrate=null,sound=null,defaults=0x0,flags=0x62)
01-12 23:17:14.313 E/NotificationService( 182): Ignoring notification with icon==0: Notification(contentView=null vibrate=null,sound=null,defaults=0x0,flags=0x1)
01-12 23:17:14.727 D/RIL[C] ( 114): __silent_reset [***** SILENT RESET START *****]
01-12 23:17:14.727 D/RIL[C] ( 114): ReaderLoop Terminated!
01-12 23:17:14.727 D/RIL[C] ( 114): StopRXReader: trans_fd(19) closed
01-12 23:17:14.727 D/RIL[C] ( 114): StopRXReader: trans_fd(20) closed
01-12 23:17:14.727 D/RIL[C] ( 114): StopRXReader: rfs_fd(21) closed
01-12 23:17:14.727 D/RIL[C] ( 114): __silent_reset StopRXReader() OK!
01-12 23:17:14.727 D/RIL[C] ( 114): InformNewEvent g_bexit_thread has been signaled!
01-12 23:17:14.727 D/RIL[C] ( 114): WaitForEvent g_bexit_thread is 0
01-12 23:17:14.727 D/RIL[C] ( 114): RilProcessorLoop Finished!
01-12 23:17:14.727 D/RIL[C] ( 114): RilProcessorLoop Terminated!
01-12 23:17:14.727 D/RIL[C] ( 114): __silent_reset StopRilProcessor() OK!
01-12 23:17:14.727 D/RIL[C] ( 114): CancelAllRequests [EVT]:Req(0), RX(0)
01-12 23:17:14.727 D/RIL[C] ( 114): CancelAllRequests [EVT]:Req(0), RX(0)
01-12 23:17:14.727 D/RIL[C] ( 114): __silent_reset CancelAllRequests() OK!
01-12 23:17:14.727 D/RIL[C] ( 114): __silent_reset CancelAllReceived() OK!
01-12 23:17:14.727 D/RIL[C] ( 114): [dl_boot_lte] dl_boot_lte!
01-12 23:17:14.727 D/RIL[C] ( 114): [dl_boot_lte] OPEN USB_BOOT_DEV Succeeded!
01-12 23:17:14.727 D/RIL[C] ( 114): IOCTL_MODEM_RESET
01-12 23:17:15.195 E/NotificationService( 182): Ignoring notification with icon==0: Notification(contentView=null vibrate=null,sound=null,defaults=0x0,flags=0x1)
01-12 23:17:15.219 E/NotificationService( 182): Ignoring notification with icon==0: Notification(contentView=null vibrate=null,sound=null,defaults=0x0,flags=0x1)
01-12 23:17:15.227 E/NotificationService( 182): Ignoring notification with icon==0: Notification(contentView=null vibrate=null,sound=null,defaults=0x0,flags=0x1)
01-12 23:17:16.586 D/RIL[C] ( 114): set_sysfs(): set sysfs [/sys/bus/usb/devices/usb1/power/control] IN : on
01-12 23:17:16.649 E/NotificationService( 182): Ignoring notification with icon==0: Notification(contentView=null vibrate=null,sound=null,defaults=0x0,flags=0x1)
01-12 23:17:16.742 D/RIL[C] ( 114): set_sysfs(): set sysfs [/sys/bus/usb/devices/usb1/power/control] OUT : on
01-12 23:17:16.742 D/RIL[C] ( 114): Open LTE SPI: 17
01-12 23:17:16.742 D/RIL[C] ( 114): Open imgdev: 18
01-12 23:17:16.742 D/RIL[C] ( 114): Open imgdev: 18
01-12 23:17:16.742 D/RIL[C] ( 114): Load Boot.
01-12 23:17:16.742 D/RIL[C] ( 114): Actual Size of img Header is : 2428
It looks to me like some sort of hardware/watchdog timeout on the LTE radio device. I.e., I think it's a hardware problem. Or could a bad SIM card theoretically trigger something like this? I would have expected some sort of Icc or SIM message from the kernel (in dmesg) if that were the case, though.<3>[ 8936.320098] host wakeup timeout ! retry..
<3>[ 8936.819183] host wakeup timeout ! retry..
<3>[ 8937.320098] host wakeup timeout ! retry..
<3>[ 8937.820068] host wakeup timeout !!
<3>[ 8937.820281] usb_tx_work usb_tx_urb_with_skb for iod(0), ret(-1)
<6>[ 8937.929779] usb 1-1: USB disconnect, device number 4
<6>[ 8937.930358] force disconnect by modem not responding!!
<6>[ 8938.054260] usb 1-1: new high speed USB device number 5 using ehci-omap
<3>[ 8952.945037] USB disconnected and not enumerated for long time
<6>[ 8952.945343] [MODEM_IF] lte_ipc0 state changed: CRASH_EXIT
<3>[ 8953.179412] usb 1-1: device descriptor read/64, error -110
<3>[ 8955.950439] [MODEM_IF] no data from sk_rx_q, modem_state : CRASH_EXIT(lte_rfs0)
<6>[ 8955.950531] [MODEM_IF] cbp71_off()
<6>[ 8955.950561] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[ 8955.951171] [MODEM_IF] misc_release : cdma_ipc0
<6>[ 8955.951751] [MODEM_IF] misc_release : lte_ipc0
<6>[ 8955.952209] [MODEM_IF] misc_release : lte_rfs0
<6>[ 8955.968536] [MODEM_IF] misc_open : lte_boot0
<6>[ 8955.970245] [MODEM_IF] cmc221_reset()
<6>[ 8955.970336] [MODEM_IF] cmc221_off()
<6>[ 8955.987457] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[ 8955.987670] phone_active_irq_handler : phone_state=0
<6>[ 8956.015960] [MODEM_IF] cdma_ipc0 state changed: OFFLINE
<6>[ 8956.016143] phone_active_irq_handler : phone_state=0
<3>[ 8956.147338] hub 1-0:1.0: unable to enumerate USB device on port 1
<3>[ 8956.233306] [MODEM_IF], phone_status changed to invalid!!
<6>[ 8956.295715] [MODEM_IF] cmc221_on()
<6>[ 8957.828033] [MODEM_IF] misc_release : lte_boot0
<6>[ 8957.988403] [MODEM_IF] misc_open : lte_boot0
<6>[ 8957.990173] lte_modem_spi spi4.0: IOCTL_LTE_MODEM_XMIT_BOOT - bin size: 2428
<6>[ 8958.109130] usb 1-1: new high speed USB device number 6 using ehci-omap
<6>[ 8958.120574] lte_modem_spi spi4.0: IOCTL_LTE_MODEM_XMIT_BOOT - bin size: 64288
<3>[ 8958.186340] hub 1-0:1.0: unable to enumerate USB device on port 1
<3>[ 8958.667572] lte_modem_spi spi4.0: spi_xmit - error -22
This has pretty much convinced me it's an LTE radio hardware problem, but I am going to let my logcat run over night and see if I catch any more of these. If I do, I'll try CDMA only to see if it's just the LTE radio or some sort of handoff thing.


