pppd_timed_out(): Looks like pppd didn't initialize our dbus module

[Alessandro Bono <alessandro bono gmail com>]

Hi

I'm having problem with my umts connection with NM
after obtaining ip and other parameters NM emit this warning

pppd_timed_out(): Looks like pppd didn't initialize our dbus module

and close connection
distribution is an ubuntu jaunty

dpkg -l | grep network-manager
ii  network-manager                            0.7.1~rc4.1.cf199a964-0ubuntu2                             network management framework daemon
ii  network-manager-gnome                      0.7.1~rc4.1-0ubuntu2                                       network management framework (GNOME frontend
ii  network-manager-kde                        1:0.7svn864988-0ubuntu8                                    KDE systray applet for controlling NetworkMa
ii  network-manager-openvpn                    0.7.1~rc4.1.20090323+bzr27-0ubuntu2                        network management framework (OpenVPN plugin
ii  network-manager-pptp                       0.7.1~rc4.20090316+bzr23-0ubuntu3                          network management framework (PPTP plugin)
ii  network-manager-vpnc                       0.7.1~rc4.20090316+bzr21-0ubuntu2                          network management framework (VPNC plugin)

dpkg -l ppp
ii  ppp                                                   2.4.5~git20081126t100229-0ubuntu2                     Point-to-Point Protocol (PPP) - daemon


modem is an Onda et505up

below a full log of a connection

tell me if you need other info

thanks


Apr 24 23:57:29 champagne NetworkManager: <info>  Activation (ttyUSB1) starting connection 'TIM'
Apr 24 23:57:29 champagne NetworkManager: <info>  (ttyUSB1): device state change: 3 -> 4
Apr 24 23:57:29 champagne NetworkManager: <info>  Activation (ttyUSB1) Stage 1 of 5 (Device Prepare) scheduled...
Apr 24 23:57:29 champagne NetworkManager: <info>  Activation (ttyUSB1) Stage 1 of 5 (Device Prepare) started...
Apr 24 23:57:29 champagne NetworkManager: <debug> [1240610249.890546] nm_serial_device_open(): (ttyUSB1) opening device...
Apr 24 23:57:29 champagne NetworkManager: <info>  Activation (ttyUSB1) Stage 1 of 5 (Device Prepare) complete.
Apr 24 23:57:30 champagne NetworkManager: <info>  (ttyUSB1): powering up...
Apr 24 23:57:30 champagne NetworkManager: <info>  Registered on Home network
Apr 24 23:57:30 champagne NetworkManager: <info>  Associated with network: +COPS: 0,0,"Telecom Italia Mobile",2
Apr 24 23:57:30 champagne NetworkManager: <info>  Connected, Woo!
Apr 24 23:57:30 champagne NetworkManager: <info>  Activation (ttyUSB1) Stage 2 of 5 (Device Configure) scheduled...
Apr 24 23:57:30 champagne NetworkManager: <info>  Activation (ttyUSB1) Stage 2 of 5 (Device Configure) starting...
Apr 24 23:57:30 champagne NetworkManager: <info>  (ttyUSB1): device state change: 4 -> 5
Apr 24 23:57:30 champagne NetworkManager: <info>  Starting pppd connection
Apr 24 23:57:30 champagne NetworkManager: <debug> [1240610250.404936] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock nodefaultroute ttyUSB1 noipdefault noauth usepeerdns lcp-echo-failure 0 lcp-echo-interval 0 ipparam/org/freedesktop/NetworkManager/PPP/7 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
Apr 24 23:57:30 champagne NetworkManager: <debug> [1240610250.406659] nm_ppp_manager_start(): ppp started with pid 28220
Apr 24 23:57:30 champagne NetworkManager: <info>  Activation (ttyUSB1) Stage 2 of 5 (Device Configure) complete.
Apr 24 23:57:30 champagne pppd[28220]: Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
Apr 24 23:57:30 champagne pppd[28220]: pppd 2.4.5 started by root, uid 0
Apr 24 23:57:30 champagne pppd[28220]: Using interface ppp0
Apr 24 23:57:30 champagne pppd[28220]: Connect: ppp0 <--> /dev/ttyUSB1
Apr 24 23:57:30 champagne pppd[28220]: Unable to obtain CHAP password for champagne on UMTS_CHAP_SRVR from plugin
Apr 24 23:57:30 champagne pppd[28220]: No CHAP secret found for authenticating us to UMTS_CHAP_SRVR
Apr 24 23:57:30 champagne pppd[28220]: CHAP authentication succeeded
Apr 24 23:57:30 champagne pppd[28220]: CHAP authentication succeeded
Apr 24 23:57:33 champagne pppd[28220]: Could not determine remote IP address: defaulting to 10.64.64.64
Apr 24 23:57:33 champagne pppd[28220]: Cannot determine ethernet address for proxy ARP
Apr 24 23:57:33 champagne pppd[28220]: local  IP address 217.201.129.203
Apr 24 23:57:33 champagne pppd[28220]: remote IP address 10.64.64.64
Apr 24 23:57:33 champagne pppd[28220]: primary   DNS address 213.230.129.94
Apr 24 23:57:33 champagne pppd[28220]: secondary DNS address 213.230.155.94
Apr 24 23:57:37 champagne postfix/master[4547]: reload configuration /etc/postfix
Apr 24 23:57:46 champagne NetworkManager: <WARN>  pppd_timed_out(): Looks like pppd didn't initialize our dbus module
Apr 24 23:57:46 champagne NetworkManager: <info>  (ttyUSB1): device state change: 5 -> 9
Apr 24 23:57:46 champagne pppd[28220]: Terminating on signal 15
Apr 24 23:57:46 champagne pppd[28220]: Connect time 0.3 minutes.
Apr 24 23:57:46 champagne pppd[28220]: Sent 0 bytes, received 204 bytes.
Apr 24 23:57:46 champagne NetworkManager: <debug> [1240610266.003519] nm_serial_device_close(): Closing device 'ttyUSB1'
Apr 24 23:57:46 champagne NetworkManager: <info>  Marking connection 'TIM' invalid.
Apr 24 23:57:46 champagne NetworkManager: <info>  Activation (ttyUSB1) failed.
Apr 24 23:57:46 champagne NetworkManager: <info>  (ttyUSB1): device state change: 9 -> 3
Apr 24 23:57:46 champagne NetworkManager: <info>  (ttyUSB1): deactivating device (reason: 0).
Apr 24 23:57:46 champagne dnsmasq[4521]: reading /etc/resolv.conf
Apr 24 23:57:46 champagne dnsmasq[4521]: using nameserver 10.151.1.3#53
Apr 24 23:57:46 champagne dnsmasq[4521]: using nameserver 213.230.155.94#53
Apr 24 23:57:46 champagne dnsmasq[4521]: using nameserver 213.230.129.94#53
Apr 24 23:57:46 champagne NetworkManager: <info>  (eth0): writing resolv.conf to /sbin/resolvconf
Apr 24 23:57:46 champagne pppd[28220]: Connection terminated.
Apr 24 23:57:46 champagne NetworkManager: <info>  Policy set 'Auto eth0' (eth0) as default for routing and DNS.
Apr 24 23:57:46 champagne NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Apr 24 23:57:46 champagne NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
Apr 24 23:57:46 champagne postfix/master[4547]: reload configuration /etc/postfix
Apr 24 23:57:46 champagne pppd[28220]: Exit.
Apr 24 23:57:48 champagne NetworkManager: <debug> [1240610268.002972] ensure_killed(): waiting for ppp pid 28220 to exit
Apr 24 23:57:48 champagne NetworkManager: <debug> [1240610268.003537] ensure_killed(): ppp pid 28220 cleaned up


-- 
Cordiali saluti

Alessandro Bono