[Pkg-utopia-maintainers] Bug#478819: network-manager fails to connect to open WLAN

Stephen Bohler sboehler2006 at gmail.com
Thu May 1 09:50:47 UTC 2008


Package: network-manager
Version: 0.6.6-1


When trying to connect wlan0 to an open WLAN (through nm-applet),
network-manager times out and switches back to eth0 after some time:

stephen at buendia:~$ groups
stephen dialout cdrom floppy sudo audio video plugdev netdev powerdev

stephen at buendia:~$ uname -r
2.6.24-1-amd64

stephen at buendia:~$ lspci | grep Network
00:19.0 Ethernet controller: Intel Corporation 82566MM Gigabit Network
Connection (rev 03)
10:00.0 Network controller: Intel Corporation PRO/Wireless 4965 AG or
AGN Network Connection (rev 61)

stephen at buendia:~$ lsmod | grep iw
iwl4965               111848  0
firmware_class         15104  2 pcmcia,iwl4965
mac80211              132236  1 iwl4965

stephen at buendia:~$ sudo tail -f /var/log/daemon.log
May  1 11:18:29 buendia acpid: received event "thermal_zone TZ2
00000081 00000000"
May  1 11:18:29 buendia acpid: notifying client 3196[106:111]
May  1 11:18:29 buendia acpid: notifying client 3290[0:0]
May  1 11:18:29 buendia acpid: completed event "thermal_zone TZ2
00000081 00000000"
May  1 11:18:40 buendia NetworkManager: <info>  Updating allowed
wireless network lists.
May  1 11:18:40 buendia NetworkManager: <WARN>
nm_dbus_get_networks_cb(): error received:
org.freedesktop.NetworkManagerInfo.NoNetworks - There are no wireless
networks stored..
May  1 11:18:43 buendia acpid: received event "thermal_zone TZ1
00000081 00000000"
May  1 11:18:43 buendia acpid: notifying client 3196[106:111]
May  1 11:18:43 buendia acpid: notifying client 3290[0:0]
May  1 11:18:43 buendia acpid: completed event "thermal_zone TZ1
00000081 00000000"
May  1 11:19:52 buendia NetworkManager: <debug> [1209633592.788190]
nm_device_802_11_wireless_get_activation_ap(): Forcing AP 'mywlan'
May  1 11:19:52 buendia NetworkManager: <info>  User Switch:
/org/freedesktop/NetworkManager/Devices/wlan0 / mywlan
May  1 11:19:52 buendia NetworkManager: <info>  Deactivating device wlan0.
May  1 11:19:52 buendia NetworkManager: <info>  Device wlan0
activation scheduled...
May  1 11:19:52 buendia NetworkManager: <info>  Deactivating device eth0.
May  1 11:19:52 buendia dhclient: There is already a pid file
/var/run/dhclient.eth0.pid with pid 3315
May  1 11:19:52 buendia dhclient: killed old client process, removed PID file
May  1 11:19:52 buendia dhclient: wmaster0: unknown hardware address type 801
May  1 11:19:52 buendia dhclient: wmaster0: unknown hardware address type 801
May  1 11:19:52 buendia dhclient: DHCPRELEASE on eth0 to 192.168.1.1 port 67
May  1 11:19:52 buendia avahi-daemon[3145]: Withdrawing address record
for 192.168.1.7 on eth0.
May  1 11:19:52 buendia avahi-daemon[3145]: Leaving mDNS multicast
group on interface eth0.IPv4 with address 192.168.1.7.
May  1 11:19:52 buendia avahi-daemon[3145]: Interface eth0.IPv4 no
longer relevant for mDNS.
May  1 11:19:53 buendia avahi-daemon[3145]: Withdrawing address record
for fe80::21a:4bff:fe7e:342c on eth0.
May  1 11:19:53 buendia avahi-daemon[3145]: Leaving mDNS multicast
group on interface eth0.IPv6 with address fe80::21a:4bff:fe7e:342c.
May  1 11:19:53 buendia avahi-daemon[3145]: Interface eth0.IPv6 no
longer relevant for mDNS.
May  1 11:19:53 buendia NetworkManager: <info>  Activation (wlan0) started...
May  1 11:19:53 buendia NetworkManager: <info>  Activation (wlan0)
Stage 1 of 5 (Device Prepare) scheduled...
May  1 11:19:53 buendia NetworkManager: <info>  Activation (wlan0)
Stage 1 of 5 (Device Prepare) started...
May  1 11:19:53 buendia NetworkManager: <info>  Activation (wlan0)
Stage 2 of 5 (Device Configure) scheduled...
May  1 11:19:53 buendia NetworkManager: <info>  Activation (wlan0)
Stage 1 of 5 (Device Prepare) complete.
May  1 11:19:53 buendia NetworkManager: <info>  Activation (wlan0)
Stage 2 of 5 (Device Configure) starting...
May  1 11:19:53 buendia NetworkManager: <info>  Activation
(wlan0/wireless): access point 'mywlan' is unencrypted, no key needed.
May  1 11:19:55 buendia NetworkManager: <info>  SUP: sending command
'INTERFACE_ADD wlan0^I^Iwext^I/var/run/wpa_supplicant^I'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: response was 'OK'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: sending command
'AP_SCAN 1'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: response was 'OK'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: sending command
'ADD_NETWORK'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: response was '0'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: sending command
'SET_NETWORK 0 ssid 333631312031333434'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: response was 'OK'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: sending command
'SET_NETWORK 0 key_mgmt NONE'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: response was 'OK'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: sending command
'ENABLE_NETWORK 0'
May  1 11:19:55 buendia NetworkManager: <info>  SUP: response was 'OK'
May  1 11:19:55 buendia NetworkManager: <info>  Activation (wlan0)
Stage 2 of 5 (Device Configure) complete.
May  1 11:19:57 buendia NetworkManager: <info>  Old device 'wlan0'
activating, won't change.
May  1 11:20:02 buendia NetworkManager: <info>  Old device 'wlan0'
activating, won't change.
May  1 11:21:55 buendia NetworkManager: <info>  Activation
(wlan0/wireless): association took too long (>120s), failing
activation.
May  1 11:21:55 buendia NetworkManager: <info>  Activation (wlan0)
failure scheduled...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (wlan0)
failed for access point (mywlan)
May  1 11:21:55 buendia NetworkManager: <info>  Activation (wlan0) failed.
May  1 11:21:55 buendia NetworkManager: <info>  Deactivating device wlan0.
May  1 11:21:55 buendia NetworkManager: <info>  SWITCH: no current
connection, found better connection 'eth0'.
May  1 11:21:55 buendia NetworkManager: <info>  Will activate
connection 'eth0'.
May  1 11:21:55 buendia NetworkManager: <info>  Device eth0 activation
scheduled...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0) started...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 1 of 5 (Device Prepare) scheduled...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 1 of 5 (Device Prepare) started...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 2 of 5 (Device Configure) scheduled...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 1 of 5 (Device Prepare) complete.
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 2 of 5 (Device Configure) starting...
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 2 of 5 (Device Configure) successful.
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 3 of 5 (IP Configure Start) scheduled.
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 2 of 5 (Device Configure) complete.
May  1 11:21:55 buendia NetworkManager: <info>  Activation (eth0)
Stage 3 of 5 (IP Configure Start) started...
May  1 11:21:56 buendia NetworkManager: <info>  Activation (eth0)
Beginning DHCP transaction.
May  1 11:21:56 buendia NetworkManager: <info>  Activation (eth0)
Stage 3 of 5 (IP Configure Start) complete.
May  1 11:21:56 buendia NetworkManager: <info>  DHCP daemon state is
now 12 (successfully started) for interface eth0
May  1 11:21:56 buendia dhclient: wmaster0: unknown hardware address type 801
May  1 11:21:57 buendia NetworkManager: <info>  DHCP daemon state is
now 1 (starting) for interface eth0
May  1 11:21:57 buendia dhclient: wmaster0: unknown hardware address type 801
May  1 11:21:58 buendia dhclient: DHCPDISCOVER on eth0 to
255.255.255.255 port 67 interval 4
May  1 11:21:59 buendia dhclient: DHCPOFFER from 192.168.1.1
May  1 11:21:59 buendia dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
May  1 11:22:01 buendia dhclient: DHCPACK from 192.168.1.1
May  1 11:22:01 buendia avahi-daemon[3145]: Joining mDNS multicast
group on interface eth0.IPv4 with address 192.168.1.7.
May  1 11:22:01 buendia avahi-daemon[3145]: New relevant interface
eth0.IPv4 for mDNS.
May  1 11:22:01 buendia avahi-daemon[3145]: Registering new address
record for 192.168.1.7 on eth0.IPv4.
May  1 11:22:01 buendia dhclient: bound to 192.168.1.7 -- renewal in
41111 seconds.
May  1 11:22:01 buendia NetworkManager: <info>  DHCP daemon state is
now 2 (bound) for interface eth0
May  1 11:22:01 buendia NetworkManager: <info>  Activation (eth0)
Stage 4 of 5 (IP Configure Get) scheduled...
May  1 11:22:01 buendia NetworkManager: <info>  Activation (eth0)
Stage 4 of 5 (IP Configure Get) started...
May  1 11:22:01 buendia NetworkManager: <info>  Retrieved the
following IP4 configuration from the DHCP daemon:
May  1 11:22:01 buendia NetworkManager: <info>    address 192.168.1.7
May  1 11:22:01 buendia NetworkManager: <info>    netmask 255.255.255.0
May  1 11:22:01 buendia NetworkManager: <info>    broadcast 192.168.1.255
May  1 11:22:01 buendia NetworkManager: <info>    gateway 192.168.1.1
May  1 11:22:01 buendia NetworkManager: <info>    nameserver 192.168.1.1
May  1 11:22:01 buendia NetworkManager: <info>  Activation (eth0)
Stage 5 of 5 (IP Configure Commit) scheduled...
May  1 11:22:01 buendia NetworkManager: <info>  Activation (eth0)
Stage 4 of 5 (IP Configure Get) complete.
May  1 11:22:01 buendia NetworkManager: <info>  Activation (eth0)
Stage 5 of 5 (IP Configure Commit) started...
May  1 11:22:01 buendia avahi-daemon[3145]: Withdrawing address record
for 192.168.1.7 on eth0.
May  1 11:22:01 buendia avahi-daemon[3145]: Leaving mDNS multicast
group on interface eth0.IPv4 with address 192.168.1.7.
May  1 11:22:01 buendia avahi-daemon[3145]: Interface eth0.IPv4 no
longer relevant for mDNS.
May  1 11:22:01 buendia avahi-daemon[3145]: Joining mDNS multicast
group on interface eth0.IPv4 with address 192.168.1.7.
May  1 11:22:01 buendia avahi-daemon[3145]: New relevant interface
eth0.IPv4 for mDNS.
May  1 11:22:01 buendia avahi-daemon[3145]: Registering new address
record for 192.168.1.7 on eth0.IPv4.
May  1 11:22:02 buendia NetworkManager: <info>  DHCP returned name
servers but system has disabled dynamic modification!
May  1 11:22:02 buendia NetworkManager: <info>  Activation (eth0)
Finish handler scheduled.
May  1 11:22:02 buendia NetworkManager: <info>  Activation (eth0)
Stage 5 of 5 (IP Configure Commit) complete.
May  1 11:22:02 buendia NetworkManager: <info>  Activation (eth0)
successful, device activated.
May  1 11:22:03 buendia avahi-daemon[3145]: Joining mDNS multicast
group on interface eth0.IPv6 with address fe80::21a:4bff:fe7e:342c.
May  1 11:22:03 buendia avahi-daemon[3145]: New relevant interface
eth0.IPv6 for mDNS.
May  1 11:22:03 buendia avahi-daemon[3145]: Registering new address
record for fe80::21a:4bff:fe7e:342c on eth0.*.


Configuring it manually works as expected:


stephen at buendia:~$ sudo ifconfig wlan0 up
stephen at buendia:~$ sudo iwconfig wlan0 essid "mywlan"
stephen at buendia:~$ sudo iwconfig wlan0 ap auto
stephen at buendia:~$ sudo dhclient wlan0
Internet Systems Consortium DHCP Client V3.1.0
Copyright 2004-2007 Internet Systems Consortium.
All rights reserved.
For info, please visit http://www.isc.org/sw/dhcp/

wmaster0: unknown hardware address type 801
wmaster0: unknown hardware address type 801
Listening on LPF/wlan0/00:1f:3b:13:c2:4f
Sending on   LPF/wlan0/00:1f:3b:13:c2:4f
Sending on   Socket/fallback
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
DHCPACK from 192.168.1.1
bound to 192.168.1.63 -- renewal in 41105 seconds.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.alioth.debian.org/pipermail/pkg-utopia-maintainers/attachments/20080501/53809ffb/attachment-0001.htm 


More information about the Pkg-utopia-maintainers mailing list