This is how it works on my main machine (not flawless because disconnect does 
not work):
---------------------
Jun 28 12:20:55 wb kernel: [13850.192111] usb 2-3: new high speed USB device 
using ehci_hcd and address 6
Jun 28 12:20:55 wb kernel: [13850.336128] usb 2-3: configuration #1 chosen from 
1 choice
Jun 28 12:20:55 wb kernel: [13850.338593] usb-storage: probe of 2-3:1.0 failed 
with error -5
Jun 28 12:20:55 wb kernel: [13850.338969] usb-storage: probe of 2-3:1.1 failed 
with error -5
Jun 28 12:20:55 wb kernel: [13850.340580] usb-storage: probe of 2-3:1.2 failed 
with error -1
Jun 28 12:20:55 wb kernel: [13850.342759] usb-storage: probe of 2-3:1.3 failed 
with error -1
Jun 28 12:20:55 wb kernel: [13850.662531] usbcore: registered new interface 
driver usbserial
Jun 28 12:20:55 wb kernel: [13850.662559] USB Serial support registered for 
generic
Jun 28 12:20:55 wb kernel: [13850.662678] usbcore: registered new interface 
driver usbserial_generic
Jun 28 12:20:55 wb kernel: [13850.662683] usbserial: USB Serial Driver core
Jun 28 12:20:55 wb kernel: [13850.675874] USB Serial support registered for GSM 
modem (1-port)
Jun 28 12:20:55 wb kernel: [13850.675977] option 2-3:1.0: GSM modem (1-port) 
converter detected
Jun 28 12:20:55 wb kernel: [13850.676873] usb 2-3: GSM modem (1-port) converter 
now attached to ttyUSB0
Jun 28 12:20:55 wb kernel: [13850.676897] option 2-3:1.1: GSM modem (1-port) 
converter detected
Jun 28 12:20:55 wb kernel: [13850.676990] usb 2-3: GSM modem (1-port) converter 
now attached to ttyUSB1
Jun 28 12:20:55 wb kernel: [13850.677042] usbcore: registered new interface 
driver option
Jun 28 12:20:55 wb kernel: [13850.677046] option: v0.7.2:USB Driver for GSM 
modems
Jun 28 12:20:56 wb NetworkManager: <info>  (ttyUSB1): ignoring due to lack of 
mobile broadband capabilties 
Jun 28 12:20:56 wb nm-system-settings:    SCPlugin-Ifupdown: device added (udi: 
/org/freedesktop/Hal/devices/usb_device_12d1_1003_noserial_if0_serial_usb_0, 
iface: (null)): iface not found
Jun 28 12:20:56 wb NetworkManager: <info>  (ttyUSB0): found serial port 
(udev:GSM  hal:GSM) 
Jun 28 12:20:56 wb NetworkManager: <info>  (ttyUSB0): new Modem device (driver: 
'option') 
Jun 28 12:20:56 wb NetworkManager: <info>  (ttyUSB0): exported as 
/org/freedesktop/Hal/devices/usb_device_12d1_1003_noserial_if0_serial_usb_0 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): device state change: 1 -> 
2 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): deactivating device 
(reason: 2). 
Jun 28 12:21:01 wb NetworkManager: <info>  Policy set 'rsb' (wlan0) as default 
for routing and DNS. 
Jun 28 12:21:01 wb NetworkManager: 
nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Jun 28 12:21:01 wb NetworkManager: 
nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' 
failed
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): device state change: 2 -> 
3 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) starting 
connection 'Yesss' 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): device state change: 3 -> 
4 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 
(Device Prepare) scheduled... 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 
(Device Prepare) started... 
Jun 28 12:21:01 wb NetworkManager: <debug> [1246184461.033944] 
nm_serial_device_open(): (ttyUSB0) opening device... 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 
(Device Prepare) complete. 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): GSM pin secret required 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): device state change: 4 -> 
6 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 
(Device Prepare) scheduled... 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 
(Device Prepare) started... 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): device state change: 6 -> 
4 
Jun 28 12:21:01 wb NetworkManager: <debug> [1246184461.230078] 
nm_serial_device_open(): (ttyUSB0) opening device... 
Jun 28 12:21:01 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 
(Device Prepare) complete. 
Jun 28 12:21:01 wb NetworkManager: <info>  (ttyUSB0): powering up... 
Jun 28 12:21:03 wb NetworkManager: <info>  Searching for a network... 
Jun 28 12:21:05 wb NetworkManager: <info>  Searching for a network... 
Jun 28 12:21:06 wb NetworkManager: <info>  Registered on Roaming network 
Jun 28 12:21:06 wb NetworkManager: <info>  Associated with network: +COPS: 
0,2,"23205",2 
Jun 28 12:21:06 wb NetworkManager: <info>  Connected, Woo! 
Jun 28 12:21:06 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 
(Device Configure) scheduled... 
Jun 28 12:21:06 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 
(Device Configure) starting... 
Jun 28 12:21:06 wb NetworkManager: <info>  (ttyUSB0): device state change: 4 -> 
5 
Jun 28 12:21:06 wb NetworkManager: <info>  Starting pppd connection 
Jun 28 12:21:06 wb NetworkManager: <debug> [1246184466.174152] 
nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock 
nodefaultroute ttyUSB0 noipdefault noauth usepeerdns lcp-echo-failure 0 
lcp-echo-interval 0 ipparam /org/freedesktop/NetworkManager/PPP/0 plugin 
/usr/lib/pppd/2.4.4/nm-pppd-plugin.so 
Jun 28 12:21:06 wb NetworkManager: <debug> [1246184466.189485] 
nm_ppp_manager_start(): ppp started with pid 22413 
Jun 28 12:21:06 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 2 of 5 
(Device Configure) complete. 
Jun 28 12:21:06 wb pppd[22413]: Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so 
loaded.
Jun 28 12:21:06 wb pppd[22413]: pppd 2.4.5 started by root, uid 0
Jun 28 12:21:06 wb pppd[22413]: Using interface ppp0
Jun 28 12:21:06 wb pppd[22413]: Connect: ppp0 <--> /dev/ttyUSB0
Jun 28 12:21:06 wb NetworkManager: <info>  (ttyUSB0): device state change: 5 -> 
6 
Jun 28 12:21:06 wb pppd[22413]: CHAP authentication succeeded
Jun 28 12:21:06 wb pppd[22413]: CHAP authentication succeeded
Jun 28 12:21:06 wb NetworkManager: <info>  (ttyUSB0): device state change: 6 -> 
7 
Jun 28 12:21:06 wb kernel: [13861.461555] PPP BSD Compression module registered
Jun 28 12:21:06 wb kernel: [13861.547037] PPP Deflate Compression module 
registered
Jun 28 12:21:06 wb pppd[22442]: pppd 2.4.5 started by root, uid 0
Jun 28 12:21:06 wb pppd[22442]: Device ttyUSB0 is locked by pid 22413
Jun 28 12:21:16 wb pppd[22413]: Could not determine remote IP address: 
defaulting to 10.64.64.64
Jun 28 12:21:16 wb pppd[22413]: found interface wlan0 for proxy arp
Jun 28 12:21:16 wb pppd[22413]: local  IP address 10.113.214.1
Jun 28 12:21:16 wb pppd[22413]: remote IP address 10.64.64.64
Jun 28 12:21:16 wb pppd[22413]: primary   DNS address 81.3.216.100
Jun 28 12:21:16 wb pppd[22413]: secondary DNS address 194.24.128.100
Jun 28 12:21:16 wb NetworkManager: <info>  PPP manager(IP Config Get) reply 
received. 
Jun 28 12:21:16 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 4 of 5 
(IP Configure Get) scheduled... 
Jun 28 12:21:16 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 4 of 5 
(IP Configure Get) started... 
Jun 28 12:21:16 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 5 of 5 
(IP Configure Commit) scheduled... 
Jun 28 12:21:16 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 4 of 5 
(IP Configure Get) complete. 
Jun 28 12:21:16 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 5 of 5 
(IP Configure Commit) started... 
Jun 28 12:21:17 wb NetworkManager: <info>  Policy set 'rsb' (wlan0) as default 
for routing and DNS. 
Jun 28 12:21:17 wb NetworkManager: <info>  (ttyUSB0): device state change: 7 -> 
8 
Jun 28 12:21:17 wb NetworkManager: <info>  Activation (ttyUSB0) successful, 
device activated. 
Jun 28 12:21:17 wb NetworkManager: <info>  Activation (ttyUSB0) Stage 5 of 5 
(IP Configure Commit) complete. 
Jun 28 12:22:06 wb pppd[22442]: Device ttyUSB0 is locked by pid 22413
Jun 28 12:22:36 wb pppd[22442]: Device ttyUSB0 is locked by pid 22413
Jun 28 12:22:39 wb kernel: [13954.296836] usb 2-3: USB disconnect, address 6
Jun 28 12:22:39 wb pppd[22413]: Modem hangup
Jun 28 12:22:39 wb pppd[22413]: Connect time 1.4 minutes.
Jun 28 12:22:39 wb pppd[22413]: Sent 0 bytes, received 0 bytes.
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): device state change: 8 -> 
7 
Jun 28 12:22:39 wb kernel: [13954.297390] option 2-3:1.0: device disconnected
Jun 28 12:22:39 wb kernel: [13954.297723] option1 ttyUSB1: GSM modem (1-port) 
converter now disconnected from ttyUSB1
Jun 28 12:22:39 wb kernel: [13954.299097] option 2-3:1.1: device disconnected
Jun 28 12:22:39 wb pppd[22413]: Connection terminated.
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): device state change: 7 -> 
9 
Jun 28 12:22:39 wb NetworkManager: <WARN>  monitor_cb(): Could not read ppp 
stats: No such device 
Jun 28 12:22:39 wb NetworkManager: <debug> [1246184559.497356] 
nm_serial_device_close(): Closing device 'ttyUSB0' 
Jun 28 12:22:39 wb NetworkManager: <info>  Marking connection 'Yesss' invalid. 
Jun 28 12:22:39 wb NetworkManager: <info>  Activation (ttyUSB0) failed. 
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): now unmanaged 
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): device state change: 9 -> 
1 
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): deactivating device 
(reason: 36). 
Jun 28 12:22:39 wb pppd[22413]: Terminating on signal 15
Jun 28 12:22:39 wb pppd[22413]: Exit.
Jun 28 12:22:39 wb NetworkManager: <info>  Policy set 'rsb' (wlan0) as default 
for routing and DNS. 
Jun 28 12:22:39 wb NetworkManager: 
nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Jun 28 12:22:39 wb NetworkManager: 
nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' 
failed
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): cleaning up... 
Jun 28 12:22:39 wb NetworkManager: <info>  (ttyUSB0): taking down device. 
Jun 28 12:22:39 wb pppd[22442]: Terminating on signal 15
Jun 28 12:22:39 wb pppd[22442]: Exit.
Jun 28 12:22:42 wb NetworkManager: <debug> [1246184561.999940] ensure_killed(): 
waiting for ppp pid 22413 to exit 
Jun 28 12:22:42 wb NetworkManager: <debug> [1246184562.000109] ensure_killed(): 
ppp pid 22413 cleaned up 
-------------------------------

-- 
network manager ppp timeout
https://bugs.launchpad.net/bugs/393094
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

Reply via email to