wpa_supplicant receive "Wireless event: new AP: 00:00:00:00:00:00" event?

Sun Apr 17 23:31:45 PDT 2005

2005/4/17, Jouni Malinen <jkmaline at cc.hut.fi>:

> Can you please send debug log from both hostapd and wpa_supplicant for
> such a case? Whhich firmware version are you using? Can you reproduce
> this easily with manually running 'iwlist wlan0 scan' three times?
> 
Here are debug logs of hostapd and wpa-supplicant. I just show parts
of logs in the mail content and i put full logs as attachments. These
logs were recoreded with prism2 station firmware 1.8.0. The same
probelm still occured even i upgrade firmware to 1.8.3. BTW, i can
reproduce this by a certain number of scan. It occurs by chance :(

thanks for any comment

wpa-supplicant:
[skip]
WPA: Key negotiation completed with 00:60:b3:64:3f:b6 [PTK=CCMP GTK=TKIP]
Cancelling authentication timeout
State: GROUP_HANDSHAKE -> COMPLETED
Connection to 00:60:b3:64:3f:b6 completed (auth)
EAPOL: External notification - portValid=1
RSN: processing PMKSA candidate list
RSN: PMKSA candidate 00:60:b3:64:3f:b6 does not need pre-authentication anymore
RSN: no more pending PMKSA candidates
Wireless event: cmd=0x8b19 len=12
Received 1008 bytes of scan results (6 BSSes)
Scan results: 6
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Already associated with the selected AP.
Wireless event: cmd=0x8b19 len=12
Received 1082 bytes of scan results (7 BSSes)
Scan results: 7
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Already associated with the selected AP.
Wireless event: cmd=0x8b19 len=12
Received 1082 bytes of scan results (7 BSSes)
Scan results: 7
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Already associated with the selected AP.
Wireless event: cmd=0x8b19 len=12
Received 1173 bytes of scan results (7 BSSes)
Scan results: 7
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Already associated with the selected AP.
Wireless event: cmd=0x8b19 len=12
Received 1050 bytes of scan results (7 BSSes)
Scan results: 7
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Already associated with the selected AP.
Wireless event: cmd=0x8b19 len=12
Received 1173 bytes of scan results (7 BSSes)
Scan results: 7
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Already associated with the selected AP.
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:00:00:00:00:00
Setting scan request: 0 sec 100000 usec
Added BSSID 00:60:b3:64:3f:b6 into blacklist
State: COMPLETED -> DISCONNECTED
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
Disconnect event - remove keys
wpa_driver_hostap_set_key: alg=none key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_hostap_set_key: alg=none key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_hostap_set_key: alg=none key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_hostap_set_key: alg=none key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_hostap_set_key: alg=none key_idx=0 set_tx=0 seq_len=0 key_len=0
RTM_NEWLINK, IFLA_IFNAME: Interface 'wifi0' added
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
State: DISCONNECTED -> SCANNING
Starting AP scan (broadcast SSID)
Wireless event: cmd=0x8b19 len=12
Received 1173 bytes of scan results (7 BSSes)
Scan results: 7
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   skip - blacklisted
1: 00:0f:b5:00:e4:46 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
   skip - no WPA/RSN IE
2: 00:0d:54:a0:b0:b0 ssid='VHE' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
3: 00:20:a6:4f:b5:7f ssid='WL1' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
4: 00:07:40:76:c6:1b ssid='00074035D4E6' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
5: 00:60:b3:16:68:b7 ssid='WL1' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
6: 00:0d:54:a0:8b:7a ssid='NETLAB_11G' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
No APs found - clear blacklist and try again
Removed BSSID 00:60:b3:64:3f:b6 from blacklist (clear)
Selecting BSS from priority group 1
0: 00:60:b3:64:3f:b6 ssid='HOSTAP' wpa_ie_len=0 rsn_ie_len=26 caps=0x11
   selected
Trying to associate with 00:60:b3:64:3f:b6 (SSID='HOSTAP' freq=2422 MHz)
Cancelling scan request
Automatic auth_alg selection: 0x1
RSN: PMKID - hexdump(len=16): ef 4d fb b8 fc 31 e6 45 02 75 ad 85 48 b5 01 a3
RSN: Trying to use cached PMKSA
RSN: using IEEE 802.11i/D9.0
WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 1
WPA: using GTK TKIP
WPA: using PTK CCMP
WPA: using KEY_MGMT 802.1X
WPA: Own WPA IE - hexdump(len=40): 30 26 01 00 00 0f ac 02 01 00 00 0f
ac 04 01 00 00 0f ac 01 00 00 01 00 ef 4d fb b8 fc 31 e6 45 02 75 ad
85 48 b5 01 a3
No keys have been configured - skip key clearing
wpa_driver_hostap_set_drop_unencrypted: enabled=1
State: SCANNING -> ASSOCIATING
wpa_driver_hostap_associate
Setting authentication timeout: 5 sec 0 usec
EAPOL: External notification - portControl=Auto
Wireless event: cmd=0x8b06 len=8
Wireless event: cmd=0x8b04 len=12
Wireless event: cmd=0x8b1a len=19
EAPOL: authWhile --> 0
EAPOL: startWhen --> 0
Signal 2 received - terminating
State: ASSOCIATING -> DISCONNECTED

hostapd:
[skip]
wlan0: STA 00:60:b3:f1:fa:91 WPA: pairwise key handshake completed (RSN)
IEEE 802.1X: 00:60:b3:f1:fa:91 AUTH_PAE entering state AUTHENTICATED
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.1X: authorizing port
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.1X: authenticated
wlan0: STA 00:60:b3:f1:fa:91 WPA: added PMKSA cache entry
RSN: added PMKID - hexdump(len=16): 74 12 5f 70 6e 46 3d 02 86 f1 38
1d 76 7f b4 70
mgmt::auth
authentication: STA=00:60:b3:f1:fa:91 auth_alg=0 auth_transaction=1
status_code=0 wep=0
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.11: authentication OK (open system)
wlan0: STA 00:60:b3:f1:fa:91 WPA: event 0 notification
authentication reply: STA=00:60:b3:f1:fa:91 auth_alg=0 auth_transaction=2 resp=0
MGMT (TX callback) fail
mgmt::auth cb
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.11: did not acknowledge
authentication response
mgmt::auth
authentication: STA=00:60:b3:f1:fa:91 auth_alg=0 auth_transaction=1
status_code=0 wep=0
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.11: authentication OK (open system)
wlan0: STA 00:60:b3:f1:fa:91 WPA: event 0 notification
authentication reply: STA=00:60:b3:f1:fa:91 auth_alg=0 auth_transaction=2 resp=0
mgmt::auth
authentication: STA=00:60:b3:f1:fa:91 auth_alg=0 auth_transaction=1
status_code=0 wep=0
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.11: authentication OK (open system)
wlan0: STA 00:60:b3:f1:fa:91 WPA: event 0 notification
authentication reply: STA=00:60:b3:f1:fa:91 auth_alg=0 auth_transaction=2 resp=0
MGMT (TX callback) ACK
mgmt::auth cb
wlan0: STA 00:60:b3:f1:fa:91 IEEE 802.11: authenticated
Signal 2 received - terminating
Removing station 00:60:b3:f1:fa:91