No subject

bogus at does.not.exist.com bogus at does.not.exist.com
Sat Oct 15 15:41:40 PDT 2011 

# EAPOL: startWhen --> 0
# EAPOL: SUPP_PAE entering state CONNECTING
# EAPOL: txStart
# TX EAPOL: dst=00:23:69:8f:6b:16
# Authentication with 00:23:69:8f:6b:16 timed out.
# BSSID 00:23:69:8f:6b:16 blacklist count incremented to 4

I've captured the authentication using tshark and it seems that wpa_supplicant
ignores the identity requests:

# 21043   7.653254 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 23617   8.618366 Azurewav_33:62:f2 -> Cisco-Li_8f:6b:16 EAPOL 54 Start
# 23631   8.621801 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 28894  10.639204 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 47517  16.646363 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 62544  21.826867 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 65885  22.806460 Azurewav_33:62:f2 -> Cisco-Li_8f:6b:16 EAPOL 54 Start
# 65886  22.806995 Azurewav_33:62:f2 -> Cisco-Li_8f:6b:16 EAPOL 54 Start
# 65908  22.814801 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 72079  24.819307 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]
# 90661  30.827557 Cisco-Li_8f:6b:16 -> Azurewav_33:62:f2 EAP 61 Request, Identity [RFC3748]

PS: I've attached the full debug log.

Any ideas?

-- 
Best Regards,
Peter Kasza

http://www.iit.uni-miskolc.hu/~kasza1
mr.schyte at gmail.com

()  ascii ribbon campaign - against html e-mail
/\  www.asciiribbon.org   - against proprietary attachments

--n8g4imXOkfNTN/H1
Content-Type: text/plain; charset=us-ascii
Content-Disposition: attachment; filename="debug.log"

Authentication with c0:c1:c0:8c:08:3b timed out.
BSSID c0:c1:c0:8c:08:3b blacklist count incremented to 2
wpa_driver_wext_disassociate
No keys have been configured - skip key clearing
State: ASSOCIATED -> DISCONNECTED
CTRL-EVENT-STATE-CHANGE id=1 state=0 BSSID=00:00:00:00:00:00
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
EAPOL: External notification - portValid=0
Setting scan request: 0 sec 0 usec
State: DISCONNECTED -> SCANNING
CTRL-EVENT-STATE-CHANGE id=-1 state=2 BSSID=00:00:00:00:00:00
Starting AP scan (broadcast SSID)
wpa_driver_wext_scan: specific scan = 0
Scan requested (ret=0) - scan timeout 30 seconds
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:00:00:00:00:00
BSSID 00:00:00:00:00:00 blacklist count incremented to 11
CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
State: SCANNING -> DISCONNECTED
CTRL-EVENT-STATE-CHANGE id=-1 state=0 BSSID=00:00:00:00:00:00
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
Wireless event: cmd=0x8b19 len=8
Received 1866 bytes of scan results (6 BSSes)
New scan results available
Selecting BSS from priority group 13
Try to find WPA-enabled AP
0: 00:23:69:8f:6b:16 ssid='wifi' wpa_ie_len=0 rsn_ie_len=24 caps=0x11
   skip - blacklisted
1: 00:23:69:2a:44:4f ssid='wifi' wpa_ie_len=0 rsn_ie_len=24 caps=0x11
   skip - blacklisted
2: 00:23:69:8f:6a:d7 ssid='wifi' wpa_ie_len=0 rsn_ie_len=24 caps=0x11
   skip - blacklisted
3: 00:23:69:8f:6a:d4 ssid='wifi' wpa_ie_len=0 rsn_ie_len=24 caps=0x11
   skip - blacklisted
4: c0:c1:c0:8c:08:3b ssid='wifi' wpa_ie_len=0 rsn_ie_len=24 caps=0x11
   skip - blacklisted
5: 00:23:69:29:ef:6b ssid='wifi' wpa_ie_len=0 rsn_ie_len=24 caps=0x11
   selected based on RSN IE
   selected WPA AP 00:23:69:29:ef:6b ssid='wifi'
Trying to associate with 00:23:69:29:ef:6b (SSID='wifi' freq=2422 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
Overriding auth_alg selection: 0x1
RSN: using IEEE 802.11i/D9.0
WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 1 proto 2
WPA: clearing AP WPA IE
WPA: using GTK TKIP
WPA: using PTK CCMP
WPA: using KEY_MGMT 802.1X
No keys have been configured - skip key clearing
wpa_driver_wext_set_drop_unencrypted
State: DISCONNECTED -> ASSOCIATING
CTRL-EVENT-STATE-CHANGE id=-1 state=3 BSSID=00:23:69:29:ef:6b
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
wpa_driver_wext_associate
wpa_driver_wext_set_psk
Association request to the driver failed
Setting authentication timeout: 5 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=Auto
RSN: Ignored PMKID candidate without preauth flag
RSN: Ignored PMKID candidate without preauth flag
RSN: Ignored PMKID candidate without preauth flag
RSN: Ignored PMKID candidate without preauth flag
RSN: Ignored PMKID candidate without preauth flag
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
Wireless event: cmd=0x8b04 len=12
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:23:69:8f:6b:16
State: ASSOCIATING -> ASSOCIATED
CTRL-EVENT-STATE-CHANGE id=1 state=4 BSSID=00:23:69:29:ef:6b
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
Associated to a new BSS: BSSID=00:23:69:8f:6b:16
No keys have been configured - skip key clearing
Associated with 00:23:69:8f:6b:16
WPA: Association event - clear replay counter
WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: SUPP_BE entering state IDLE
EAP: EAP entering state INITIALIZE
EAP: EAP entering state IDLE
Setting authentication timeout: 10 sec 0 usec
Cancelling scan request
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
Wireless event: cmd=0x8b1a len=12
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
EAPOL: startWhen --> 0
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: txStart
TX EAPOL: dst=00:23:69:8f:6b:16
Authentication with 00:23:69:8f:6b:16 timed out.
BSSID 00:23:69:8f:6b:16 blacklist count incremented to 4
wpa_driver_wext_disassociate
No keys have been configured - skip key clearing
State: ASSOCIATED -> DISCONNECTED
CTRL-EVENT-STATE-CHANGE id=1 state=0 BSSID=00:00:00:00:00:00
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED

--n8g4imXOkfNTN/H1--

More information about the Hostap mailing list