Public bug reported: Binary package hint: network-manager
Ubuntu Gutsy. I used to have this before. Something strange happens after some disconnects. I tell nm-applet to connect to a WiFi network. It starts connecting, it associates with the AP, then it gets an address from DHCP, and then it disconnects. Then either it starts again, or it locks itself. Below is an example snippet from /var/log/daemons.log. It connected several times (here's only the end). You can see at the start that it gets an address from DHCP, avahi-daemon does something weird, and then at 12:00:17 NM disconnects. This happened several times (ie, before where I cut the log), and at the end there's some weird message from NM. At this moment the nm-applet icon shows I'm connected (three of four bars), the correct network is shown, but in fact iwconfig says it's "unassociated" (though the ESSID is correct). Right now this happened after the situation in bug #164305, so there may be a link with restarting dbus, but I stress that something like this has happened to me before. Nov 21 12:00:14 arioch dhclient: DHCPDISCOVER on wan0 to 255.255.255.255 port 67 interval 7 Nov 21 12:00:16 arioch dhclient: DHCPOFFER from 192.168.1.254 Nov 21 12:00:16 arioch dhclient: DHCPREQUEST on wan0 to 255.255.255.255 port 67 Nov 21 12:00:16 arioch dhclient: DHCPACK from 192.168.1.254 Nov 21 12:00:16 arioch avahi-daemon[7421]: Joining mDNS multicast group on interface wan0.IPv4 with address 192.168.1.69. Nov 21 12:00:16 arioch avahi-daemon[7421]: New relevant interface wan0.IPv4 for mDNS. Nov 21 12:00:16 arioch avahi-daemon[7421]: Registering new address record for 192.168.1.69 on wan0.IPv4. Nov 21 12:00:16 arioch NetworkManager: <info> DHCP daemon state is now 2 (bound) for interface wan0 Nov 21 12:00:16 arioch NetworkManager: <info> Activation (wan0) Stage 4 of 5 (IP Configure Get) scheduled... Nov 21 12:00:16 arioch NetworkManager: <info> Activation (wan0) Stage 4 of 5 (IP Configure Get) started... Nov 21 12:00:16 arioch dhclient: bound to 192.168.1.69 -- renewal in 34472 seconds. Nov 21 12:00:16 arioch NetworkManager: <info> Retrieved the following IP4 configuration from the DHCP daemon: Nov 21 12:00:16 arioch NetworkManager: <info> address 192.168.1.69 Nov 21 12:00:16 arioch NetworkManager: <info> netmask 255.255.255.0 Nov 21 12:00:16 arioch NetworkManager: <info> broadcast 192.168.1.255 Nov 21 12:00:16 arioch NetworkManager: <info> gateway 192.168.1.254 Nov 21 12:00:16 arioch NetworkManager: <info> nameserver 192.168.1.254 Nov 21 12:00:16 arioch NetworkManager: <info> nameserver 208.67.222.222 Nov 21 12:00:16 arioch NetworkManager: <info> nameserver 208.67.220.220 Nov 21 12:00:16 arioch NetworkManager: <info> domain name 'lan' Nov 21 12:00:16 arioch NetworkManager: <info> Activation (wan0) Stage 5 of 5 (IP Configure Commit) scheduled... Nov 21 12:00:16 arioch NetworkManager: <info> Activation (wan0) Stage 4 of 5 (IP Configure Get) complete. Nov 21 12:00:16 arioch NetworkManager: <info> Activation (wan0) Stage 5 of 5 (IP Configure Commit) started... Nov 21 12:00:16 arioch avahi-daemon[7421]: Withdrawing address record for 192.168.1.69 on wan0. Nov 21 12:00:16 arioch avahi-daemon[7421]: Leaving mDNS multicast group on interface wan0.IPv4 with address 192.168.1.69. Nov 21 12:00:16 arioch avahi-daemon[7421]: Interface wan0.IPv4 no longer relevant for mDNS. Nov 21 12:00:16 arioch avahi-daemon[7421]: Withdrawing address record for fe80::218:deff:feb2:c880 on wan0. Nov 21 12:00:16 arioch avahi-daemon[7421]: Joining mDNS multicast group on interface wan0.IPv4 with address 192.168.1.69. Nov 21 12:00:16 arioch avahi-daemon[7421]: New relevant interface wan0.IPv4 for mDNS. Nov 21 12:00:16 arioch avahi-daemon[7421]: Registering new address record for 192.168.1.69 on wan0.IPv4. Nov 21 12:00:17 arioch NetworkManager: <info> Clearing nscd hosts cache. Nov 21 12:00:17 arioch NetworkManager: <WARN> nm_spawn_process(): nm_spawn_process('/usr/sbin/nscd -i hosts'): could not spawn process. (Failed to execute child process "/usr/sbin/nscd" (No such file or directory)) Nov 21 12:00:17 arioch NetworkManager: <info> Activation (wan0) successful, device activated. Nov 21 12:00:17 arioch NetworkManager: <info> Activation (wan0) Finish handler scheduled. Nov 21 12:00:17 arioch NetworkManager: <info> Activation (wan0) Stage 5 of 5 (IP Configure Commit) complete. Nov 21 12:00:17 arioch NetworkManager: <debug> [1195642817.136702] nm_dbus_signal_filter(): NetworkManagerInfo triggered update of wireless network 'SpeedTouch324B2B' Nov 21 12:00:17 arioch NetworkManager: <info> SWITCH: terminating current connection 'wan0' because it's no longer valid. Nov 21 12:00:17 arioch NetworkManager: <info> Deactivating device wan0. Nov 21 12:00:17 arioch dhclient: There is already a pid file /var/run/dhclient.wan0.pid with pid 8165 Nov 21 12:00:17 arioch dhclient: killed old client process, removed PID file Nov 21 12:00:17 arioch dhclient: DHCPRELEASE on wan0 to 192.168.1.254 port 67 Nov 21 12:00:17 arioch avahi-daemon[7421]: Withdrawing address record for 192.168.1.69 on wan0. Nov 21 12:00:17 arioch avahi-daemon[7421]: Leaving mDNS multicast group on interface wan0.IPv4 with address 192.168.1.69. Nov 21 12:00:17 arioch avahi-daemon[7421]: Interface wan0.IPv4 no longer relevant for mDNS. Nov 21 12:00:17 arioch ntpdate[8244]: can't find host ntp.ubuntu.com Nov 21 12:00:17 arioch ntpdate[8244]: no servers can be used, exiting Nov 21 12:00:18 arioch NetworkManager: <info> SUP: sending command 'DISABLE_NETWORK 0' Nov 21 12:00:18 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:18 arioch NetworkManager: <info> SUP: sending command 'AP_SCAN 0' Nov 21 12:00:18 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:18 arioch NetworkManager: <info> SUP: sending command 'TERMINATE' Nov 21 12:00:18 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:18 arioch NetworkManager: nm_device_is_802_3_ethernet: assertion `dev != NULL' failed Nov 21 12:00:18 arioch NetworkManager: nm_device_is_802_11_wireless: assertion `dev != NULL' failed Nov 21 12:00:18 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:00:30 arioch last message repeated 2 times Nov 21 12:00:33 arioch NetworkManager: <debug> [1195642833.900788] nm_device_802_11_wireless_get_activation_ap(): Forcing AP 'SpeedTouch324B2B' Nov 21 12:00:33 arioch NetworkManager: <info> User Switch: /org/freedesktop/NetworkManager/Devices/wan0 / SpeedTouch324B2B Nov 21 12:00:33 arioch NetworkManager: <info> Deactivating device wan0. Nov 21 12:00:33 arioch NetworkManager: <info> Device wan0 activation scheduled... Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) started... Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) Stage 1 of 5 (Device Prepare) scheduled... Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) Stage 1 of 5 (Device Prepare) started... Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) Stage 2 of 5 (Device Configure) scheduled... Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) Stage 1 of 5 (Device Prepare) complete. Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) Stage 2 of 5 (Device Configure) starting... Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0/wireless): access point 'SpeedTouch324B2B' is encrypted, but NO valid key exists. New key needed. Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) New wireless user key requested for network 'SpeedTouch324B2B'. Nov 21 12:00:33 arioch NetworkManager: <info> Activation (wan0) Stage 2 of 5 (Device Configure) complete. Nov 21 12:00:36 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:00:42 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0) New wireless user key for network 'SpeedTouch324B2B' received. Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0) Stage 1 of 5 (Device Prepare) scheduled... Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0) Stage 1 of 5 (Device Prepare) started... Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0) Stage 2 of 5 (Device Configure) scheduled... Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0) Stage 1 of 5 (Device Prepare) complete. Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0) Stage 2 of 5 (Device Configure) starting... Nov 21 12:00:45 arioch NetworkManager: <info> Activation (wan0/wireless): access point 'SpeedTouch324B2B' is encrypted, and a key exists. No new key needed. Nov 21 12:00:46 arioch NetworkManager: <info> supplicant_interface_init() - connect to global ctrl socket (0/10). Nov 21 12:00:46 arioch NetworkManager: <info> supplicant_interface_init() - connect to global ctrl socket (1/10). Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'INTERFACE_ADD wan0^I^Iwext^I/var/run/wpa_supplicant5^I' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> supplicant_init() - connect to device ctrl socket (1/10). Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'AP_SCAN 1' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'ADD_NETWORK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was '0' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'SET_NETWORK 0 ssid 5370656564546f756368333234423242' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'SET_NETWORK 0 proto WPA2' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'SET_NETWORK 0 key_mgmt WPA-PSK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'SET_NETWORK 0 psk <key>' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: sending command 'ENABLE_NETWORK 0' Nov 21 12:00:46 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:00:46 arioch NetworkManager: <info> Activation (wan0) Stage 2 of 5 (Device Configure) complete. Nov 21 12:00:48 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:00:54 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:00:59 arioch dhclient: Internet Systems Consortium DHCP Client V3.0.5 Nov 21 12:00:59 arioch dhclient: Copyright 2004-2006 Internet Systems Consortium. Nov 21 12:00:59 arioch dhclient: All rights reserved. Nov 21 12:00:59 arioch dhclient: For info, please visit http://www.isc.org/sw/dhcp/ Nov 21 12:00:59 arioch dhclient: Nov 21 12:01:00 arioch dhclient: Listening on LPF/wan0/00:18:de:b2:c8:80 Nov 21 12:01:00 arioch dhclient: Sending on LPF/wan0/00:18:de:b2:c8:80 Nov 21 12:01:00 arioch dhclient: Sending on Socket/fallback Nov 21 12:01:00 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:01:01 arioch dhclient: DHCPDISCOVER on wan0 to 255.255.255.255 port 67 interval 5 Nov 21 12:01:03 arioch NetworkManager: <info> Activation (wan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to access point 'SpeedTouch324B2B'. Nov 21 12:01:03 arioch NetworkManager: <info> Activation (wan0) Stage 3 of 5 (IP Configure Start) scheduled. Nov 21 12:01:03 arioch NetworkManager: <info> Activation (wan0) Stage 3 of 5 (IP Configure Start) started... Nov 21 12:01:04 arioch NetworkManager: <info> Activation (wan0) Beginning DHCP transaction. Nov 21 12:01:04 arioch NetworkManager: <info> Activation (wan0) Stage 3 of 5 (IP Configure Start) complete. Nov 21 12:01:04 arioch dhclient: There is already a pid file /var/run/dhclient.wan0.pid with pid 134519120 Nov 21 12:01:04 arioch NetworkManager: <info> DHCP daemon state is now 12 (successfully started) for interface wan0 Nov 21 12:01:05 arioch avahi-daemon[7421]: Registering new address record for fe80::218:deff:feb2:c880 on wan0.*. Nov 21 12:01:05 arioch NetworkManager: <info> DHCP daemon state is now 1 (starting) for interface wan0 Nov 21 12:01:06 arioch dhclient: DHCPDISCOVER on wan0 to 255.255.255.255 port 67 interval 7 Nov 21 12:01:06 arioch NetworkManager: <info> Error getting killswitch power: org.freedesktop.Hal.Device.KillSwitch.NotSupported - Access type not supported Nov 21 12:01:08 arioch dhclient: DHCPOFFER from 192.168.1.254 Nov 21 12:01:08 arioch dhclient: DHCPREQUEST on wan0 to 255.255.255.255 port 67 Nov 21 12:01:08 arioch dhclient: DHCPACK from 192.168.1.254 Nov 21 12:01:08 arioch avahi-daemon[7421]: Joining mDNS multicast group on interface wan0.IPv4 with address 192.168.1.73. Nov 21 12:01:08 arioch avahi-daemon[7421]: New relevant interface wan0.IPv4 for mDNS. Nov 21 12:01:08 arioch avahi-daemon[7421]: Registering new address record for 192.168.1.73 on wan0.IPv4. Nov 21 12:01:08 arioch dhclient: bound to 192.168.1.73 -- renewal in 32651 seconds. Nov 21 12:01:09 arioch dhclient: DHCPDISCOVER on wan0 to 255.255.255.255 port 67 interval 6 Nov 21 12:01:09 arioch dhclient: DHCPOFFER from 192.168.1.254 Nov 21 12:01:09 arioch dhclient: DHCPREQUEST on wan0 to 255.255.255.255 port 67 Nov 21 12:01:09 arioch dhclient: DHCPACK from 192.168.1.254 Nov 21 12:01:09 arioch avahi-daemon[7421]: Withdrawing address record for 192.168.1.73 on wan0. Nov 21 12:01:09 arioch avahi-daemon[7421]: Leaving mDNS multicast group on interface wan0.IPv4 with address 192.168.1.73. Nov 21 12:01:09 arioch avahi-daemon[7421]: Interface wan0.IPv4 no longer relevant for mDNS. Nov 21 12:01:09 arioch avahi-daemon[7421]: Joining mDNS multicast group on interface wan0.IPv4 with address 192.168.1.73. Nov 21 12:01:09 arioch avahi-daemon[7421]: New relevant interface wan0.IPv4 for mDNS. Nov 21 12:01:09 arioch avahi-daemon[7421]: Registering new address record for 192.168.1.73 on wan0.IPv4. Nov 21 12:01:09 arioch dhclient: bound to 192.168.1.73 -- renewal in 34010 seconds. Nov 21 12:01:09 arioch NetworkManager: <info> DHCP daemon state is now 2 (bound) for interface wan0 Nov 21 12:01:09 arioch NetworkManager: <info> Activation (wan0) Stage 4 of 5 (IP Configure Get) scheduled... Nov 21 12:01:09 arioch NetworkManager: <info> Activation (wan0) Stage 4 of 5 (IP Configure Get) started... Nov 21 12:01:10 arioch NetworkManager: <info> Retrieved the following IP4 configuration from the DHCP daemon: Nov 21 12:01:10 arioch NetworkManager: <info> address 192.168.1.73 Nov 21 12:01:10 arioch NetworkManager: <info> netmask 255.255.255.0 Nov 21 12:01:10 arioch NetworkManager: <info> broadcast 192.168.1.255 Nov 21 12:01:10 arioch NetworkManager: <info> gateway 192.168.1.254 Nov 21 12:01:10 arioch NetworkManager: <info> nameserver 192.168.1.254 Nov 21 12:01:10 arioch NetworkManager: <info> nameserver 208.67.222.222 Nov 21 12:01:10 arioch NetworkManager: <info> nameserver 208.67.220.220 Nov 21 12:01:10 arioch NetworkManager: <info> domain name 'lan' Nov 21 12:01:10 arioch NetworkManager: <info> Activation (wan0) Stage 5 of 5 (IP Configure Commit) scheduled... Nov 21 12:01:10 arioch NetworkManager: <info> Activation (wan0) Stage 4 of 5 (IP Configure Get) complete. Nov 21 12:01:10 arioch NetworkManager: <info> Activation (wan0) Stage 5 of 5 (IP Configure Commit) started... Nov 21 12:01:10 arioch avahi-daemon[7421]: Withdrawing address record for 192.168.1.73 on wan0. Nov 21 12:01:10 arioch avahi-daemon[7421]: Leaving mDNS multicast group on interface wan0.IPv4 with address 192.168.1.73. Nov 21 12:01:10 arioch avahi-daemon[7421]: Interface wan0.IPv4 no longer relevant for mDNS. Nov 21 12:01:10 arioch avahi-daemon[7421]: Withdrawing address record for fe80::218:deff:feb2:c880 on wan0. Nov 21 12:01:10 arioch avahi-daemon[7421]: Joining mDNS multicast group on interface wan0.IPv4 with address 192.168.1.73. Nov 21 12:01:10 arioch avahi-daemon[7421]: New relevant interface wan0.IPv4 for mDNS. Nov 21 12:01:10 arioch avahi-daemon[7421]: Registering new address record for 192.168.1.73 on wan0.IPv4. Nov 21 12:01:11 arioch NetworkManager: <info> Clearing nscd hosts cache. Nov 21 12:01:11 arioch NetworkManager: <WARN> nm_spawn_process(): nm_spawn_process('/usr/sbin/nscd -i hosts'): could not spawn process. (Failed to execute child process "/usr/sbin/nscd" (No such file or directory)) Nov 21 12:01:11 arioch NetworkManager: <info> Activation (wan0) Finish handler scheduled. Nov 21 12:01:11 arioch NetworkManager: <info> Activation (wan0) Stage 5 of 5 (IP Configure Commit) complete. Nov 21 12:01:11 arioch NetworkManager: <info> Activation (wan0) successful, device activated. Nov 21 12:01:11 arioch NetworkManager: <debug> [1195642871.145675] nm_dbus_signal_filter(): NetworkManagerInfo triggered update of wireless network 'SpeedTouch324B2B' Nov 21 12:01:11 arioch NetworkManager: <info> SWITCH: terminating current connection 'wan0' because it's no longer valid. Nov 21 12:01:11 arioch NetworkManager: <info> Deactivating device wan0. Nov 21 12:01:11 arioch dhclient: There is already a pid file /var/run/dhclient.wan0.pid with pid 8389 Nov 21 12:01:11 arioch dhclient: killed old client process, removed PID file Nov 21 12:01:11 arioch dhclient: DHCPRELEASE on wan0 to 192.168.1.254 port 67 Nov 21 12:01:11 arioch avahi-daemon[7421]: Withdrawing address record for 192.168.1.73 on wan0. Nov 21 12:01:11 arioch avahi-daemon[7421]: Leaving mDNS multicast group on interface wan0.IPv4 with address 192.168.1.73. Nov 21 12:01:11 arioch avahi-daemon[7421]: Interface wan0.IPv4 no longer relevant for mDNS. Nov 21 12:01:11 arioch ntpdate[8479]: can't find host ntp.ubuntu.com Nov 21 12:01:11 arioch ntpdate[8479]: no servers can be used, exiting Nov 21 12:01:12 arioch NetworkManager: <info> SUP: sending command 'DISABLE_NETWORK 0' Nov 21 12:01:12 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:01:12 arioch NetworkManager: <info> SUP: sending command 'AP_SCAN 0' Nov 21 12:01:12 arioch NetworkManager: <info> SUP: response was 'OK' Nov 21 12:01:12 arioch NetworkManager: <info> SUP: sending command 'TERMINATE' Nov 21 12:01:12 arioch avahi-daemon[7421]: Registering new address record for fe80::218:deff:feb2:c880 on wan0.*. Nov 21 12:01:13 arioch avahi-daemon[7421]: Withdrawing address record for fe80::218:deff:feb2:c880 on wan0. Nov 21 12:01:13 arioch dhclient: receive_packet failed on wan0: Network is down Nov 21 12:01:24 arioch NetworkManager: <info> SUP: response was 'TIMEOUT[CLI]' Nov 21 12:01:24 arioch NetworkManager: <WARN> nm_signal_handler(): Caught signal 11. Generating backtrace... Nov 21 12:01:24 arioch NetworkManager: ******************* START ********************************** Nov 21 12:01:24 arioch NetworkManager: (no debugging symbols found) Nov 21 12:01:24 arioch NetworkManager: Using host libthread_db library "/lib/tls/i686/cmov/libthread_db.so.1". Nov 21 12:01:24 arioch NetworkManager: (no debugging symbols found) Nov 21 12:01:24 arioch last message repeated 12 times Nov 21 12:01:24 arioch NetworkManager: [Thread debugging using libthread_db enabled] Nov 21 12:01:24 arioch NetworkManager: [New Thread -1212676432 (LWP 7490)] Nov 21 12:01:24 arioch NetworkManager: [New Thread -1221071984 (LWP 7529)] Nov 21 12:01:24 arioch NetworkManager: [New Thread -1212679280 (LWP 7498)] Nov 21 12:01:24 arioch NetworkManager: (no debugging symbols found) Nov 21 12:01:24 arioch last message repeated 4 times Nov 21 12:01:24 arioch NetworkManager: 0xffffe410 in __kernel_vsyscall () Nov 21 12:01:24 arioch NetworkManager: ******************* END ************************ ** Affects: network-manager (Ubuntu) Importance: Undecided Status: New -- NetworkManager stuck in connect/disconnect loop https://bugs.launchpad.net/bugs/164306 You received this bug notification because you are a member of Ubuntu Bugs, which is the bug contact for Ubuntu. -- ubuntu-bugs mailing list ubuntu-bugs@lists.ubuntu.com https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs