Re: ath0 bug in current?

[Vincent <10.50%free.fr@localhost>]

David:

> When you say that the authentication exchange times out, do you mean
> that you get an 802.11 Disassociate message with a 'Reason Code'
> indicating such?  I ask because that is what I often get.  I am using
> WPA on an rtw(4), however.  The interval between my client receiving and
> responding to some messages was about one quarter of a second!  The AP,
> a Linksys WRT54GC, does not wait that long.

I have been able to reproduce and capture what I think happens at start:


vincent# wpa_supplicant -d -i ath0 -c /etc/wpa_supplicant.conf
Initializing interface 'ath0' conf '/etc/wpa_supplicant.conf' driver 
'default' ctrl_interface 'N/A' bridge 'N/A'
Reading configuration file '/etc/wpa_supplicant.conf'
ctrl_interface='/tmp/wpa'
Priority group 0
   id=0 ssid='gabuzomeuh'
Initializing interface (2) 'ath0'
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
Own MAC address: 00:0f:b5:64:19:a8
wpa_driver_bsd_set_wpa: enabled=1
wpa_driver_bsd_set_wpa_internal: wpa=3 privacy=1
wpa_driver_bsd_del_key: keyidx=0
wpa_driver_bsd_del_key: keyidx=1
wpa_driver_bsd_del_key: keyidx=2
wpa_driver_bsd_del_key: keyidx=3
wpa_driver_bsd_set_countermeasures: enabled=0
wpa_driver_bsd_set_drop_unencrypted: enabled=1
RSN: flushing PMKID list in the driver
Setting scan request: 0 sec 100000 usec
Added interface ath0
State: DISCONNECTED -> SCANNING
Starting AP scan (specific SSID)
Scan SSID - hexdump_ascii(len=10):
     67 61 62 75 7a 6f 6d 65 75 68                     gabuzomeuh
Trying to get current scan results first without requesting a new scan 
to speed up initial association
Received 0 bytes of scan results (8 BSSes)
Scan results: 8
Selecting BSS from priority group 0
Try to find WPA-enabled AP
0: 06:d6:f7:3a:f9:d6 ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
   skip - SSID mismatch
1: 06:d6:f7:3a:f9:d7 ssid='' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
   skip - SSID mismatch
2: 06:d6:f7:3a:f9:d4 ssid='gabuzomeuh' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
   selected based on WPA IE
   selected WPA AP 06:d6:f7:3a:f9:d4 ssid='gabuzomeuh'
Try to find non-WPA AP
Trying to associate with 06:d6:f7:3a:f9:d4 (SSID='gabuzomeuh' freq=2412 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
Overriding auth_alg selection: 0x1
WPA: using IEEE 802.11i/D3.0
WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 2 proto 1
WPA: set AP WPA IE - hexdump(len=28): dd 1a 00 50 f2 01 01 00 00 50 f2 
02 02 00 00 50 f2 02 00 50 f2 04 01 00 00 50 f2 02
WPA: clearing AP RSN IE
WPA: using GTK TKIP
WPA: using PTK CCMP
WPA: using KEY_MGMT WPA-PSK
WPA: Set own WPA IE default - hexdump(len=24): dd 16 00 50 f2 01 01 00 
00 50 f2 02 01 00 00 50 f2 04 01 00 00 50 f2 02
No keys have been configured - skip key clearing
wpa_driver_bsd_set_drop_unencrypted: enabled=1
State: SCANNING -> ASSOCIATING
wpa_driver_bsd_associate: ssid 'gabuzomeuh' wpa ie len 24 pairwise 3 
group 2 key mgmt 1
wpa_driver_bsd_associate: set PRIVACY 1
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=Auto
EAPOL: disable timer tick
Authentication with 06:d6:f7:3a:f9:d4 timed out.
Added BSSID 06:d6:f7:3a:f9:d4 into blacklist
No keys have been configured - skip key clearing
State: ASSOCIATING -> DISCONNECTED

Hope this helps,
Vincent