wpa_supplicant blacklists my AP, why?

Tino Keitel tino.keitel at gmx.de
Sat Oct 14 05:53:39 EDT 2006 

Hi folky,

I try to use wpa_supplicant with a madwifi card (current SVN from
madwifi-ng), but according to the log it blacklists my AP and doesn't
connect.

Could anybody give some insight what is wrong here?

The same card works with the same config on the same laptop when using
a Linksys WRT54GS and OpenWRT as the access point.

The config and the log file are attached.

Regards,
Tino
-------------- next part --------------
ctrl_interface=/var/run/wpa_supplicant
ctrl_interface_group=0

#eapol_version=1
#ap_scan=0

eapol_version=1
ap_scan=1
fast_reauth=1

network={
        scan_ssid=1
        proto=WPA2
        key_mgmt=WPA-PSK
        pairwise=CCMP TKIP
        group=CCMP TKIP WEP104 WEP40
        ssid="WMET"
        psk="XXX"
}


-------------- next part --------------
2006-10-14_09:44:57.41380 Initializing interface 'ath0' conf '/etc/wpa_supplicant.conf' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
2006-10-14_09:44:57.41386 Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.conf'
2006-10-14_09:44:57.41387 Reading configuration file '/etc/wpa_supplicant.conf'
2006-10-14_09:44:57.41393 ctrl_interface='/var/run/wpa_supplicant'
2006-10-14_09:44:57.41394 ctrl_interface_group='0' (DEPRECATED)
2006-10-14_09:44:57.41395 eapol_version=1
2006-10-14_09:44:57.41396 ap_scan=1
2006-10-14_09:44:57.41399 fast_reauth=1
2006-10-14_09:44:57.41400 Priority group 0
2006-10-14_09:44:57.41401    id=0 ssid='WMET'
2006-10-14_09:44:57.41402 Initializing interface (2) 'ath0'
2006-10-14_09:44:57.41403 EAPOL: SUPP_PAE entering state DISCONNECTED
2006-10-14_09:44:57.41404 EAPOL: KEY_RX entering state NO_KEY_RECEIVE
2006-10-14_09:44:57.41405 EAPOL: SUPP_BE entering state INITIALIZE
2006-10-14_09:44:57.41406 EAP: EAP entering state DISABLED
2006-10-14_09:44:57.41407 EAPOL: External notification - portEnabled=0
2006-10-14_09:44:57.41408 EAPOL: External notification - portValid=0
2006-10-14_09:44:57.41409 SIOCGIWRANGE: WE(compiled)=20 WE(source)=13 enc_capa=0xf
2006-10-14_09:44:57.41410   capabilities: key_mgmt 0xf enc 0xf
2006-10-14_09:44:57.41411 WEXT: Operstate: linkmode=1, operstate=5
2006-10-14_09:44:57.41412 Own MAC address: 00:0f:b5:25:a3:13
2006-10-14_09:44:57.41413 wpa_driver_wext_set_wpa
2006-10-14_09:44:57.41414 wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:44:57.41415 wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:44:57.41416 wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:44:57.41419 wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:44:57.41420 wpa_driver_wext_set_countermeasures
2006-10-14_09:44:57.41421 wpa_driver_wext_set_drop_unencrypted
2006-10-14_09:44:57.41422 Setting scan request: 0 sec 100000 usec
2006-10-14_09:44:57.41423 ctrl_interface_group=0
2006-10-14_09:44:57.41424 Added interface ath0
2006-10-14_09:44:57.41425 RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
2006-10-14_09:44:57.41426 Wireless event: cmd=0x8b06 len=8
2006-10-14_09:44:57.41427 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:44:57.41428 RTM_NEWLINK, IFLA_IFNAME: Interface 'ath0' added
2006-10-14_09:44:57.41429 State: DISCONNECTED -> SCANNING
2006-10-14_09:44:57.41430 Starting AP scan (specific SSID)
2006-10-14_09:44:57.41431 Scan SSID - hexdump_ascii(len=4):
2006-10-14_09:44:57.41432      57 4d 45 54                                       WMET            
2006-10-14_09:44:57.41433 Trying to get current scan results first without requesting a new scan to speed up initial association
2006-10-14_09:44:57.41434 Received 259 bytes of scan results (1 BSSes)
2006-10-14_09:44:57.41435 Scan results: 1
2006-10-14_09:44:57.41436 Selecting BSS from priority group 0
2006-10-14_09:44:57.41437 0: 00:16:38:45:6a:89 ssid='WMET' wpa_ie_len=30 rsn_ie_len=26 caps=0x11
2006-10-14_09:44:57.41439    selected based on RSN IE
2006-10-14_09:44:57.41440 Trying to associate with 00:16:38:45:6a:89 (SSID='WMET' freq=2462 MHz)
2006-10-14_09:44:57.41442 Cancelling scan request
2006-10-14_09:44:57.41443 WPA: clearing own WPA/RSN IE
2006-10-14_09:44:57.41444 Automatic auth_alg selection: 0x1
2006-10-14_09:44:57.41445 RSN: using IEEE 802.11i/D9.0
2006-10-14_09:44:57.41446 WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 2 proto 2
2006-10-14_09:44:57.41447 WPA: set AP WPA IE - hexdump(len=30): dd 1c 00 50 f2 01 01 00 00 50 f2 02 02 00 00 50 f2 04 00 50 f2 02 01 00 00 50 f2 02 00 00
2006-10-14_09:44:57.41448 WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
2006-10-14_09:44:57.41449 WPA: using GTK TKIP
2006-10-14_09:44:57.41450 WPA: using PTK CCMP
2006-10-14_09:44:57.41451 WPA: using KEY_MGMT WPA-PSK
2006-10-14_09:44:57.41452 WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
2006-10-14_09:44:57.41453 No keys have been configured - skip key clearing
2006-10-14_09:44:57.41454 wpa_driver_wext_set_drop_unencrypted
2006-10-14_09:44:57.41455 State: SCANNING -> ASSOCIATING
2006-10-14_09:44:57.41456 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:44:57.41459 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:44:57.41460 wpa_driver_wext_associate
2006-10-14_09:44:57.41461 Setting authentication timeout: 10 sec 0 usec
2006-10-14_09:44:57.41462 EAPOL: External notification - EAP success=0
2006-10-14_09:44:57.41463 EAPOL: External notification - EAP fail=0
2006-10-14_09:44:57.41465 EAPOL: External notification - portControl=Auto
2006-10-14_09:44:57.41467 RSN: Ignored PMKID candidate without preauth flag
2006-10-14_09:44:57.41468 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:44:57.41469 Wireless event: cmd=0x8b06 len=8
2006-10-14_09:44:57.41470 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:44:57.41471 Wireless event: cmd=0x8b04 len=12
2006-10-14_09:44:57.41472 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:44:57.41473 Wireless event: cmd=0x8b1a len=13
2006-10-14_09:44:57.41474 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:44:57.41475 Wireless event: cmd=0x8b19 len=8
2006-10-14_09:44:57.41476 Received 259 bytes of scan results (1 BSSes)
2006-10-14_09:44:57.41477 Scan results: 1
2006-10-14_09:44:57.41478 Selecting BSS from priority group 0
2006-10-14_09:44:57.41479 0: 00:16:38:45:6a:89 ssid='WMET' wpa_ie_len=30 rsn_ie_len=26 caps=0x11
2006-10-14_09:44:57.41480    selected based on RSN IE
2006-10-14_09:44:57.41481 Already associated with the selected AP.
2006-10-14_09:44:57.41482 RSN: Ignored PMKID candidate without preauth flag
2006-10-14_09:44:57.41483 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:44:57.41484 Wireless event: cmd=0x8b15 len=20
2006-10-14_09:44:57.41485 Wireless event: new AP: 00:16:38:45:6a:89
2006-10-14_09:44:57.41486 State: ASSOCIATING -> ASSOCIATED
2006-10-14_09:44:57.41487 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:44:57.41489 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:44:57.41490 Associated to a new BSS: BSSID=00:16:38:45:6a:89
2006-10-14_09:45:09.72341 No keys have been configured - skip key clearing
2006-10-14_09:45:09.72342 Associated with 00:16:38:45:6a:89
2006-10-14_09:45:09.72343 WPA: Association event - clear replay counter
2006-10-14_09:45:09.72344 EAPOL: External notification - portEnabled=0
2006-10-14_09:45:09.72345 EAPOL: External notification - portValid=0
2006-10-14_09:45:09.72346 EAPOL: External notification - EAP success=0
2006-10-14_09:45:09.72347 EAPOL: External notification - portEnabled=1
2006-10-14_09:45:09.72349 EAPOL: SUPP_PAE entering state CONNECTING
2006-10-14_09:45:09.72350 EAPOL: SUPP_BE entering state IDLE
2006-10-14_09:45:09.72351 Setting authentication timeout: 10 sec 0 usec
2006-10-14_09:45:09.72352 Cancelling scan request
2006-10-14_09:45:09.72353 EAPOL: startWhen --> 0
2006-10-14_09:45:09.72354 EAPOL: SUPP_PAE entering state CONNECTING
2006-10-14_09:45:09.72355 EAPOL: txStart
2006-10-14_09:45:09.72356 WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
2006-10-14_09:45:09.72357 Authentication with 00:16:38:45:6a:89 timed out.
2006-10-14_09:45:09.72358 Added BSSID 00:16:38:45:6a:89 into blacklist
2006-10-14_09:45:09.72359 State: ASSOCIATED -> DISCONNECTED
2006-10-14_09:45:09.72360 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:45:09.72361 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:45:09.72362 wpa_driver_wext_disassociate
2006-10-14_09:45:09.72363 No keys have been configured - skip key clearing
2006-10-14_09:45:09.72364 EAPOL: External notification - portEnabled=0
2006-10-14_09:45:09.72365 EAPOL: SUPP_PAE entering state DISCONNECTED
2006-10-14_09:45:09.72366 EAPOL: SUPP_BE entering state INITIALIZE
2006-10-14_09:45:09.72368 EAPOL: External notification - portValid=0
2006-10-14_09:45:09.72369 Setting scan request: 0 sec 0 usec
2006-10-14_09:45:09.72370 State: DISCONNECTED -> SCANNING
2006-10-14_09:45:09.72371 Starting AP scan (broadcast SSID)
2006-10-14_09:45:09.72372 RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
2006-10-14_09:45:09.72373 Wireless event: cmd=0x8b15 len=20
2006-10-14_09:45:09.72379 Wireless event: new AP: 00:00:00:00:00:00
2006-10-14_09:45:09.72380 BSSID 00:16:38:45:6a:89 blacklist count incremented to 2
2006-10-14_09:45:09.72381 CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
2006-10-14_09:45:09.72382 wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:45:09.72383 wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:45:09.72384 wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:45:09.72385 wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:45:09.72386 wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
2006-10-14_09:45:09.72388 State: SCANNING -> DISCONNECTED
2006-10-14_09:45:09.72389 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:45:09.72390 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:45:09.72391 EAPOL: External notification - portEnabled=0
2006-10-14_09:45:09.72392 EAPOL: External notification - portValid=0
2006-10-14_09:45:09.72393 EAPOL: External notification - EAP success=0
2006-10-14_09:45:09.72394 RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
2006-10-14_09:45:09.72396 RTM_NEWLINK, IFLA_IFNAME: Interface 'ath0' added
2006-10-14_09:45:09.72397 RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
2006-10-14_09:45:09.72398 Wireless event: cmd=0x8b19 len=8
2006-10-14_09:45:09.72399 Received 259 bytes of scan results (1 BSSes)
2006-10-14_09:45:09.72400 Scan results: 1
2006-10-14_09:45:09.72401 Selecting BSS from priority group 0
2006-10-14_09:45:09.72402 0: 00:16:38:45:6a:89 ssid='WMET' wpa_ie_len=30 rsn_ie_len=26 caps=0x11
2006-10-14_09:45:09.72403    skip - blacklisted
2006-10-14_09:45:09.72404 No APs found - clear blacklist and try again
2006-10-14_09:45:09.72405 Removed BSSID 00:16:38:45:6a:89 from blacklist (clear)
2006-10-14_09:45:09.72406 Selecting BSS from priority group 0
2006-10-14_09:45:09.72407 0: 00:16:38:45:6a:89 ssid='WMET' wpa_ie_len=30 rsn_ie_len=26 caps=0x11
2006-10-14_09:45:09.72408    selected based on RSN IE
2006-10-14_09:45:09.72409 Trying to associate with 00:16:38:45:6a:89 (SSID='WMET' freq=2462 MHz)
2006-10-14_09:45:09.72410 Cancelling scan request
2006-10-14_09:45:09.72411 WPA: clearing own WPA/RSN IE
2006-10-14_09:45:09.72412 Automatic auth_alg selection: 0x1
2006-10-14_09:45:09.72413 RSN: using IEEE 802.11i/D9.0
2006-10-14_09:45:09.72414 WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 2 proto 2
2006-10-14_09:45:09.72415 WPA: set AP WPA IE - hexdump(len=30): dd 1c 00 50 f2 01 01 00 00 50 f2 02 02 00 00 50 f2 04 00 50 f2 02 01 00 00 50 f2 02 00 00
2006-10-14_09:45:09.72416 WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
2006-10-14_09:45:09.72419 WPA: using GTK TKIP
2006-10-14_09:45:09.72420 WPA: using PTK CCMP
2006-10-14_09:45:09.72421 WPA: using KEY_MGMT WPA-PSK
2006-10-14_09:45:09.72422 WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
2006-10-14_09:45:09.72423 No keys have been configured - skip key clearing
2006-10-14_09:45:09.72424 wpa_driver_wext_set_drop_unencrypted
2006-10-14_09:45:09.72425 State: DISCONNECTED -> ASSOCIATING
2006-10-14_09:45:09.72426 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:45:09.72427 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:45:09.72428 wpa_driver_wext_associate
2006-10-14_09:45:09.72429 Setting authentication timeout: 10 sec 0 usec
2006-10-14_09:45:09.72430 EAPOL: External notification - EAP success=0
2006-10-14_09:45:09.72431 EAPOL: External notification - EAP fail=0
2006-10-14_09:45:09.72432 EAPOL: External notification - portControl=Auto
2006-10-14_09:45:09.72433 RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
2006-10-14_09:45:09.72434 Wireless event: cmd=0x8b06 len=8
2006-10-14_09:45:09.72435 RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
2006-10-14_09:45:09.72436 Wireless event: cmd=0x8b04 len=12
2006-10-14_09:45:09.72437 RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
2006-10-14_09:45:09.72440 Wireless event: cmd=0x8b1a len=13
2006-10-14_09:45:09.72441 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:45:09.72442 Wireless event: cmd=0x8b15 len=20
2006-10-14_09:45:10.11750 Wireless event: new AP: 00:16:38:45:6a:89
2006-10-14_09:45:10.11751 State: ASSOCIATING -> ASSOCIATED
2006-10-14_09:45:10.11752 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:45:10.11753 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:45:10.11754 Associated to a new BSS: BSSID=00:16:38:45:6a:89
2006-10-14_09:45:10.11755 No keys have been configured - skip key clearing
2006-10-14_09:45:10.11756 Associated with 00:16:38:45:6a:89
2006-10-14_09:45:10.11757 WPA: Association event - clear replay counter
2006-10-14_09:45:10.11758 EAPOL: External notification - portEnabled=0
2006-10-14_09:45:10.11760 EAPOL: External notification - portValid=0
2006-10-14_09:45:10.11761 EAPOL: External notification - EAP success=0
2006-10-14_09:45:10.11762 EAPOL: External notification - portEnabled=1
2006-10-14_09:45:10.11763 EAPOL: SUPP_PAE entering state CONNECTING
2006-10-14_09:45:10.11764 EAPOL: SUPP_BE entering state IDLE
2006-10-14_09:45:10.11765 Setting authentication timeout: 10 sec 0 usec
2006-10-14_09:45:10.11766 Cancelling scan request
2006-10-14_09:45:10.11767 RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
2006-10-14_09:45:10.11768 RTM_NEWLINK, IFLA_IFNAME: Interface 'ath0' added
2006-10-14_09:45:10.11769 CTRL-EVENT-TERMINATING - signal 15 received
2006-10-14_09:45:10.11770 Removing interface ath0
2006-10-14_09:45:10.11771 State: ASSOCIATED -> DISCONNECTED
2006-10-14_09:45:10.11772 wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
2006-10-14_09:45:10.11773 WEXT: Operstate: linkmode=-1, operstate=5
2006-10-14_09:45:10.11774 wpa_driver_wext_deauthenticate
2006-10-14_09:45:10.11775 No keys have been configured - skip key clearing
2006-10-14_09:45:10.11778 EAPOL: External notification - portEnabled=0
2006-10-14_09:45:10.11779 EAPOL: SUPP_PAE entering state DISCONNECTED
2006-10-14_09:45:10.11780 EAPOL: SUPP_BE entering state INITIALIZE
2006-10-14_09:45:10.11781 EAPOL: External notification - portValid=0
2006-10-14_09:45:10.11782 wpa_driver_wext_set_wpa
2006-10-14_09:45:10.11783 wpa_driver_wext_set_drop_unencrypted
2006-10-14_09:45:10.11784 wpa_driver_wext_set_countermeasures
2006-10-14_09:45:10.11785 No keys have been configured - skip key clearing
2006-10-14_09:45:10.11786 WEXT: Operstate: linkmode=0, operstate=6
2006-10-14_09:45:10.11787 Cancelling scan request

More information about the HostAP mailing list