On Tue 2014-10-14, Dimstar / Dominique Leuenberger wrote:
>> http://bugzilla.suse.com/show_bug.cgi?id=901122
>> "Need to restart ModemManager after suspend-to-RAM for 3G/UMTS"
> That one is certainly not nice. do you see anything in particular in
> systemctl status ModemManager prior to restarting it?
This does not show anything significant, though it seems the signal
strength update is still working?
ModemManager.service - Modem Manager
Loaded: loaded (/usr/lib/systemd/system/ModemManager.service; enabled)
Active: active (running) since Son 2014-10-19 12:30:33 CEST; 20h ago
Main PID: 5271 (ModemManager)
CGroup: /system.slice/ModemManager.service
└─5271 /usr/sbin/ModemManager
Okt 20 09:13:56 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: 3GPP location updated (MCC: '262'...D:
'0')
Okt 20 09:13:57 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: access technology changed (umts -> hspa)
Okt 20 09:13:57 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: 3GPP location updated (MCC: '0', ...D:
'0')
Okt 20 09:13:57 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: 3GPP Registration state changed (...>
home)
Okt 20 09:13:57 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: state changed (searching -> registered)
Okt 20 09:13:57 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: 3GPP location updated (MCC: '0',
...F5E8A')
Okt 20 09:13:57 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: signal quality updated (40)
Okt 20 09:14:27 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: signal quality updated (40)
Okt 20 09:14:32 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: 3GPP location updated (MCC: '0',
...F5E8E')
Okt 20 09:14:32 tuna.site ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: access technology changed (hspa -> umts)
dmesg, on the other hand may be interesting. Watch out for lines
with "cdc":
[53018.247928] PM: resume of devices complete after 2738.881 msecs
[53018.248241] PM: Finishing wakeup.
[53018.248243] Restarting tasks ... done.
[53018.257593] usb 1-4: USB disconnect, device number 6
[53018.257810] cdc_acm 1-4:1.1: failed to set dtr/rts
[53018.261261] cdc_acm 1-4:1.3: failed to set dtr/rts
[53018.261764] cdc_ncm 1-4:1.6 wwp0s20u4i6: unregister 'cdc_ncm'
usb-0000:00:14.0-4, Mobile Broadband Network Device
[53018.303615] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801b3a46a00
[53018.303619] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801b3a46a48
[53018.303621] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff88019ae5b4c0
[53018.303622] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff88019ae5b508
[53018.303624] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801ecbe14c0
[53018.303626] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801ecbe1508
[53018.303628] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8800c9c2b140
[53018.303630] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801b3a461c0
[53018.303631] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801b3a46208
[53018.303633] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff880211cbcac0
[53018.303635] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8800c9c2aac0
[53018.303636] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8800c9c2bb40
[53018.303638] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff88003739aac0
[53018.303640] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff880210930b40
[53019.027171] usb 1-4: new high-speed USB device number 7 using xhci_hcd
[53019.157294] usb 1-4: New USB device found, idVendor=0bdb, idProduct=1926
[53019.157311] usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[53019.157314] usb 1-4: Product: H5321 gw
[53019.157317] usb 1-4: Manufacturer: Lenovo
[53019.157319] usb 1-4: SerialNumber: AB74AECE5318A2M0
[53019.189976] cdc_acm 1-4:1.1: ttyACM2: USB ACM device
[53019.190668] cdc_acm 1-4:1.3: ttyACM3: USB ACM device
[53019.194115] cdc_wdm 1-4:1.5: cdc-wdm0: USB WDM device
[53019.209553] cdc_ncm 1-4:1.6: MAC-Address: 02:15:e0:ec:01:00
[53019.209950] cdc_ncm 1-4:1.6 wwan0: register 'cdc_ncm' at usb-0000:00:14.0-4,
Mobile Broadband Network Device, 02:15:e0:ec:01:00
[53019.210593] cdc_wdm 1-4:1.8: cdc-wdm1: USB WDM device
[53019.210845] cdc_acm 1-4:1.9: ttyACM4: USB ACM device
[53021.585924] e1000e: enp0s25 NIC Link is Down
[53021.903759] e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
[53022.005098] e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
[53022.005381] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
[53022.020760] iwlwifi 0000:03:00.0: L1 Enabled; Disabling L0S
[53022.027378] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[53022.116213] IPv6: ADDRCONF(NETDEV_UP): wl: link is not ready
[53028.715929] VFS: busy inodes on changed media or resized disk sr0
Is it possible cdc_* is reset, but ModemManager not reconnecting
properly?
When I then systemctl restart ModemManager, I see the following:
2014-10-20T09:20:56.682387+02:00 tuna sudo: ...
COMMAND=/usr/bin/systemctl restart ModemManager
2014-10-20T09:20:56.688264+02:00 tuna ModemManager[5271]: <info> Caught
signal, shutting down...
2014-10-20T09:20:56.841386+02:00 tuna ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: state changed (registered -> disabling)
2014-10-20T09:20:56.841709+02:00 tuna ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: signal quality updated (0)
2014-10-20T09:20:56.841907+02:00 tuna ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: access technology changed (umts ->
unknown)
2014-10-20T09:20:56.960329+02:00 tuna ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: 3GPP Registration state changed (home
-> unknown)
2014-10-20T09:20:56.962966+02:00 tuna ModemManager[5271]: <info> Modem
/org/freedesktop/ModemManager1/Modem/3: state changed (disabling -> disabled)
2014-10-20T09:20:57.150619+02:00 tuna ModemManager[5271]: <info> ModemManager
is shut down
2014-10-20T09:20:57.292214+02:00 tuna ModemManager[26061]: <info> ModemManager
(version 1.0.0) starting...
2014-10-20T09:20:57.302456+02:00 tuna sudo: pam_unix(sudo:session): session
closed for user root
2014-10-20T09:20:58.445400+02:00 tuna org.freedesktop.Tracker1[1428]:
(tracker-store:1735): GLib-CRITICAL **: Source ID 6754 was not found when
attempting to remove it
2014-10-20T09:20:58.445665+02:00 tuna org.freedesktop.Tracker1[1428]:
(tracker-store:1735): GLib-CRITICAL **: Source ID 6757 was not found when
attempting to remove it
2014-10-20T09:20:59.799423+02:00 tuna ModemManager[26061]: <info> Creating
modem with plugin 'Ericsson MBM' and '6' ports
2014-10-20T09:20:59.799739+02:00 tuna ModemManager[26061]: <warn> Could not
grab port (usbmisc/cdc-wdm1): 'Cannot add port 'usbmisc/cdc-wdm1', unsupported'
2014-10-20T09:20:59.799944+02:00 tuna ModemManager[26061]: <warn> Could not
grab port (usbmisc/cdc-wdm0): 'Cannot add port 'usbmisc/cdc-wdm0', unsupported'
2014-10-20T09:20:59.800138+02:00 tuna ModemManager[26061]: <info> Modem for
device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4' successfully created
2014-10-20T09:20:59.800342+02:00 tuna ModemManager[26061]: <warn> Couldn't
find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not
supported by any plugin
2014-10-20T09:20:59.800559+02:00 tuna ModemManager[26061]: <warn> Couldn't
find support for device at '/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0':
not supported by any plugin
2014-10-20T09:20:59.957358+02:00 tuna ModemManager[26061]: <warn> couldn't
load Supported Modes: 'Couldn't retrieve supported modes'
2014-10-20T09:21:01.050152+02:00 tuna ModemManager[26061]: <info> Modem: state
changed (unknown -> disabled)
2014-10-20T09:21:01.057351+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
2014-10-20T09:21:01.090688+02:00 tuna gnome-session[1379]: **
(gnome-shell:1532): CRITICAL **: nma_mobile_providers_database_lookup_cdma_sid:
assertion 'sid > 0' failed
2014-10-20T09:21:08.544383+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
(unknown -> registering)
2014-10-20T09:21:08.544654+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown ->
umts)
2014-10-20T09:21:08.580373+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> hspa)
2014-10-20T09:21:08.623842+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
(registering -> home)
2014-10-20T09:21:08.735018+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: access technology changed (hspa -> umts)
2014-10-20T09:21:08.735270+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC:
'0', Location area code: '3BB', Cell ID: 'EAF5E8E')
2014-10-20T09:21:08.735471+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '262', MNC:
'2', Location area code: '3BB', Cell ID: 'EAF5E8E')
2014-10-20T09:21:08.735613+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> hspa)
2014-10-20T09:21:08.939190+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
2014-10-20T09:21:08.952566+02:00 tuna ModemManager[26061]: <info> Modem
/org/freedesktop/ModemManager1/Modem/0: signal quality updated (40)
2014-10-20T09:21:15.798389+02:00 tuna ModemManager[26061]: <info> Creating
modem with plugin 'Generic' and '1' ports
2014-10-20T09:21:15.798662+02:00 tuna ModemManager[26061]: <warn> Could not
grab port (tty/ttyS4): 'Cannot add port 'tty/ttyS4', unhandled serial type'
2014-10-20T09:21:15.798885+02:00 tuna ModemManager[26061]: <warn> Couldn't
create modem for device at '/sys/devices/pci0000:00/0000:00:16.3': Failed to
find primary AT port
2014-10-20T09:21:22.456719+02:00 tuna org.freedesktop.Tracker1[1428]:
(tracker-store:1735): GLib-CRITICAL **: Source ID 6766 was not found when
attempting to remove it
Does this trigger any idea?
Any then it works again when I choose the connection via the system
panel. (Before it would not even show the three "trying to establish
connection" dots; simply no visual reaction at all.)
Gerald
