Comment 9 for bug 789508

Revision history for this message
Alexander Karlstad (alexander.karlstad) wrote :

Just tested with 3.1.0-030100rc10-generic (RC10)[1] and problem still exists

Everything works well until the modem dies.

Here's from the syslog when it happened

> Oct 25 01:57:57 bobsacamano NetworkManager[2594]: <info> PPP manager(IP Config Get) reply received.
> Oct 25 01:57:57 bobsacamano NetworkManager[2594]: <info> Activation (ttyUSB1) Stage 4 of 5 (IP4 Configure Get) scheduled...
> Oct 25 01:57:57 bobsacamano NetworkManager[2594]: <info> Activation (ttyUSB1) Stage 4 of 5 (IP4 Configure Get) started...
> Oct 25 01:57:57 bobsacamano NetworkManager[2594]: <info> Activation (ttyUSB1) Stage 5 of 5 (IP Configure Commit) started...
> Oct 25 01:57:58 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): device state change: ip-config -> activated (reason 'none') [70 100 0]
> Oct 25 01:57:58 bobsacamano NetworkManager[2594]: <info> Policy set 'Infocare' (ppp0) as default for IPv4 routing and DNS.
> Oct 25 01:57:58 bobsacamano NetworkManager[2594]: <info> Activation (ttyUSB1) successful, device activated.
> Oct 25 01:57:58 bobsacamano NetworkManager[2594]: <info> Activation (ttyUSB1) Stage 5 of 5 (IP Configure Commit) complete.
> Oct 25 01:57:58 bobsacamano NetworkManager[2594]: <info> Activation (ttyUSB1) Stage 4 of 5 (IP4 Configure Get) complete.
> Oct 25 01:57:58 bobsacamano dbus[724]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
> Oct 25 01:57:58 bobsacamano dbus[724]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
> Oct 25 01:58:07 bobsacamano ntpdate[3009]: adjust time server 91.189.94.4 offset -0.005078 sec
> Oct 25 02:09:01 bobsacamano CRON[3134]: (root) CMD ( [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
> Oct 25 02:15:30 bobsacamano pppd[2957]: Modem hangup
> Oct 25 02:15:30 bobsacamano modem-manager[2778]: <info> (ttyUSB1) closing serial port...
> Oct 25 02:15:30 bobsacamano modem-manager[2778]: <info> (ttyUSB1) serial port closed
> Oct 25 02:15:30 bobsacamano pppd[2957]: Connect time 17.6 minutes.
> Oct 25 02:15:30 bobsacamano pppd[2957]: Sent 1112571 bytes, received 2893984 bytes.
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.308036] usb 2-1.6: USB disconnect, device number 5
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.308489] qcserial ttyUSB0: Qualcomm USB modem converter now disconnected from ttyUSB0
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.308536] qcserial 2-1.6:1.1: device disconnected
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.309197] qcserial ttyUSB1: Qualcomm USB modem converter now disconnected from ttyUSB1
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.309220] qcserial 2-1.6:1.2: device disconnected
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.310097] qcserial ttyUSB2: Qualcomm USB modem converter now disconnected from ttyUSB2
> Oct 25 02:15:30 bobsacamano kernel: [ 1636.310163] qcserial 2-1.6:1.3: device disconnected
> Oct 25 02:15:30 bobsacamano pppd[2957]: Connection terminated.
> Oct 25 02:15:30 bobsacamano avahi-daemon[741]: Withdrawing workstation service for ppp0.
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): device state change: activated -> failed (reason 'ppp-disconnect') [100 120 13]
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <warn> Activation (ttyUSB1) failed.
> Oct 25 02:15:31 bobsacamano modem-manager[2778]: <info> (tty/ttyUSB1): released by modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6
> Oct 25 02:15:31 bobsacamano modem-manager[2778]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (connected -> disabled)
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): device state change: failed -> disconnected (reason 'none') [120 30 0]
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): deactivating device (reason 'none') [0]
> Oct 25 02:15:31 bobsacamano dbus[724]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <warn> could not read ppp stats: No such device
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): now unmanaged
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): cleaning up...
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> (ttyUSB1): taking down device.
> Oct 25 02:15:31 bobsacamano dbus[724]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
> Oct 25 02:15:31 bobsacamano NetworkManager[2594]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
> Oct 25 02:15:31 bobsacamano kernel: [ 1636.509685] usb 2-1.6: new high speed USB device number 6 using ehci_hcd
> Oct 25 02:15:31 bobsacamano kernel: [ 1636.603575] usb 2-1.6: config 1 has an invalid interface number: 1 but max is 0
> Oct 25 02:15:31 bobsacamano kernel: [ 1636.603581] usb 2-1.6: config 1 has no interface number 0
> Oct 25 02:15:31 bobsacamano kernel: [ 1636.607827] qcserial 2-1.6:1.1: Qualcomm USB modem converter detected
> Oct 25 02:15:31 bobsacamano kernel: [ 1636.608289] usb 2-1.6: Qualcomm USB modem converter now attached to ttyUSB0
> Oct 25 02:15:31 bobsacamano mtp-probe: checking bus 2, device 6: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6"
> Oct 25 02:15:31 bobsacamano mtp-probe: bus: 2, device: 6 was not an MTP device
> Oct 25 02:15:33 bobsacamano pppd[2957]: Exit.
> Oct 25 02:15:34 bobsacamano kernel: [ 1639.892579] usb 2-1.6: USB disconnect, device number 6
> Oct 25 02:15:34 bobsacamano kernel: [ 1639.892928] qcserial ttyUSB0: Qualcomm USB modem converter now disconnected from ttyUSB0
> Oct 25 02:15:34 bobsacamano kernel: [ 1639.892987] qcserial 2-1.6:1.1: device disconnected
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.625931] usb 2-1.6: new high speed USB device number 7 using ehci_hcd
> Oct 25 02:15:36 bobsacamano mtp-probe: checking bus 2, device 7: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6"
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.724784] qcserial 2-1.6:1.1: Qualcomm USB modem converter detected
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.725180] usb 2-1.6: Qualcomm USB modem converter now attached to ttyUSB0
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.726307] qcserial 2-1.6:1.2: Qualcomm USB modem converter detected
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.726545] usb 2-1.6: Qualcomm USB modem converter now attached to ttyUSB1
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.727473] qcserial 2-1.6:1.3: Qualcomm USB modem converter detected
> Oct 25 02:15:36 bobsacamano kernel: [ 1641.727709] usb 2-1.6: Qualcomm USB modem converter now attached to ttyUSB2
> Oct 25 02:15:36 bobsacamano mtp-probe: bus: 2, device: 7 was not an MTP device
> Oct 25 02:15:36 bobsacamano modem-manager[2778]: <info> (ttyUSB2) opening serial port...
> Oct 25 02:15:36 bobsacamano modem-manager[2778]: <info> (ttyUSB0) opening serial port...
> Oct 25 02:15:36 bobsacamano modem-manager[2778]: <info> (ttyUSB1) opening serial port...

 [1]: http://kernel.ubuntu.com/~kernel-ppa/mainline/v3.1-rc10-oneiric/