Modem offline on boot

Bug #1333316 reported by Pat McGowan
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
urfkill (Ubuntu)
Confirmed
Undecided
Pat McGowan

Bug Description

Mako running build 81
Booted the phone
Noticed that the modem was not online per indicator and list-modems
online-modem brought it up
The phone had been shutdown due to a low battery condition

Syslog contains:
phablet@ubuntu-phablet:~$ grep -i urfkill /var/log/syslog
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: No configured profile found
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: <warning> No dmi devices found.
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: <warning> Failed to get DMI information
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Starting urfkilld version 0.6.0
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: set_flight_mode: 0:
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting WLAN devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting BLUETOOTH devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting UWB devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting WIMAX devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting WWAN devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting GPS devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting FM devices to unblocked
Jun 23 09:35:22 ubuntu-phablet URfkill[952]: Setting NFC devices to unblocked
Jun 23 09:35:32 ubuntu-phablet NetworkManager[1280]: <info> urfkill appeared on the bus
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: No configured profile found
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: <warning> No dmi devices found.
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: <warning> Failed to get DMI information
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Starting urfkilld version 0.6.0
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: set_flight_mode: 0:
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting WLAN devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting BLUETOOTH devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting UWB devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting WIMAX devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting WWAN devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting GPS devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting FM devices to unblocked
Jun 23 09:54:24 ubuntu-phablet URfkill[960]: Setting NFC devices to unblocked
Jun 23 09:54:26 ubuntu-phablet URfkill[960]: Modem found: '/ril_0'
Jun 23 09:54:26 ubuntu-phablet URfkill[960]: killswitch state: KILLSWITCH_STATE_NO_ADAPTER new_state: KILLSWITCH_STATE_UNBLOCKED
Jun 23 09:54:26 ubuntu-phablet URfkill[960]: Setting device 100 (WWAN) to unblocked
Jun 23 09:54:28 ubuntu-phablet NetworkManager[1297]: <info> urfkill appeared on the bus
Jun 23 09:54:29 ubuntu-phablet URfkill[960]: adding killswitch idx 0 soft 0 hard 0
Jun 23 09:54:29 ubuntu-phablet URfkill[960]: killswitch state: KILLSWITCH_STATE_NO_ADAPTER new_state: KILLSWITCH_STATE_UNBLOCKED
Jun 23 09:54:29 ubuntu-phablet URfkill[960]: Setting device 0 (WLAN) to unblocked
Jun 23 09:54:29 ubuntu-phablet URfkill[960]: Setting WLAN to unblocked
Jun 23 09:54:34 ubuntu-phablet URfkill[960]: adding killswitch idx 1 soft 0 hard 0
Jun 23 09:54:34 ubuntu-phablet URfkill[960]: killswitch state: KILLSWITCH_STATE_NO_ADAPTER new_state: KILLSWITCH_STATE_UNBLOCKED
Jun 23 09:54:34 ubuntu-phablet URfkill[960]: Setting device 1 (BLUETOOTH) to unblocked
Jun 23 09:54:34 ubuntu-phablet URfkill[960]: Setting BLUETOOTH to unblocked
Jun 23 09:55:22 ubuntu-phablet URfkill[960]: <warning> Could not set Online property in oFono: GDBus.Error:org.ofono.Error.Failed: Operation failed

Revision history for this message
Pat McGowan (pat-mcgowan) wrote :

After a normal shutdown and boot the modem was online

description: updated
Revision history for this message
Tiago Salem Herrmann (tiagosh) wrote :

I have seen the same issue with the ofono simulator (ofono-phonesim-autostart package)
Not 100% reproducible though.

Revision history for this message
Tony Espy (awe) wrote :

@Pat

In this case, it looks like urfkill was not able to Online the modem ( see the last log message output ). The current version of urfkill lacks any kind of retry logic for the modem.

If you still have the syslog, it would be interesting to see the output from ofono for the same time-frame. Why did the modem fail to come online?

Also can you confirm the device used?

Finally, can you reproduce this bug with a more recent image?

Changed in urfkill (Ubuntu):
status: New → Confirmed
status: Confirmed → Incomplete
assignee: nobody → Pat McGowan (pat-mcgowan)
Revision history for this message
Pat McGowan (pat-mcgowan) wrote :

I have not seen this again, this was on Mako

the syslog from that time:

Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: [UNSOL]< UNSOL_RIL_CONNECTED
Jun 23 09:55:22 ubuntu-phablet NetworkManager[1297]: <info> ofono: found new SimManager interface
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: ril_file_info_cb: Reply failure: GENERIC_FAILURE
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: ril_set_online_cb: set_online: 1 failed
Jun 23 09:55:22 ubuntu-phablet URfkill[960]: <warning> Could not set Online property in oFono: GDBus.Error:org.ofono.Error.Failed: Operation failed
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: ril_file_info_cb: Reply failure: GENERIC_FAILURE
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: message repeated 2 times: [ ril_file_info_cb: Reply failure: GENERIC_FAILURE]
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: Reply failure: GENERIC_FAILURE
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: Unable to read IMSI, emergency calls only
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: Size of SIM_STATUS reply too small: 0 bytes
Jun 23 09:55:24 ubuntu-phablet ofonod[1184]: ril_file_info_cb: Reply failure: GENERIC_FAILURE
pat@pat-samsung:~/bug/modem$ grep -i 09:55:2 syslog.5
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: [UNSOL]< UNSOL_RIL_CONNECTED
Jun 23 09:55:22 ubuntu-phablet NetworkManager[1297]: <info> (/ril_0) updated available interfaces
Jun 23 09:55:22 ubuntu-phablet NetworkManager[1297]: <info> ofono: found new SimManager interface
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: ril_file_info_cb: Reply failure: GENERIC_FAILURE
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: ril_set_online_cb: set_online: 1 failed
Jun 23 09:55:22 ubuntu-phablet URfkill[960]: <warning> Could not set Online property in oFono: GDBus.Error:org.ofono.Error.Failed: Operation failed
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: ril_file_info_cb: Reply failure: GENERIC_FAILURE
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: message repeated 2 times: [ ril_file_info_cb: Reply failure: GENERIC_FAILURE]
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: Reply failure: GENERIC_FAILURE
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: Unable to read IMSI, emergency calls only
Jun 23 09:55:22 ubuntu-phablet ofonod[1184]: Size of SIM_STATUS reply too small: 0 bytes
Jun 23 09:55:23 ubuntu-phablet kernel: [ 52.020814] healthd: battery l=17 v=3927 t=34.9 h=2 st=2 c=-796 chg=a
Jun 23 09:55:24 ubuntu-phablet ofonod[1184]: ril_file_info_cb: Reply failure: GENERIC_FAILURE

Revision history for this message
Ken VanDine (ken-vandine) wrote :
Download full text (114.5 KiB)

I just started hitting this again, as of image 274 (urfkill changed), on every boot the modem starts offline. Running online-modem fixes it though.

Here's part of the syslog, note when it comes online is when i ran the online-modem script.

Oct 9 11:00:01 ubuntu-phablet powerd[965]: Initializing powerd.
Oct 9 11:00:01 ubuntu-phablet powerd[965]: Running on mako
Oct 9 11:00:01 ubuntu-phablet powerd[965]: libsuspend: detect module: earlysuspend.
Oct 9 11:00:01 ubuntu-phablet kernel: [ 10.256432] adsp_boot_store:going to call adsp_loader_do
Oct 9 11:00:01 ubuntu-phablet kernel: [ 10.388402] request_suspend_state: wakeup (3->0) at 10346340547 (2014-10-09 15:00:01.556961315 UTC)
Oct 9 11:00:01 ubuntu-phablet kernel: [ 10.428872] systemd-udevd[993]: starting version 208
Oct 9 11:00:01 ubuntu-phablet URfkill[999]: No configured profile found
Oct 9 11:00:01 ubuntu-phablet URfkill[999]: <warning> No dmi devices found.
Oct 9 11:00:01 ubuntu-phablet URfkill[999]: <warning> Failed to get DMI information
Oct 9 11:00:01 ubuntu-phablet URfkill[999]: Starting urfkilld version 0.6.0
Oct 9 11:00:01 ubuntu-phablet dbus[807]: [system] Activating service name='org.freedesktop.PolicyKit1' (using servicehelper)
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.892476] type=1400 audit(1412866802.057:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/lightdm/lightdm-guest-session" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.892507] type=1400 audit(1412866802.057:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="chromium" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.913322] type=1400 audit(1412866802.077:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.913352] type=1400 audit(1412866802.077:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-mounting" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.913383] type=1400 audit(1412866802.077:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-nesting" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.923180] type=1400 audit(1412866802.087:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/sbin/dhclient" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.923210] type=1400 audit(1412866802.087:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.923241] type=1400 audit(1412866802.087:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=1073 comm="apparmor_parser"
Oct 9 11:00:02 ubuntu-phablet kernel: [ 10.944452] type=1400 audit(1412866802.107:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/lxc-start" pid=1073 comm=...

Changed in urfkill (Ubuntu):
status: Incomplete → Confirmed
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.