network manager ppp timeout
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
network-manager (Ubuntu) |
Won't Fix
|
Low
|
Unassigned | ||
Bug Description
NetworkManager: <WARN> pppd_timed_out(): Looks like pppd didn't initialize our dbus module
I cannot connect via usb UMTS modem.
Here's the log (Machine: MSI X320, Ubuntu 9.04, Linux 2.6.28.13-generic #44-Ubuntu SMP)
-------
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) starting connection 'Yesss'
Jun 28 11:29:53 xb NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Jun 28 11:29:53 xb NetworkManager: <debug> [1246181393.723285] nm_serial_
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Jun 28 11:29:53 xb NetworkManager: <info> (ttyUSB0): GSM pin secret required
Jun 28 11:29:53 xb NetworkManager: <info> (ttyUSB0): device state change: 4 -> 6
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Jun 28 11:29:53 xb NetworkManager: <info> (ttyUSB0): device state change: 6 -> 4
Jun 28 11:29:53 xb NetworkManager: <debug> [1246181393.929608] nm_serial_
Jun 28 11:29:53 xb NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Jun 28 11:29:54 xb NetworkManager: <info> (ttyUSB0): powering up...
Jun 28 11:29:56 xb NetworkManager: <info> Searching for a network...
Jun 28 11:30:01 xb last message repeated 5 times
Jun 28 11:30:02 xb NetworkManager: <info> Registered on Roaming network
Jun 28 11:30:02 xb NetworkManager: <info> Associated with network: +COPS: 0,2,"23205",2
Jun 28 11:30:02 xb NetworkManager: <info> Connected, Woo!
Jun 28 11:30:02 xb NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Jun 28 11:30:02 xb NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Jun 28 11:30:02 xb NetworkManager: <info> (ttyUSB0): device state change: 4 -> 5
Jun 28 11:30:02 xb NetworkManager: <info> Starting pppd connection
Jun 28 11:30:02 xb NetworkManager: <debug> [1246181402.327197] nm_ppp_
Jun 28 11:30:02 xb NetworkManager: <debug> [1246181402.347065] nm_ppp_
Jun 28 11:30:02 xb NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Jun 28 11:30:02 xb pppd[3306]: Plugin /usr/lib/
Jun 28 11:30:02 xb pppd[3306]: pppd 2.4.5 started by root, uid 0
Jun 28 11:30:02 xb pppd[3306]: Using interface ppp0
Jun 28 11:30:02 xb pppd[3306]: Connect: ppp0 <--> /dev/ttyUSB0
Jun 28 11:30:02 xb pppd[3306]: Unable to obtain CHAP password for xb on UMTS_CHAP_SRVR from plugin
Jun 28 11:30:02 xb pppd[3306]: No CHAP secret found for authenticating us to UMTS_CHAP_SRVR
Jun 28 11:30:02 xb pppd[3306]: CHAP authentication succeeded
Jun 28 11:30:02 xb pppd[3306]: CHAP authentication succeeded
Jun 28 11:30:02 xb kernel: [ 137.660149] PPP BSD Compression module registered
Jun 28 11:30:02 xb kernel: [ 137.717957] PPP Deflate Compression module registered
Jun 28 11:30:06 xb pppd[3306]: Could not determine remote IP address: defaulting to 10.64.64.64
Jun 28 11:30:06 xb pppd[3306]: Cannot determine ethernet address for proxy ARP
Jun 28 11:30:06 xb pppd[3306]: local IP address 10.113.214.1
Jun 28 11:30:06 xb pppd[3306]: remote IP address 10.64.64.64
Jun 28 11:30:06 xb pppd[3306]: primary DNS address 81.3.216.100
Jun 28 11:30:06 xb pppd[3306]: secondary DNS address 194.24.128.100
Jun 28 11:30:18 xb NetworkManager: <WARN> pppd_timed_out(): Looks like pppd didn't initialize our dbus module
Jun 28 11:30:18 xb NetworkManager: <info> (ttyUSB0): device state change: 5 -> 9
Jun 28 11:30:18 xb pppd[3306]: Terminating on signal 15
Jun 28 11:30:18 xb NetworkManager: <debug> [1246181418.011641] nm_serial_
Jun 28 11:30:18 xb pppd[3306]: Connect time 0.2 minutes.
Jun 28 11:30:18 xb pppd[3306]: Sent 0 bytes, received 0 bytes.
Jun 28 11:30:18 xb NetworkManager: <info> Marking connection 'Yesss' invalid.
Jun 28 11:30:18 xb NetworkManager: <info> Activation (ttyUSB0) failed.
Jun 28 11:30:18 xb NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
Jun 28 11:30:18 xb NetworkManager: <info> (ttyUSB0): deactivating device (reason: 0).
Jun 28 11:30:18 xb NetworkManager: nm_system_
Jun 28 11:30:18 xb NetworkManager: nm_system_
Jun 28 11:30:18 xb pppd[3306]: Connection terminated.
Jun 28 11:30:18 xb pppd[3306]: Exit.
Jun 28 11:30:20 xb NetworkManager: <debug> [1246181420.002615] ensure_killed(): waiting for ppp pid 3306 to exit
Jun 28 11:30:20 xb NetworkManager: <debug> [1246181420.002969] ensure_killed(): ppp pid 3306 cleaned up
-------
It works fine on all my other machines.
This is how it works on my main machine (not flawless because disconnect does not work): ------- ------- p/Hal/devices/ usb_device_ 12d1_1003_ noserial_ if0_serial_ usb_0, iface: (null)): iface not found p/Hal/devices/ usb_device_ 12d1_1003_ noserial_ if0_serial_ usb_0 device_ flush_ip4_ routes_ with_iface: assertion `iface_idx >= 0' failed device_ flush_ip4_ addresses_ with_iface: assertion `iface_idx >= 0' failed
-------
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/freedeskto
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/freedeskto
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_
Jun 28 12:21:01 wb NetworkManager: nm_system_
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 ...