relevant log part below shows that on start the ttyUSB0 modem is
properly claimed and added by modemmanager, but NM (probably not started
enough) does not get the notification (or maybe just dismisses it
because its not properly started).
Here the log of
1. boot -> NM +MM start
2. MM detects modem
3 NM finishes startup , but never sees USB0
4. NM restart then claims USB0
Sep 9 22:22:33 BluBUG kernel: [ 13.243535] ip6_tables: (C) 2000-2006
Netfilter Core Team
Sep 9 22:22:51 BluBUG NetworkManager: <info> starting...
Sep 9 22:22:51 BluBUG NetworkManager: <info> Trying to start the
modem-manager...
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: init!
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown:
update_system_hostname
Sep 9 22:22:51 BluBUG NetworkManager: SCPluginIfupdown: management mode:
unmanaged
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0/net/wlan0, iface:
wlan0)
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0/net/wmaster0, iface:
wmaster0)
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/pci0000:00/0000:00:1c.4/0000:02:00.0/net/eth0, iface: eth0)
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/virtual/net/lo, iface: lo)
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: end _init.
Sep 9 22:22:51 BluBUG NetworkManager: Loaded plugin ifupdown: (C) 2008
Canonical Ltd. To report bugs please use the NetworkManager mailing list.
Sep 9 22:22:51 BluBUG NetworkManager: Loaded plugin keyfile: (c) 2007 - 2008
Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Sep 9 22:22:51 BluBUG NetworkManager: <info> Found radio killswitch rfkill1
(at /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0/ieee80211/phy0/rfkill1)
(driver <unknown>)
Sep 9 22:22:51 BluBUG NetworkManager: <info> Wireless now enabled by radio
killswitch
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: (10487072) ...
get_connections.
Sep 9 22:22:51 BluBUG NetworkManager: SCPlugin-Ifupdown: (10487072) ...
get_connections (managed=false): return empty list.
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin ZTE
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Huawei
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Generic
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Nokia
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Ericsson MBM
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Sierra
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Gobi
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Novatel
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Option
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin Option High-Speed
Sep 9 22:22:51 BluBUG modem-manager: Loaded plugin MotoC
Sep 9 22:22:52 BluBUG modem-manager: (ttyUSB0) opening serial device...
Sep 9 22:22:52 BluBUG modem-manager: (ttyUSB0): probe requested by plugin
'Huawei'
Sep 9 22:22:52 BluBUG modem-manager: (Huawei): (ttyUSB1) deferring support
check
Sep 9 22:22:53 BluBUG modem-manager: (ttyUSB0) closing serial device...
Sep 9 22:22:53 BluBUG modem-manager: (Huawei): GSM modem
/sys/devices/pci0000:00/0000:00:1d.0/usb5/5-1 claimed port ttyUSB0
Sep 9 22:22:53 BluBUG modem-manager: Added modem
/sys/devices/pci0000:00/0000:00:1d.0/usb5/5-1
Sep 9 22:22:53 BluBUG modem-manager: Exported modem
/sys/devices/pci0000:00/0000:00:1d.0/usb5/5-1 as
/org/freedesktop/ModemManager/Modems/0
Sep 9 22:22:54 BluBUG NetworkManager: Ifupdown: get unmanaged devices
count: 0
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): driver supports SSID
scans (scan_capa 0x01).
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): new 802.11 WiFi device
(driver: 'iwlagn')
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): exported as
/org/freedesktop/NetworkManager/Devices/0
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): now managed
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): device state change: 1
-> 2 (reason 2)
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): bringing up device.
Sep 9 22:22:54 BluBUG kernel: [ 35.567656] iwlagn 0000:04:00.0: firmware:
requesting iwlwifi-4965-2.ucode
Sep 9 22:22:54 BluBUG kernel: [ 36.072201] iwlagn 0000:04:00.0: loaded
firmware version 228.61.2.24
Sep 9 22:22:54 BluBUG kernel: [ 36.289885] Registered led device:
iwl-phy0::radio
Sep 9 22:22:54 BluBUG kernel: [ 36.289997] Registered led device:
iwl-phy0::assoc
Sep 9 22:22:54 BluBUG kernel: [ 36.290105] Registered led device:
iwl-phy0::RX
Sep 9 22:22:54 BluBUG kernel: [ 36.290210] Registered led device:
iwl-phy0::TX
Sep 9 22:22:54 BluBUG kernel: [ 36.331786] ADDRCONF(NETDEV_UP): wlan0: link
is not ready
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): preparing device.
Sep 9 22:22:54 BluBUG NetworkManager: <info> (wlan0): deactivating device
(reason: 2).
Sep 9 22:22:54 BluBUG NetworkManager: supplicant_interface_acquire: assertion
`mgr_state == NM_SUPPLICANT_MANAGER_STATE_IDLE' failed
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): new Ethernet device
(driver: 'sky2')
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/1
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): now managed
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): device state change: 1
-> 2 (reason 2)
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): bringing up device.
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): preparing device.
Sep 9 22:22:54 BluBUG NetworkManager: <info> (eth0): deactivating device
(reason: 2).
Sep 9 22:22:54 BluBUG NetworkManager: Added default wired connection 'Auto
eth0' for /sys/devices/pci0000:00/0000:00:1c.4/0000:02:00.0/net/eth0
Sep 9 22:22:54 BluBUG kernel: [ 36.345347] sky2 eth0: enabling interface
Sep 9 22:22:54 BluBUG kernel: [ 36.345732] ADDRCONF(NETDEV_UP): eth0: link
is not ready
Sep 9 22:22:55 BluBUG NetworkManager: <info> modem-manager is now available
Sep 9 22:22:55 BluBUG NetworkManager: <WARN> default_adapter_cb(): bluez
error getting default adapter: The name org.bluez was not provided by any
.service files
Sep 9 22:22:55 BluBUG NetworkManager: <info> Trying to start the supplicant...
Sep 9 22:22:55 BluBUG modem-manager: (ttyUSB1): re-checking support...
Sep 9 22:22:55 BluBUG modem-manager: (ttyUSB1) opening serial device...
Sep 9 22:22:55 BluBUG NetworkManager: <info> (wlan0): supplicant manager
state: down -> idle
Sep 9 22:22:55 BluBUG NetworkManager: <info> (wlan0): device state change: 2
-> 3 (reason 0)
Sep 9 22:22:55 BluBUG NetworkManager: <info> (wlan0): supplicant interface
state: starting -> ready
Sep 9 22:23:00 BluBUG modem-manager: (ttyUSB1) closing serial device...
Sep 9 22:23:00 BluBUG modem-manager: (ttyUSB1) opening serial device...
Sep 9 22:23:00 BluBUG modem-manager: (ttyUSB1): probe requested by plugin
'Generic'
Sep 9 22:23:01 BluBUG modem-manager: (ttyUSB1) closing serial device...
Sep 9 22:23:01 BluBUG modem-manager: (Generic): GSM modem
/sys/devices/pci0000:00/0000:00:1d.0/usb5/5-1 claimed port ttyUSB1
Sep 9 22:23:45 BluBUG kernel: [ 86.489360] __ratelimit: 6 callbacks
suppressed
Sep 9 22:23:45 BluBUG kernel: [ 86.489364] gvfs-gdu-volume[3850]: segfault
at 18 ip 00007f9a751069e1 sp 00007fff2cb4a790 error 4 in
libgdu.so.0.0.0[7f9a750fb000+25000]
Sep 9 22:23:50 BluBUG NetworkManager: <WARN> user_list_connections_cb():
Couldn't retrieve connections: Did not receive a reply. Possible causes
include: the remote application did not send a reply, the message bus security
policy blocked the reply, the reply timeout expired, or the network connection
was broken..
Sep 9 22:24:36 BluBUG kernel: [ 137.883275] gdl_indexer[4863]: segfault at 29
ip 00007fdeaba9ce44 sp 00007fdea91ba0a0 error 4 in
ld-2.10.1.so[7fdeaba8a000+1f000]
Sep 9 22:25:09 BluBUG NetworkManager: <WARN> nm_signal_handler(): Caught
signal 15, shutting down normally.
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): taking down device.
Sep 9 22:25:09 BluBUG NetworkManager: <info> (eth0): cleaning up...
Sep 9 22:25:09 BluBUG NetworkManager: <info> (eth0): taking down device.
Sep 9 22:25:09 BluBUG kernel: [ 171.093035] sky2 eth0: disabling interface
Sep 9 22:25:09 BluBUG NetworkManager: <info> exiting (success)
Sep 9 22:25:09 BluBUG NetworkManager: <info> starting...
Sep 9 22:25:09 BluBUG NetworkManager: <info> modem-manager is now available
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: init!
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown:
update_system_hostname
Sep 9 22:25:09 BluBUG NetworkManager: SCPluginIfupdown: management mode:
unmanaged
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0/net/wlan0, iface:
wlan0)
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0/net/wmaster0, iface:
wmaster0)
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/pci0000:00/0000:00:1c.4/0000:02:00.0/net/eth0, iface: eth0)
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: devices added
(path: /sys/devices/virtual/net/lo, iface: lo)
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: end _init.
Sep 9 22:25:09 BluBUG NetworkManager: Loaded plugin ifupdown: (C) 2008
Canonical Ltd. To report bugs please use the NetworkManager mailing list.
Sep 9 22:25:09 BluBUG NetworkManager: Loaded plugin keyfile: (c) 2007 - 2008
Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Sep 9 22:25:09 BluBUG NetworkManager: <info> Found radio killswitch rfkill1
(at /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0/ieee80211/phy0/rfkill1)
(driver <unknown>)
Sep 9 22:25:09 BluBUG NetworkManager: <info> Wireless now enabled by radio
killswitch
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: (30775584) ...
get_connections.
Sep 9 22:25:09 BluBUG NetworkManager: SCPlugin-Ifupdown: (30775584) ...
get_connections (managed=false): return empty list.
Sep 9 22:25:09 BluBUG NetworkManager: Ifupdown: get unmanaged devices
count: 0
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): driver supports SSID
scans (scan_capa 0x01).
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): new 802.11 WiFi device
(driver: 'iwlagn')
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): exported as
/org/freedesktop/NetworkManager/Devices/0
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): now managed
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): device state change: 1
-> 2 (reason 2)
Sep 9 22:25:09 BluBUG NetworkManager: <info> (wlan0): bringing up device.
Sep 9 22:25:10 BluBUG kernel: [ 171.382849] Registered led device:
iwl-phy0::radio
Sep 9 22:25:10 BluBUG kernel: [ 171.383784] Registered led device:
iwl-phy0::assoc
Sep 9 22:25:10 BluBUG kernel: [ 171.384973] Registered led device:
iwl-phy0::RX
Sep 9 22:25:10 BluBUG kernel: [ 171.385163] Registered led device:
iwl-phy0::TX
Sep 9 22:25:10 BluBUG NetworkManager: <info> (wlan0): preparing device.
Sep 9 22:25:10 BluBUG NetworkManager: <info> (wlan0): deactivating device
(reason: 2).
Sep 9 22:25:10 BluBUG kernel: [ 171.419531] ADDRCONF(NETDEV_UP): wlan0: link
is not ready
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): new Ethernet device
(driver: 'sky2')
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/1
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): now managed
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): device state change: 1
-> 2 (reason 2)
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): bringing up device.
Sep 9 22:25:10 BluBUG kernel: [ 171.428651] sky2 eth0: enabling interface
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): preparing device.
Sep 9 22:25:10 BluBUG NetworkManager: <info> (eth0): deactivating device
(reason: 2).
Sep 9 22:25:10 BluBUG NetworkManager: Added default wired connection 'Auto
eth0' for /sys/devices/pci0000:00/0000:00:1c.4/0000:02:00.0/net/eth0
Sep 9 22:25:10 BluBUG kernel: [ 171.429547] ADDRCONF(NETDEV_UP): eth0: link
is not ready
Sep 9 22:25:10 BluBUG NetworkManager: <info> (ttyUSB0): new GSM device
(driver: 'option1')
Sep 9 22:25:10 BluBUG NetworkManager: <info> (ttyUSB0): exported as
/org/freedesktop/NetworkManager/Devices/2
Sep 9 22:25:10 BluBUG NetworkManager: <info> (ttyUSB0): now managed
Sep 9 22:25:10 BluBUG NetworkManager: <info> (ttyUSB0): device state change:
1 -> 2 (reason 2)
Sep 9 22:25:10 BluBUG NetworkManager: <info> (ttyUSB0): deactivating device
(reason: 2).
** Changed in: network-manager (Ubuntu)
Importance: Undecided => High
** Changed in: network-manager (Ubuntu)
Status: New => Triaged
** Changed in: network-manager (Ubuntu)
Assignee: (unassigned) => Alexander Sack (asac)
--
modem 3g not seen on boot
https://bugs.launchpad.net/bugs/427046
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
--
ubuntu-bugs mailing list
ubuntu-bugs@lists.ubuntu.com
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
