wpa_supplicant: Problems associating accesspoint

Berthold Cogel cogel at rrz.uni-koeln.de
Wed Nov 1 20:17:39 EST 2006


Hello!

I'm trying to associate to a Cisco Arionet 1231 (SSID tsunami, WEP key)
access point using wpa_supplicant. While manual association is no
problem, association with wpa_supplicant to this WLAN doesn't work.
Association with other WEP encrypted networks is not a problem.

My system:

Debian unstable/testing with homemade (vanilla) kernel linux-2.6.18,
patched with ieee80211-1.2.15, suspend 2 and with the ipw2200-1.2.0
modules, wpasupplicant (0.5.5-2).

The results are the same for the unpatched linux-2.6.18.


/etc/modprobe.d/ipw2200:

options ipw2200 associate=0 led=1 hwcrypto=0 debug=0x43fff


wpa_supplicant.conf:

ctrl_interface=/var/run/wpa_supplicant
ctrl_interface_group=0
eapol_version=1
ap_scan=1
fast_reauth=1

network={
	# ssid hidden
	ssid="ringworld"
	id_str="home_static"
	scan_ssid=1
        key_mgmt=NONE
	auth_alg=SHARED
	wep_key0=DONTKNOW
	wep_tx_keyidx=0
}

network={
	ssid="tsunami"
	id_str="uni"
	key_mgmt=NONE
	auth_alg=SHARED
	wep_key0=DONTSAY
	wep_tx_keyidx=0
}


'iwlist eth1 scanning':

eth1      Scan completed :
          Cell 01 - Address: 00:0E:84:BE:31:60
                    ESSID:"tsunami"
                    Protocol:IEEE 802.11bg
                    Mode:Master
                    Channel:9
                    Encryption key:on
                    Bit Rates:1 Mb/s; 2 Mb/s; 5.5 Mb/s; 6 Mb/s; 9 Mb/s
                              11 Mb/s; 12 Mb/s; 18 Mb/s; 24 Mb/s; 36 Mb/s
                              48 Mb/s; 54 Mb/s
                    Quality=55/100  Signal level=-68 dBm
                    Extra: Last beacon: 4ms ago
          Cell 02 - Address: 00:40:96:34:F2:2C
                    ESSID:"tsunami"
                    Protocol:IEEE 802.11b
                    Mode:Master
                    Channel:1
                    Encryption key:on
                    Bit Rates:1 Mb/s; 2 Mb/s; 5.5 Mb/s; 11 Mb/s
                    Quality=27/100  Signal level=-83 dBm
                    Extra: Last beacon: 3124ms ago


Association with 'wpa_supplicant -ieth1 -Dwext
-c/etc/wpa_supplicant/wpa_supplicant.conf -dd'
gives this result:

(see the complete log: http://www.uni-koeln.de/~a0537/wpalog.txt)


First SSID 'ringworld' is scanned and discarded. Then wpa_supplicant
scans for other networks, finds SSID 'tsunami' and tries to associate.

Both BSSIDs get skipped (skip - no WPA/RSN IE) and wpa_supplicant tells
me that it is 'Already associated with the selected AP'.
Then BSSID '00:0e:84:be:31:60' is added to a blacklist and after some
additional attempts skipped because it is blacklisted. Now the second
BSSID is blacklisted and skipped because of this reason.
It starts all over again: 'No APs found - clear blacklist and try again'.

What I see in the debug output of the ipw driver something like this:

...

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: I ipw_rx_notification type = 20 (104 bytes)
ipw2200: I ipw_rx_notification TODO: Calibration
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 1
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 2
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 3
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 4
ipw2200: U ipw_wx_get_name Name: unassociated
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 5
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 6
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 7
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 8
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 9
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 10
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 11
ipw2200: I ipw_rx_notification type = 13 (4 bytes)
ipw2200: I ipw_rx_notification Scan completed: type 11, 11 channels, 1
status
ipw2200: U ipw_associate Not attempting association (associate=0)
ipw2200: U ipw_wx_set_mode Set MODE: 2
ipw2200: U ipw_wx_set_essid Setting ESSID: 'tsunami' (7)
ipw2200: U ipw_wx_set_essid [re]association triggered due to ESSID change.
ipw2200: U ipw_best_network Network 'tsunami (00:40:96:34:f2:2c)' is a
viable match.
ipw2200: U ipw_best_network Network 'tsunami (00:0e:84:be:31:60)' is a
viable match.
ipw2200: U __ipw_send_cmd WEP_KEY command (#18) 20 bytes: 0x80000023
ipw2200: U __ipw_send_cmd WEP_KEY command masked out for secure.
ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U ipw_associate_network Assocation attempt: 'tsunami', channel
9, 802.11g [12], short[:short], enc=on (shared) key=1.
ipw2200: U __ipw_send_cmd SSID command (#8) 7 bytes: 0x80000023
00000000 74 73 75 6E 61 6D 69                               tsunami

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd SUPPORTED_RATES command (#22) 16 bytes: 0x80000023
00000000 02 0C 00 00 82 04 0B 0C  12 16 18 24 30 48 60 6C   ........
...$0H`l
ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd SYSTEM_CONFIG command (#6) 20 bytes: 0x80000023
00000000 00 00 00 00 01 00 01 00  01 00 00 01 00 00 00 1E   ........
........
00000010 00 00 01 00                                        ....

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U ipw_associate_network Association sensitivity: -72
ipw2200: U __ipw_send_cmd SENSITIVITY_CALIB command (#42) 4 bytes:
0x80000023
00000000 28 00 00 00                                        (...

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd ASSOCIATE command (#21) 40 bytes: 0x00000123
00000000 09 01 00 00 00 00 04 02  00 0E 84 BE 31 60 62 06   ........
....1`b.
00000010 00 00 92 D1 A6 20 31 0C  0A 00 64 00 00 0E 84 BE   ..... 1.
..d.....
00000020 31 60 00 00 00 00 00 00                            1`......

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U ipw_associate_network associating: 'tsunami' 00:0e:84:be:31:60
ipw2200: U ipw_wx_set_wap BSSID set to current BSSID.
ipw2200: I ipw_rx_notification type = 11 (43 bytes)
ipw2200: I ipw_rx_notification AUTH_SEQ_1_FAIL
ipw2200: I ipw_rx_notification type = 11 (4 bytes)
ipw2200: I ipw_rx_notification AUTH_SEQ_1_FAIL
ipw2200: I ipw_rx_notification type = 10 (4 bytes)
ipw2200: I ipw_rx_notification disassociated: 'tsunami' 00:0e:84:be:31:60
ipw2200: U __ipw_send_cmd SSID command (#8) 7 bytes: 0x00000023
00000000 74 73 75 6E 61 6D 69                               tsunami

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd SCAN_REQUEST_EXT command (#26) 96 bytes:
0x00000023
00000000 01 00 00 00 4B 01 02 03  04 05 06 07 08 09 0A 0B   ....K...
........
00000010 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00   ........
........
00000020 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00   ........
........
00000030 00 00 00 00 00 00 00 00  00 00 04 44 44 44 44 44   ........
...DDDDD
00000040 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00   ........
........
00000050 00 00 00 00 00 00 00 00  78 00 00 00 14 00 14 00   ........
x.......
ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 1
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 2
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 3
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 4
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 5
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 6
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 7
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 8
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 9
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 10
ipw2200: U ipw_wx_get_name Name: unassociated
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 11
ipw2200: I ipw_rx_notification type = 13 (4 bytes)
ipw2200: I ipw_rx_notification Scan completed: type 11, 11 channels, 1
status
ipw2200: U ipw_best_network Network 'tsunami (00:40:96:34:f2:2c)'
excluded because of BSSID mismatch: 00:0e:84:be:31:60.
ipw2200: U ipw_best_network Network 'tsunami (00:0e:84:be:31:60)'
excluded because of storming (284ms since last assoc attempt).
ipw2200: U ipw_debug_config Scan completed, no valid APs matched [CFG
0x00000106]
ipw2200: U ipw_debug_config Channel unlocked.
ipw2200: U ipw_debug_config ESSID locked to 'tsunami'
ipw2200: U ipw_debug_config BSSID locked to 00:0e:84:be:31:60
ipw2200: U ipw_debug_config PRIVACY on
ipw2200: U ipw_debug_config RATE MASK: 0x00000FFF
ipw2200: U ipw_wx_set_mode Set MODE: 2
ipw2200: U ipw_wx_set_essid ESSID set to current ESSID.
ipw2200: U ipw_wx_set_wap BSSID set to current BSSID.
ipw2200: U __ipw_send_cmd SCAN_REQUEST_EXT command (#26) 96 bytes:
0x80000023
00000000 02 00 00 00 4B 01 02 03  04 05 06 07 08 09 0A 0B   ....K...
........
00000010 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00   ........
........
00000020 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00   ........
........
00000030 00 00 00 00 00 00 00 00  00 00 03 33 33 33 33 33   ........
...33333
00000040 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00   ........
........
00000050 00 00 00 00 00 00 00 00  78 00 00 00 14 00 14 00   ........
x.......
ipw2200: I ipw_irq_tasklet Command completed.

...



When I try to associate with 'iwconfig eth1 essid tsunami key DONTSAY' I
get this output from
the ipw2200 driver:

...

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 1
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 2
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 3
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 4
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 5
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 6
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 7
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 8
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 9
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 10
ipw2200: I ipw_rx_notification type = 12 (46 bytes)
ipw2200: I ipw_rx_notification Scan result for channel 11
ipw2200: I ipw_rx_notification type = 13 (4 bytes)
ipw2200: I ipw_rx_notification Scan completed: type 11, 11 channels, 1
status
ipw2200: U ipw_best_network Network 'tsunami (00:40:96:34:f2:2c)' is a
viable match.
ipw2200: U ipw_best_network Network 'tsunami (00:0e:84:be:31:60)' is a
viable match.
ipw2200: U ipw_associate_network Assocation attempt: 'tsunami', channel
9, 802.11g [12], short[:short], enc=on (open) key=1.
ipw2200: U __ipw_send_cmd SSID command (#8) 7 bytes: 0x80000023
00000000 74 73 75 6E 61 6D 69                               tsunami

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd SUPPORTED_RATES command (#22) 16 bytes: 0x80000023
00000000 02 0C 00 00 82 04 0B 0C  12 16 18 24 30 48 60 6C   ........
...$0H`l
ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd SYSTEM_CONFIG command (#6) 20 bytes: 0x80000023
00000000 00 00 00 00 01 00 01 00  01 00 00 01 00 00 00 1E   ........
........
00000010 00 00 01 00                                        ....

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U ipw_associate_network Association sensitivity: -70
ipw2200: U __ipw_send_cmd SENSITIVITY_CALIB command (#42) 4 bytes:
0x80000023
00000000 2A 00 00 00                                        *...

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U __ipw_send_cmd ASSOCIATE command (#21) 40 bytes: 0x00000123
00000000 09 00 00 00 00 00 04 02  00 0E 84 BE 31 60 68 06   ........
....1`h.
00000010 00 00 B9 28 F9 AD 31 0C  0A 00 64 00 00 0E 84 BE   ...(..1.
..d.....
00000020 31 60 00 00 00 00 00 00                            1`......

ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U ipw_associate_network associating: 'tsunami' 00:0e:84:be:31:60
ipw2200: I ipw_rx_notification type = 11 (4 bytes)
ipw2200: I ipw_rx_notification authenticated: 'tsunami' 00:0e:84:be:31:60
ipw2200: I ipw_rx_notification type = 10 (59 bytes)
ipw2200: I ipw_rx_notification associated: 'tsunami' 00:0e:84:be:31:60
ipw2200: U __ipw_send_cmd SYSTEM_CONFIG command (#6) 20 bytes: 0x000000E3
00000000 00 00 00 00 01 00 01 00  01 00 00 01 00 00 00 1E   ........
........
00000010 00 00 01 00                                        ....

ipw2200: U ipw_link_up starting queue
ipw2200: I ipw_irq_tasklet Command completed.
ipw2200: U ipw_wx_get_name Name: IEEE 802.11g
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
localhost last message repeated 3 times
ipw2200: U ipw_wx_get_name Name: IEEE 802.11g
ipw2200: I ipw_rx_notification type = 25 (4 bytes)
localhost last message repeated 4 times

...


What I see , is that the '__ipw_send_cmd ASSOCIATE command' part is
different:

With wpa_supplicant:

U __ipw_send_cmd ASSOCIATE command (#21) 40 bytes: 0x00000123
09 01 00 00 00 00 04 02  00 0E 84 BE 31 60 62 06   ........ ....1`b.
00 00 92 D1 A6 20 31 0C  0A 00 64 00 00 0E 84 BE   ..... 1. ..d.....
31 60 00 00 00 00 00 00                            1`......


With iwconfig:

U __ipw_send_cmd ASSOCIATE command (#21) 40 bytes: 0x00000123
09 00 00 00 00 00 04 02  00 0E 84 BE 31 60 68 06   ........ ....1`h.
00 00 B9 28 F9 AD 31 0C  0A 00 64 00 00 0E 84 BE   ...(..1. ..d.....
31 60 00 00 00 00 00 00                            1`......


Is there something in my configuration that I haven't found yet?

Help would be appreciated.


Berthold Cogel



More information about the HostAP mailing list