Activity log for bug #432205

Date Who What changed Old value New value Message
2009-09-18 00:52:34 Michael Lazarev bug added bug
2009-09-18 00:56:06 Michael Lazarev nominated for series Ubuntu Karmic
2009-10-17 23:31:44 yurik81 bug added subscriber yurik81
2009-10-23 16:55:39 Michael Lazarev network-manager (Ubuntu): status New Confirmed
2009-10-26 18:55:41 Mathieu Trudel-Lapierre summary Unable to establish DSL connection [MASTER] pppd timeout when trying to establish a DSL connection
2009-10-26 19:20:38 Mathieu Trudel-Lapierre bug task added ppp (Ubuntu)
2009-10-26 21:42:07 theghost attachment added Debug Log http://launchpadlibrarian.net/34428582/Debug.log
2009-10-29 11:21:44 Jurist C. M. Macabuhay removed subscriber Jurist C. M. Macabuhay
2009-10-30 12:37:08 Daniel de Souza Telles bug watch added https://bugzilla.novell.com/show_bug.cgi?id=530430
2009-10-30 12:37:55 Daniel de Souza Telles bug task added network-manager (openSUSE)
2009-10-30 12:39:53 Daniel de Souza Telles network-manager (openSUSE): importance Unknown Undecided
2009-10-30 12:39:53 Daniel de Souza Telles network-manager (openSUSE): status Unknown New
2009-10-30 12:39:53 Daniel de Souza Telles network-manager (openSUSE): remote watch Novell/SUSE Bugzilla #530430
2009-10-30 12:40:09 Daniel de Souza Telles network-manager (openSUSE): status New Fix Released
2009-10-30 15:53:34 dobee network-manager (Ubuntu): status Confirmed In Progress
2009-10-30 15:53:42 dobee network-manager (Ubuntu): status In Progress Confirmed
2009-10-30 16:51:07 Alexander Sack network-manager (openSUSE): status Fix Released Invalid
2009-10-30 17:00:53 Alexander Sack network-manager (Ubuntu): importance Undecided High
2009-10-30 17:00:53 Alexander Sack network-manager (Ubuntu): milestone karmic-updates
2009-10-30 17:00:53 Alexander Sack network-manager (Ubuntu): assignee Alexander Sack (asac)
2009-10-30 17:05:22 Alexander Sack bug watch removed https://bugzilla.novell.com/show_bug.cgi?id=530430
2009-10-30 18:27:13 Alexander Sack attachment added 1st attempt - allow updating secrets for state != NEED_AUTH http://launchpadlibrarian.net/34710401/lp432205.patch
2009-10-30 19:05:51 Alexander Sack network-manager (Ubuntu): status Confirmed Fix Committed
2009-10-30 19:07:08 Alexander Sack ppp (Ubuntu): status New Invalid
2009-10-30 19:26:08 Launchpad Janitor branch linked lp:~mathieu-tl/network-manager/fix-build-1306816-b
2009-10-30 19:37:45 lostprophet network-manager (Ubuntu): status Fix Committed Fix Released
2009-10-30 19:37:55 lostprophet network-manager (Ubuntu): status Fix Released Fix Committed
2009-10-31 13:59:04 Alexander Sack 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? NOTE: fixed packages are available here: https://edge.launchpad.net/~network-manager/+archive/trunk ===== 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?
2009-11-01 11:36:49 agitdd99 network-manager (Ubuntu): status Fix Committed Fix Released
2009-11-01 11:57:37 agitdd99 network-manager (Ubuntu): status Fix Released In Progress
2009-11-01 12:19:14 agitdd99 attachment added Screenshot-1.png http://launchpadlibrarian.net/34858792/Screenshot-1.png
2009-11-01 12:21:04 agitdd99 attachment added syslog network manager http://launchpadlibrarian.net/34858960/syslog%20network%20manager
2009-11-01 12:22:02 agitdd99 attachment added syslog on pppd http://launchpadlibrarian.net/34859026/syslog%20pppd
2009-11-01 12:23:42 agitdd99 attachment added syslog insufficient priviliges http://launchpadlibrarian.net/34859127/syslog%20insufficient%20priviliges
2009-11-01 17:55:42 Ymmot removed subscriber Ymmot
2009-11-02 11:47:35 Holger Schwolow removed subscriber Holger Schwolow
2009-11-02 13:55:46 Marius Vasilescu removed subscriber Marius Vasilescu
2009-11-03 11:08:09 Jurist C. M. Macabuhay network-manager (Ubuntu): status In Progress Fix Released
2009-11-03 15:12:48 Nicolai_J. network-manager (Ubuntu): status Fix Released In Progress
2009-11-04 01:03:24 Daniel de Souza Telles attachment added syslog_comment#78 http://launchpadlibrarian.net/35064513/syslog_comment%2378
2009-11-06 00:36:30 Alexander Sack network-manager (Ubuntu): status In Progress Fix Committed
2009-11-06 07:43:26 Nils Gladitz network-manager (Ubuntu): status Fix Committed Fix Released
2009-11-06 07:43:32 Nils Gladitz network-manager (Ubuntu): status Fix Released Fix Committed
2009-12-03 17:50:21 Launchpad Janitor branch linked lp:~mathieu-tl/network-manager/karmic-sru.lp432205
2009-12-08 04:55:20 Launchpad Janitor branch linked lp:~network-manager/network-manager/ubuntu.head
2009-12-11 21:55:59 zerwas network-manager (Ubuntu): status Fix Committed Fix Released
2009-12-11 22:03:15 zerwas network-manager (Ubuntu): status Fix Released Fix Committed
2010-01-12 18:05:47 Steve Magoun bug task added oem-priority
2010-01-20 22:00:14 Launchpad Janitor network-manager (Ubuntu): status Fix Committed Fix Released
2010-01-20 22:15:25 Launchpad Janitor branch linked lp:ubuntu/network-manager
2010-02-27 11:00:04 xavier@dehemptinne.net network-manager (Ubuntu): status Fix Released New
2010-02-27 14:09:14 Tony Espy network-manager (Ubuntu): status New Fix Released
2010-03-01 19:51:32 xavier@dehemptinne.net removed subscriber xavier@dehemptinne.net
2010-03-16 15:39:49 Madhav removed subscriber Madhav
2010-05-04 14:13:12 Nicolai_J. removed subscriber fireandfuel
2010-05-18 15:24:09 Jerone Young oem-priority: status New Fix Released
2010-05-18 17:00:41 Bill Brant removed subscriber Bill Brant