[pkg-wpa-devel] Bug#459678: network-manager can't connect to my wireless network, it's dropped whet trying to associate with router (timed out).

Stefan Lippers-Hollmann s.L-H at gmx.de
Mon Sep 26 16:19:53 UTC 2011 

tags 459678 + moreinfo
thanks

Hi

On Monday 26 September 2011, Artyom Loenko wrote:
> Package: network-manager
> Version: 0.6.5-3
> Severity: grave
> Justification: renders package unusable
> 
> network-manager can't connect to my wireless network, it's dropped whet trying to associate with router (timed out). my network without any 
> encryption and router allow connect from MAC address. when i tried to 'iwconfig eth2 essid "my_net"' and then 'dhclient eth2' - network works 
> perfectly.
> 
> Network controller: Intel Corporation PRO/Wireless 3945ABG Network Connection (rev 02)
> 
> dive:/home/dive# lsmod | grep 3945
> ipw3945               192740  1 
> ieee80211              31656  1 ipw3945
> firmware_class          9504  2 pcmcia,ipw3945
> 
> some log's info:
> dive:/home/dive# NetworkManager --no-daemon
> NetworkManager: <info>  starting...
> NetworkManager: <info>  New VPN service 'openvpn' (org.freedesktop.NetworkManager.openvpn).
> NetworkManager: <info>  New VPN service 'vpnc' (org.freedesktop.NetworkManager.vpnc).
> NetworkManager: <info>  Found radio killswitch /org/freedesktop/Hal/devices/ipw_wlan_switch
> NetworkManager: <info>  eth2: Device is fully-supported using driver 'ipw3945'.
> NetworkManager: <info>  nm_device_init(): waiting for device's worker thread to start
> NetworkManager: <info>  nm_device_init(): device's worker thread started, continuing.
> NetworkManager: <info>  Now managing wireless (802.11) device 'eth2'.
> NetworkManager: <info>  Deactivating device eth2.
> NetworkManager: <info>  eth1: Device is fully-supported using driver 'sky2'.
> NetworkManager: <info>  nm_device_init(): waiting for device's worker thread to start
> NetworkManager: <info>  nm_device_init(): device's worker thread started, continuing.
> NetworkManager: <info>  Now managing wired Ethernet (802.3) device 'eth1'.
> NetworkManager: <info>  Deactivating device eth1.
> NetworkManager: <info>  Updating allowed wireless network lists.
> NetworkManager: <info>  Wireless now enabled by radio killswitch
> NetworkManager: <info>  SWITCH: no current connection, found better connection 'eth2'.
> NetworkManager: <info>  Will activate connection 'eth2/divenet'.
> NetworkManager: <info>  Device eth2 activation scheduled...
> NetworkManager: <info>  Activation (eth2) started...
> NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
> NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) started...
> NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) scheduled...
> NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) complete.
> NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) starting...
> NetworkManager: <info>  Activation (eth2/wireless): access point 'divenet' is unencrypted, no key needed.
> NetworkManager: <info>  SUP: sending command 'INTERFACE_ADD eth2		wext	/var/run/wpa_supplicant	'
> NetworkManager: <info>  SUP: response was 'OK'
> NetworkManager: <info>  SUP: sending command 'AP_SCAN 1'
> NetworkManager: <info>  SUP: response was 'OK'
> NetworkManager: <info>  SUP: sending command 'ADD_NETWORK'
> NetworkManager: <info>  SUP: response was '0'
> NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 ssid 646976656e6574'
> NetworkManager: <info>  SUP: response was 'OK'
> NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 key_mgmt NONE'
> NetworkManager: <info>  SUP: response was 'OK'
> NetworkManager: <info>  SUP: sending command 'ENABLE_NETWORK 0'
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> NetworkManager: <info>  SUP: response was 'OK'
> NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) complete.
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> Authentication with 00:00:00:00:00:00 timed out.
> Trying to associate with 00:13:49:e1:f4:4c (SSID='divenet' freq=2432 MHz)
> NetworkManager: <WARN>  nm_signal_handler(): Caught signal 2, shutting down normally.
> NetworkManager: <info>  Caught terminiation signal
> NetworkManager: <debug> [1199756624.895513] nm_print_open_socks(): Open Sockets List:
> NetworkManager: <debug> [1199756624.895578] nm_print_open_socks(): Open Sockets List Done.
> NetworkManager: <info>  Deactivating device eth2.
> NetworkManager: <info>  Activation (eth2): cancelling...
> NetworkManager: <info>  Activation (eth2) cancellation handler scheduled...
> NetworkManager: <info>  Activation (eth2): waiting for device to cancel activation.
> NetworkManager: <info>  Activation (eth2) cancellation handled.
> NetworkManager: <info>  Activation (eth2): cancelled.
> CTRL-EVENT-TERMINATING - signal 15 received
> sendmsg(CTRL_IFACE monitor): No such file or directory
> NetworkManager: <info>  Deactivating device eth1.
> 
> dive:/home/dive# dhclient eth2
> There is already a pid file /var/run/dhclient.pid with pid 4170
> killed old client process, removed PID file
> Internet Systems Consortium DHCP Client V3.0.6
> Copyright 2004-2007 Internet Systems Consortium.
> All rights reserved.
> For info, please visit http://www.isc.org/sw/dhcp/
> 
> Listening on LPF/eth2/00:19:d2:31:15:ca
> Sending on   LPF/eth2/00:19:d2:31:15:ca
> Sending on   Socket/fallback
> DHCPREQUEST on eth2 to 255.255.255.255 port 67
> DHCPACK from 192.168.1.1
> bound to 192.168.1.35 -- renewal in 116066 seconds.
> 
> dive:/home/dive# tail -n0 -f /var/log/syslog > /tmp/syslog
> ....
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2) started... 
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled... 
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) started... 
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) scheduled... 
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) complete. 
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) starting... 
> Jan  8 05:11:29 dive NetworkManager: <info>  Activation (eth2/wireless): access point 'divenet' is unencrypted, no key needed. 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: sending command 'INTERFACE_ADD eth2^I^Iwext^I/var/run/wpa_supplicant^I' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: response was 'OK' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: sending command 'AP_SCAN 1' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: response was 'OK' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: sending command 'ADD_NETWORK' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: response was '0' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 ssid 646976656e6574' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: response was 'OK' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 key_mgmt NONE' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: response was 'OK' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: sending command 'ENABLE_NETWORK 0' 
> Jan  8 05:11:30 dive NetworkManager: <info>  SUP: response was 'OK' 
> Jan  8 05:11:30 dive NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) complete. 
> ....
> and then silent... without any message and without connect.
> 
> -- System Information:
> Debian Release: lenny/sid
>   APT prefers testing
>   APT policy: (500, 'testing')
> Architecture: i386 (i686)
> 
> Kernel: Linux 2.6.22-3-686 (SMP w/2 CPU cores)
> Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
> Shell: /bin/sh linked to /bin/bash
[...]

Given that this bug is more than 3 years old - and assuming that 
iwl3945 has seen lots of changes since 2.6.22 (or rather that it 
replaced ipw3945/ ipw3945d) and both network-manager and wpasupplicant 
changing their dbus interface meanwhile, I'd like to ask if this is 
still an issue in squeeze or newer?

Regards
	Stefan Lippers-Hollmann

More information about the Pkg-wpa-devel mailing list