Alexander,
      thank you very much for fixing this, wonderful work!

Thanks for this great release!

2009/10/31 Alexander Sack <email address hidden>

> the trunk build is available for lpia and amd64 ... however, i386
> builders are a bit busy, so it will take another few hours still ...
> see: https://edge.launchpad.net/~network-
> manager/+archive/trunk/+build/1314913<https://edge.launchpad.net/%7Enetwork-%0Amanager/+archive/trunk/+build/1314913>
>
> --
> [MASTER] pppd timeout when trying to establish a DSL connection
> https://bugs.launchpad.net/bugs/432205
> You received this bug notification because you are a direct subscriber
> of the bug.
>
> Status in “network-manager” package in Ubuntu: Fix Committed
> Status in “ppp” package in Ubuntu: Invalid
> Status in “network-manager” package in openSUSE: Invalid
>
> Bug description:
> 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_system_hostname
> NetworkManager:    SCPluginIfupdown: management mode: unmanaged
> NetworkManager:    SCPlugin-Ifupdown: devices added (path:
> /sys/devices/pci0000:00/0000:00:05.0/net/eth0, iface: eth0)
> NetworkManager:    SCPlugin-Ifupdown: device added (path:
> /sys/devices/pci0000:00/0000:00:05.0/net/eth0, iface: eth0): no exported
> connection
> NetworkManager:    SCPlugin-Ifupdown: devices added (path:
> /sys/devices/virtual/net/lo, iface: lo)
> NetworkManager:    SCPlugin-Ifupdown: device added (path:
> /sys/devices/virtual/net/lo, iface: lo): no exported connection
> 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/freedesktop/NetworkManager/Devices/0
> 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-nr=1253233374 pid=4199817
> NetworkManager: <WARN>  check_one_route(): (eth0) error -34 returned from
> rtnl_route_del(): Sucess
>
> NetworkManager: Added default wired connection 'Auto eth0' for
> /sys/devices/pci0000:00/0000:00:05.0/net/eth0
> NetworkManager: <WARN>  default_adapter_cb(): bluez error getting default
> adapter: The name org.bluez was not provided by any .service files
> 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://www.isc.org/sw/dhcp/
>
> NetworkManager: <info>  dhclient started with pid 5515
> NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP6 Configure Get)
> scheduled...
> Listening on LPF/eth0/00:13:d4:31:b8:16
> Sending on   LPF/eth0/00:13:d4:31:b8:16
> 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-nr=1253233396 pid=4199817
> 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_manager_start(): Command
> line: /usr/sbin/pppd nodetach lock nodefaultroute debug user dfe3ads471
> plugin rp-pppoe.so nic-eth0 noauth nodeflate usepeerdns mru 1492 mtu 1492
> lcp-echo-failure 3 lcp-echo-interval 20 ipparam
> /org/freedesktop/NetworkManager/PPP/0 plugin
> /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
> Plugin rp-pppoe.so loaded.
> RP-PPPoE plugin version 3.8p compiled against pppd 2.4.5
> Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
> NetworkManager: <debug> [1253233380.485001] nm_ppp_manager_start(): ppp
> started with pid 5579
> 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/virtual/net/ppp0, iface: ppp0)
> NetworkManager:    SCPlugin-Ifupdown: device added (path:
> /sys/devices/virtual/net/ppp0, iface: ppp0): no exported connection
> 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/virtual/net/ppp0, iface: ppp0)
> 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://www.isc.org/sw/dhcp/
>
> Listening on LPF/eth0/00:13:d4:31:b8:16
> Sending on   LPF/eth0/00:13:d4:31:b8:16
> 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_handler(): Caught signal 2, shutting
> down normally.
> 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/freedesktop/NetworkManager/PPP/0 plugin
> /usr/lib/pppd/2.4.4/nm-pppd-plugin.so debug
>
> Any ideas?
>