[MASTER] pppd timeout when trying to establish a DSL connection
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
OEM Priority Project |
Fix Released
|
Undecided
|
Unassigned | ||
network-manager (Ubuntu) |
Fix Released
|
High
|
Alexander Sack | ||
network-manager (openSUSE) |
Invalid
|
Undecided
|
Unassigned | ||
ppp (Ubuntu) |
Invalid
|
Undecided
|
Unassigned | ||
Bug Description
NOTE: fixed packages are available here: https:/
=====
Binary package hint: network-manager
In Karmic alpha 5 I could establish a DSL connection.
In alpha 6, NetworkManager cannot do it.
I take the following steps: Edit connections -> DSL -> Add, then enter my username and password.
After, I choose "DSL Connection 1" from drop-down menu of NetworkManager Applet 0.7.996
Two green points appear on applet icon, and blue thingy circulates around them for a long time.
Then icon appears as disconnected, then two green points again, but this time pop-up note says that the connection is established to "Auto eth0".
I tried the following command:
sudo sh -c "NM_PPP_DEBUG=true NetworkManager --no-daemon"
Then I tried to connect again and the output was the following:
-------
NetworkManager: <info> starting...
NetworkManager: <info> modem-manager is now available
NetworkManager: SCPlugin-Ifupdown: init!
NetworkManager: SCPlugin-Ifupdown: update_
NetworkManager: SCPluginIfupdown: management mode: unmanaged
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/
NetworkManager: SCPlugin-Ifupdown: end _init.
NetworkManager: Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list.
NetworkManager: Loaded plugin keyfile: (c) 2007 - 2008 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
NetworkManager: <info> Wireless now enabled by radio killswitch
NetworkManager: SCPlugin-Ifupdown: (148258080) ... get_connections.
NetworkManager: SCPlugin-Ifupdown: (148258080) ... get_connections (managed=false): return empty list.
NetworkManager: Ifupdown: get unmanaged devices count: 0
NetworkManager: <info> (eth0): new Ethernet device (driver: 'forcedeth')
NetworkManager: <info> (eth0): exported as /org/freedeskto
NetworkManager: <info> (eth0): now managed
NetworkManager: <info> (eth0): device state change: 1 -> 2 (reason 2)
NetworkManager: <info> (eth0): preparing device.
NetworkManager: <info> (eth0): deactivating device (reason: 2).
-- Error received: Numerical result out of range
-- Original message: type=0x19 length=56 flags=<REQUEST,ACK> sequence-
NetworkManager: <WARN> check_one_route(): (eth0) error -34 returned from rtnl_route_del(): Sucess
NetworkManager: Added default wired connection 'Auto eth0' for /sys/devices/
NetworkManager: <WARN> default_
NetworkManager: <info> (eth0): carrier now ON (device state 2)
NetworkManager: <info> (eth0): device state change: 2 -> 3 (reason 40)
NetworkManager: <info> Activation (eth0) starting connection 'Auto eth0'
NetworkManager: <info> (eth0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (eth0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <info> (eth0): device state change: 5 -> 7 (reason 0)
NetworkManager: <info> Activation (eth0) Beginning DHCP transaction (timeout in 45 seconds)
Internet Systems Consortium DHCP Client V3.1.2
Copyright 2004-2008 Internet Systems Consortium.
All rights reserved.
For info, please visit http://
NetworkManager: <info> dhclient started with pid 5515
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) scheduled...
Listening on LPF/eth0/
Sending on LPF/eth0/
Sending on Socket/fallback
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8
DHCPOFFER of 192.168.1.2 from 192.168.1.1
DHCPREQUEST of 192.168.1.2 on eth0 to 255.255.255.255 port 67
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
DHCPACK of 192.168.1.2 from 192.168.1.1
NetworkManager: <info> DHCP: device eth0 state changed (null) -> preinit
NetworkManager: <info> DHCP: device eth0 state changed preinit -> bound
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) started...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) complete.
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
NetworkManager: <info> address 192.168.1.2
NetworkManager: <info> prefix 24 (255.255.255.0)
NetworkManager: <info> gateway 192.168.1.1
NetworkManager: <info> hostname 'ubuntu'
NetworkManager: <info> nameserver '192.168.1.1'
NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
bound to 192.168.1.2 -- renewal in 1364 seconds.
NetworkManager: <info> (eth0): device state change: 7 -> 8 (reason 0)
NetworkManager: <info> Policy set 'Auto eth0' (eth0) as default for routing and DNS.
NetworkManager: <info> Activation (eth0) successful, device activated.
NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
NetworkManager: <info> (eth0): device state change: 8 -> 3 (reason 0)
NetworkManager: <info> (eth0): deactivating device (reason: 0).
NetworkManager: <info> (eth0): canceled DHCP transaction, dhcp client pid 5515
-- Error received: Numerical result out of range
-- Original message: type=0x19 length=56 flags=<REQUEST,ACK> sequence-
NetworkManager: <WARN> check_one_route(): (eth0) error -34 returned from rtnl_route_del(): Sucess
NetworkManager: <info> Activation (eth0) starting connection 'DSL connection 1'
NetworkManager: <info> (eth0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (eth0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <info> (eth0): device state change: 5 -> 7 (reason 0)
NetworkManager: <info> Starting pppd connection
NetworkManager: <debug> [1253233380.455558] nm_ppp_
Plugin rp-pppoe.so loaded.
RP-PPPoE plugin version 3.8p compiled against pppd 2.4.5
Plugin /usr/lib/
NetworkManager: <debug> [1253233380.485001] nm_ppp_
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) scheduled...
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) started...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) complete.
Send PPPOE Discovery V1T1 PADI session 0x0 length 12
dst ff:ff:ff:ff:ff:ff src 0:13:d4:31:b8:16
[service-name] [host-uniq cb 15 00 00]
Recv PPPOE Discovery V1T1 PADO session 0x0 length 45
dst 0:13:d4:31:b8:16 src 0:1a:6c:93:a6:1a
[service-name] [host-uniq cb 15 00 00] [AC-name SCH-SSG-6] [AC-cookie 69 24 62 67 66 79 db 29 31 25 6a 45 1f bf 0f 13]
Send PPPOE Discovery V1T1 PADR session 0x0 length 32
dst 0:1a:6c:93:a6:1a src 0:13:d4:31:b8:16
[service-name] [host-uniq cb 15 00 00] [AC-cookie 69 24 62 67 66 79 db 29 31 25 6a 45 1f bf 0f 13]
Recv PPPOE Discovery V1T1 PADS session 0xfc17 length 32
dst 0:13:d4:31:b8:16 src 0:1a:6c:93:a6:1a
[service-name] [host-uniq cb 15 00 00] [AC-cookie 69 24 62 67 66 79 db 29 31 25 6a 45 1f bf 0f 13]
PADS: Service-Name: ''
PPP session is 64535
Connected to 00:1a:6c:93:a6:1a via interface eth0
using channel 2
Using interface ppp0
Connect: ppp0 <--> eth0
sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x4835079f>]
NetworkManager: SCPlugin-Ifupdown: devices added (path: /sys/devices/
NetworkManager: SCPlugin-Ifupdown: device added (path: /sys/devices/
rcvd [LCP ConfReq id=0x1 <mru 1492> <auth pap> <magic 0x605a078b>]
sent [LCP ConfAck id=0x1 <mru 1492> <auth pap> <magic 0x605a078b>]
rcvd [LCP ConfAck id=0x1 <mru 1492> <magic 0x4835079f>]
sent [LCP EchoReq id=0x0 magic=0x4835079f]
NetworkManager: <WARN> pppd_timed_out(): Looks like pppd didn't initialize our dbus module
NetworkManager: <info> (eth0): device state change: 7 -> 9 (reason 14)
NetworkManager: <info> Marking connection 'DSL connection 1' invalid.
NetworkManager: <info> Activation (eth0) failed.
NetworkManager: <info> (eth0): device state change: 9 -> 3 (reason 0)
NetworkManager: <info> (eth0): deactivating device (reason: 0).
NetworkManager: <debug> [1253233403.002232] ensure_killed(): waiting for ppp pid 5579 to exit
NetworkManager: <debug> [1253233403.256950] ensure_killed(): ppp pid 5579 cleaned up
NetworkManager: SCPlugin-Ifupdown: devices removed (path: /sys/devices/
NetworkManager: <info> Activation (eth0) starting connection 'Auto eth0'
NetworkManager: <info> (eth0): device state change: 3 -> 4 (reason 0)
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <info> (eth0): device state change: 4 -> 5 (reason 0)
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <info> (eth0): device state change: 5 -> 7 (reason 0)
NetworkManager: <info> Activation (eth0) Beginning DHCP transaction (timeout in 45 seconds)
Internet Systems Consortium DHCP Client V3.1.2
Copyright 2004-2008 Internet Systems Consortium.
All rights reserved.
For info, please visit http://
Listening on LPF/eth0/
Sending on LPF/eth0/
Sending on Socket/fallback
NetworkManager: <info> dhclient started with pid 5595
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) scheduled...
NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <info> DHCP: device eth0 state changed normal exit -> preinit
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) started...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP6 Configure Get) complete.
DHCPREQUEST of 192.168.1.2 on eth0 to 255.255.255.255 port 67
DHCPACK of 192.168.1.2 from 192.168.1.1
NetworkManager: <info> DHCP: device eth0 state changed preinit -> reboot
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
NetworkManager: <info> address 192.168.1.2
NetworkManager: <info> prefix 24 (255.255.255.0)
NetworkManager: <info> gateway 192.168.1.1
NetworkManager: <info> hostname 'ubuntu'
NetworkManager: <info> nameserver '192.168.1.1'
NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
bound to 192.168.1.2 -- renewal in 1480 seconds.
NetworkManager: <info> (eth0): device state change: 7 -> 8 (reason 0)
NetworkManager: <info> Policy set 'Auto eth0' (eth0) as default for routing and DNS.
NetworkManager: <info> Activation (eth0) successful, device activated.
NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
^CNetworkManager: <WARN> nm_signal_
NetworkManager: <info> exiting (success)
-------
The connection happens immediately If I manually issue the following command:
sudo /usr/sbin/pppd nodetach lock nodefaultroute user <USERNAME> password <PASSWORD> plugin rp-pppoe.so nic-eth0 noauth nodeflate usepeerdns mru 1492 mtu 1492 lcp-echo-failure 3 lcp-echo-interval 20 ipparam /org/freedeskto
Any ideas?
Related branches
Changed in network-manager (Ubuntu): | |
status: | New → Confirmed |
summary: |
- Unable to establish DSL connection + [MASTER] pppd timeout when trying to establish a DSL connection |
Changed in network-manager (Ubuntu): | |
status: | Confirmed → In Progress |
status: | In Progress → Confirmed |
Changed in network-manager (Ubuntu): | |
assignee: | nobody → Alexander Sack (asac) |
importance: | Undecided → High |
milestone: | none → karmic-updates |
Changed in network-manager (Ubuntu): | |
status: | Fix Committed → Fix Released |
status: | Fix Released → Fix Committed |
Changed in network-manager (Ubuntu): | |
status: | Fix Committed → Fix Released |
Changed in network-manager (Ubuntu): | |
status: | Fix Released → In Progress |
Changed in network-manager (Ubuntu): | |
status: | In Progress → Fix Released |
Changed in network-manager (Ubuntu): | |
status: | Fix Committed → Fix Released |
status: | Fix Released → Fix Committed |
Changed in network-manager (Ubuntu): | |
status: | Fix Committed → Fix Released |
Changed in network-manager (Ubuntu): | |
status: | Fix Released → Fix Committed |
Changed in network-manager (Ubuntu): | |
status: | Fix Released → New |
Changed in oem-priority: | |
status: | New → Fix Released |
I can confirm this bug in Ubuntu 9.10 rc too. I am able to connect using pon/pppoeconf though.