Cannot connect to AP with WPA2 and AES
Belisko Marek
marek.belisko at gmail.com
Mon Sep 18 13:22:45 PDT 2017
Hi,
I'm using raspberrypi with sdio esp8089 wifi (kernel 4.9). When trying
to connect to AP using WPA-PSK + AES (wpa_supplicant v2.6) I get an
below error.
I'm using following wpa_supplicant.conf:
network={
ssid="open-nandra"
scan_ssid=1
key_mgmt=WPA-PSK
psk="secret password verified 3 times"
}
log:
Successfully initialized wpa_supplicant
wlan0: SME: Trying to authenticate with [ 2554.130332] wlan0:
authenticate with ec:4d:47:88:06:9c
ec:4d:47:88:06:9c (SSID='open-nandra' freq=2422 MHz)
[ 2554.147141] wlan0: send auth to ec:4d:47:88:06:9c (try 1/3)
[ 2554.154863] wlan0: authenticated
wlan0: Trying to associate with ec:4d:47:88:06:9c (SSID='open-nandra'
freq=2422 MHz)
[ 2554.166717] wlan0: associate with ec:4d:47:88:06:9c (try 1/3)
[ 2554.176950] wlan0: RX AssocResp from ec:4d:47:88:06:9c
(capab=0x1411 status=0 aid=4)
[ 2554.184776] wlan0: associated
wlan0: Associated with ec:4d:47:88:06:9c
wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
[ 2554.200829] esp_op_ampdu_action RX START ec:4d:47:88:06:9c tid 0 0
wl[ 2554.259101] wlan0: deauthenticating from ec:4d:47:88:06:9c by
local choice (Reason: 1=UNSPECIFIED)
an0: WPA: Failed to set PTK to the driver (alg=3 keylen=16
bssid=ec:4d:47:88:06:9c)
[ 2554.288405] esp_op_ampdu_action RX STOP ec:4d:47:88:06:9c tid 0
wlan0: CTRL-EVENT-DISCONNECTED bssid=ec:4d:47:88:06:9c reason=1
locally_generated=1
wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="open-nandra"
auth_failures=1 duration=10 reason=WRONG_KEY
log with debug:
wpa_supplicant v2.6
random: Trying to read entropy from /dev/random
Successfully initialized wpa_supplicant
Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver
'nl80211' ctrl_interface 'N/A' bridge 'N/A'
Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.conf'
Reading configuration file '/etc/wpa_supplicant.conf'
Priority group 0
id=0 ssid='open-nandra'
nl80211: Supported cipher 00-0f-ac:1
nl80211: Supported cipher 00-0f-ac:5
nl80211: Supported cipher 00-0f-ac:2
nl80211: Supported cipher 00-0f-ac:4
nl80211: Using driver-based off-channel TX
nl80211: Driver-advertised extended capabilities (default) -
hexdump(len=8): 00 00 00 00 00 00 00 40
nl80211: Driver-advertised extended capabilities mask (default) -
hexdump(len=8): 00 00 00 00 00 00 00 40
nl80211: interface wlan0 in phy phy0
nl80211: Set mode ifindex 2 iftype 2 (STATION)
nl80211: Subscribe to mgmt frames with non-AP handle 0xf7ed08
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=06
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=0a07
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=0a11
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=1101
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=1102
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=0505
nl80211: Register frame type=0xd0 (WLAN_FC_STYPE_ACTION)
nl_handle=0xf7ed08 match=0500
rfkill: initial event: idx=0 type=1 op=0 soft=0 hard=0
netlink: Operstate: ifindex=2 linkmode=1 (userspace-control),
operstate=5 (IF_OPER_DORMANT)
Add interface wlan0 to a new radio phy0
nl80211: Regulatory information - country=00
nl80211: 2402-2472 @ 40 MHz 20 mBm
nl80211: 2457-2482 @ 20 MHz 20 mBm (no IR)
nl80211: 2474-2494 @ 20 MHz 20 mBm (no OFDM) (no IR)
nl80211: 5170-5250 @ 80 MHz 20 mBm (no IR)
nl80211: 5250-5330 @ 80 MHz 20 mBm (DFS) (no IR)
nl80211: 5490-5730 @ 160 MHz 20 mBm (DFS) (no IR)
nl80211: 5735-5835 @ 80 MHz 20 mBm (no IR)
nl80211: 57240-63720 @ 2160 MHz 0 mBm
nl80211: Added 802.11b mode based on 802.11g information
wlan0: Own MAC address: 18:fe:34:d9:8a:6f
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=0 addr=(nil)
key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=0 addr=(nil)
key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=0 addr=(nil)
key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=0 addr=(nil)
key_idx=3 set_tx=0 seq_len=0 key_len=0
wlan0: RSN: flushing PMKID list in the driver
nl80211: Flush PMKIDs
wlan0: Setting scan request: 0.100000 sec
wlan0: WPS: UUID based on MAC address: 6163e3f1-fe36-5fec-867c-ddf80fcf4285
GnuTLS: Library version 3.5.9 (runtime) - 3.5.9 (build)
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
nl80211: Skip set_supp_port(unauthorized) while not associated
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
wlan0: Added interface wlan0
wlan0: State: DISCONNECTED -> DISCONNECTED
nl80211: Set wlan0 operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=2 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
random: Got 16/20 bytes from /dev/random
RTM_NEWLINK: ifi_index=2 ifname=wlan0 operstate=2 linkmode=0
ifi_family=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK: ifi_index=2 ifname=wlan0 operstate=2 linkmode=1
ifi_family=0 ifi_flags=0x1003 ([UP])
wlan0: State: DISCONNECTED -> SCANNING
Scan SSID - hexdump_ascii(len=11):
6f 70 65 6e 2d 6e 61 6e 64 72 61 open-nandra
wlan0: Starting AP scan for wildcard SSID
wlan0: Add radio work 'scan'@0xf7fcb0
wlan0: First radio work item in the queue - schedule start immediately
wlan0: Starting radio work 'scan'@0xf7fcb0 after 0.000033 second wait
wlan0: nl80211: scan request
Scan requested (ret=0) - scan timeout 10 seconds
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
wlan0: nl80211: Scan trigger
wlan0: Event SCAN_STARTED (47) received
wlan0: Own scan request started a scan in 0.000083 seconds
EAPOL: disable timer tick
RTM_NEWLINK: ifi_index=2 ifname=wlan0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
wlan0: nl80211: New scan results available
nl80211: Scan probed for SSID 'open-nandra'
nl80211: Scan probed for SSID ''
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442
2447 2452 2457 2462 2467 2472
wlan0: Event SCAN_RESULTS (3) received
wlan0: Scan completed in 1.049221 seconds
nl80211: Received scan results (12 BSSes)
wlan0: BSS: Start scan result update 1
wlan0: BSS: Add new id 0 BSSID 00:1c:df:d6:18:4c SSID 'empaty' freq 2412
wlan0: BSS: Add new id 1 BSSID ec:4d:47:88:06:9c SSID 'open-nandra' freq 2422
wlan0: BSS: Add new id 2 BSSID 06:7c:34:4d:10:8d SSID 'UPC Wi-Free' freq 2437
wlan0: BSS: Add new id 3 BSSID 64:7c:34:4d:10:8d SSID 'UPC4039147' freq 2437
wlan0: BSS: Add new id 4 BSSID 14:cc:20:92:21:e4 SSID 'TP-LINK_9221E4' freq 2432
wlan0: BSS: Add new id 5 BSSID 56:67:11:c1:26:44 SSID 'UPC Wi-Free' freq 2437
wlan0: BSS: Add new id 6 BSSID 34:6a:c2:68:ed:e0 SSID 'HUAWEI-2Fat' freq 2417
wlan0: BSS: Add new id 7 BSSID 18:a6:f7:4d:3a:03 SSID 'Lindovsky' freq 2467
wlan0: BSS: Add new id 8 BSSID c4:e9:84:48:a6:76 SSID 'ROBA' freq 2437
wlan0: BSS: Add new id 9 BSSID 06:7c:34:4e:d1:25 SSID 'UPC Wi-Free' freq 2462
wlan0: BSS: Add new id 10 BSSID 60:e3:27:f2:0a:16 SSID 'BRYCHTOVA' freq 2472
wlan0: BSS: Add new id 11 BSSID 2c:56:dc:87:fb:c0 SSID 'zahrada2' freq 2462
BSS: last_scan_res_used=12/32
wlan0: New scan results available (own=1 ext=0)
WPS: AP 64:7c:34:4d:10:8d type 0 added
WPS: AP 14:cc:20:92:21:e4 type 0 added
WPS: AP 18:a6:f7:4d:3a:03 type 0 added
WPS: AP c4:e9:84:48:a6:76 type 0 added
WPS: AP 60:e3:27:f2:0a:16 type 0 added
WPS: AP[0] 64:7c:34:4d:10:8d type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[1] 14:cc:20:92:21:e4 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[2] 18:a6:f7:4d:3a:03 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[3] c4:e9:84:48:a6:76 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[4] 60:e3:27:f2:0a:16 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
wlan0: Radio work 'scan'@0xf7fcb0 done in 1.070754 seconds
wlan0: radio_work_free('scan'@0xf7fcb0: num_active_works --> 0
wlan0: Selecting BSS from priority group 0
wlan0: 0: 00:1c:df:d6:18:4c ssid='empaty' wpa_ie_len=26 rsn_ie_len=24
caps=0x411 level=-35 freq=2412
wlan0: skip - SSID mismatch
wlan0: 1: ec:4d:47:88:06:9c ssid='open-nandra' wpa_ie_len=0
rsn_ie_len=20 caps=0x1411 level=-69 freq=2422
wlan0: selected based on RSN IE
wlan0: selected BSS ec:4d:47:88:06:9c ssid='open-nandra'
wlan0: Considering connect request: reassociate: 0 selected:
ec:4d:47:88:06:9c bssid: 00:00:00:00:00:00 pending:
00:00:00:00:00:00 wpa_state: SCANNING ssid=0xf7f0b8
current_ssid=(nil)
wlan0: Request association with ec:4d:47:88:06:9c
wlan0: Add radio work 'sme-connect'@0xf7fcb0
wlan0: First radio work item in the queue - schedule start immediately
wlan0: Starting radio work 'sme-connect'@0xf7fcb0 after 0.000043 second wait
wlan0: Automatic auth_alg selection: 0x1
RSN: PMKSA cache search - network_ctx=(nil) try_opportunistic=0
RSN: Search for BSSID ec:4d:47:88:06:9c
RSN: No PMKSA cache entry found
wlan0: RSN: using IEEE 802.11i/D9.0
wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto 2
wlan0: WPA: clearing AP WPA IE
WPA: set AP RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00
0f ac 04 01 00 00 0f ac 02 0c 00
wlan0: WPA: using GTK CCMP
wlan0: WPA: using PTK CCMP
wlan0: WPA: using KEY_MGMT WPA-PSK
WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 04
01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
RRM: Determining whether RRM can be used - device support: 0x10
RRM: Adding RRM IE to Association Request
wlan0: Cancelling scan request
wlan0: SME: Trying to authenticate with ec:4d:47:88:06:9c
(SSID='open-nandra' freq=2422 MHz)
wlan0: State: SCANNING -> AUTHENTICATING
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=Auto
nl80211: Authenticate (ifindex=2)
* bssid=ec:4d:47:88:06:9c
* freq=2422
* SSID - hexdump_ascii(len=11):
6f 70 65 6e 2d 6e 61 6e 64 72 61 open-nandra
* IEs - hexdump(len=0): [NULL]
* Auth Type 0
nl80211: Authentication request send successfully
nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0
nl80211: New station ec:4d:47:88:06:9c
nl80211: Drv Event 37 (NL80211_CMD_AUTHENTICATE) received for wlan0
nl80211: Authenticate event
wlan0: Event AUTH (11) received
wlan0: SME: Authentication response: peer=ec:4d:47:88:06:9c
auth_type=0 auth_transaction=2 status_code=0
wlan0: Trying to associate with ec:4d:47:88:06:9c (SSID='open-nandra'
freq=2422 MHz)
wlan0: State: AUTHENTICATING -> ASSOCIATING
nl80211: Set wlan0 operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=2 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
WPA: set own WPA/RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 04 01
00 00 0f ac 04 01 00 00 0f ac 02 00 00
nl80211: Associate (ifindex=2)
* bssid=ec:4d:47:88:06:9c
* freq=2422
* SSID - hexdump_ascii(len=11):
6f 70 65 6e 2d 6e 61 6e 64 72 61 open-nandra
* IEs - hexdump(len=39): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04
01 00 00 0f ac 02 00 00 7f 08 00 00 00 00 00 00 00 40 46 05 00 00 00
00 00
* WPA Versions 0x2
* pairwise=0xfac04
* group=0xfac04
* akm=0xfac02
nl80211: Association request send successfully
RTM_NEWLINK: ifi_index=2 ifname=wlan0 operstate=5 linkmode=1
ifi_family=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK: ifi_index=2 ifname=wlan0 wext ifi_family=0
ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK: ifi_index=2 ifname=wlan0 wext ifi_family=0
ifi_flags=0x11003 ([UP][LOWER_UP])
nl80211: Drv Event 38 (NL80211_CMD_ASSOCIATE) received for wlan0
nl80211: Associate event
wlan0: Event ASSOC (0) received
wlan0: Association info event
resp_ies - hexdump(len=133): 01 08 82 84 8b 96 24 30 48 6c 32 04 0c 12
18 60 46 05 32 08 01 00 00 2d 1a bc 09 17 ff 00 00 00 00 00 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00 00 00 3d 16 03 08 04 00 00 00 00
00 00 00 00 00 00 00 00 00 00
00 00 00 00 00 4a 0e 14 00 0a 00 2c 01 c8 00 14 00 05 00 19 00 7f 03
05 00 08 dd 09 00 10 18 02 03 00 0c 00 00 dd 18 00 50 f2 02 01 01 00
00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62 32 2f 00
wlan0: freq=2422 MHz
wlan0: State: ASSOCIATING -> ASSOCIATED
nl80211: Set wlan0 operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=2 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
wlan0: Associated to a new BSS: BSSID=ec:4d:47:88:06:9c
wlan0: Associated with ec:4d:47:88:06:9c
wlan0: WPA: Association event - clear replay counter
wlan0: WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: SUPP_BE entering state IDLE
wlan0: Setting authentication timeout: 10 sec 0 usec
wlan0: Cancelling scan request
WMM AC: AC mandatory: AC_BE=0 AC_BK=0 AC_VI=0 AC_VO=0
WMM AC: U-APSD queues=0x0
WMM AC: Valid WMM association, WMM AC is enabled
wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
l2_packet_receive: src=ec:4d:47:88:06:9c len=121
wlan0: RX EAPOL from ec:4d:47:88:06:9c
wlan0: Setting authentication timeout: 10 sec 0 usec
wlan0: IEEE 802.1X RX: version=2 type=3 length=117
wlan0: EAPOL-Key type=2
wlan0: key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
wlan0: key_length=16 key_data_length=22
replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 00
key_nonce - hexdump(len=32): 17 a5 1f 85 66 6a c1 d3 69 1b 97 ee 60
88 cd 7a ce 26 ee 92 4d ad e8 64 1f 84 f0 55 4d c4 de 67
key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
wlan0: State: ASSOCIATED -> 4WAY_HANDSHAKE
wlan0: WPA: RX message 1 of 4-Way Handshake from ec:4d:47:88:06:9c (ver=2)
RSN: msg 1/4 key data - hexdump(len=22): dd 14 00 0f ac 04 c4 00 5d 4e
cf 78 76 bf 03 76 ae 6b 1f 23 14 e2
WPA: PMKID in EAPOL-Key - hexdump(len=22): dd 14 00 0f ac 04 c4 00 5d
4e cf 78 76 bf 03 76 ae 6b 1f 23 14 e2
RSN: PMKID from Authenticator - hexdump(len=16): c4 00 5d 4e cf 78 76
bf 03 76 ae 6b 1f 23 14 e2
wlan0: RSN: no matching PMKID found
WPA: Renewed SNonce - hexdump(len=32): 78 30 5a fe 3e a0 de 44 71 bd
de 2f 1d 6b 3c 7f fd 50 7f 5f 60 fb 6d 8c 6c ee 54 12 2d 65 14 9a
WPA: PTK derivation - A1=18:fe:34:d9:8a:6f A2=ec:4d:47:88:06:9c
WPA: Nonce1 - hexdump(len=32): 78 30 5a fe 3e a0 de 44 71 bd de 2f 1d
6b 3c 7f fd 50 7f 5f 60 fb 6d 8c 6c ee 54 12 2d 65 14 9a
WPA: Nonce2 - hexdump(len=32): 17 a5 1f 85 66 6a c1 d3 69 1b 97 ee 60
88 cd 7a ce 26 ee 92 4d ad e8 64 1f 84 f0 55 4d c4 de 67
WPA: PMK - hexdump(len=32): [REMOVED]
WPA: PTK - hexdump(len=48): [REMOVED]
WPA: KCK - hexdump(len=16): [REMOVED]
WPA: KEK - hexdump(len=16): [REMOVED]
WPA: TK - hexdump(len=16): [REMOVED]
WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 04 01
00 00 0f ac 04 01 00 00 0f ac 02 00 00
WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 00
wlan0: WPA: Sending EAPOL-Key 2/4
WPA: KCK - hexdump(len=16): [REMOVED]
WPA: Derived Key MIC - hexdump(len=16): 34 10 24 ac ba 31 10 6a 8e 03
00 82 e2 ba 74 52
nl80211: Drv Event 46 (NL80211_CMD_CONNECT) received for wlan0
nl80211: Ignore connect event (cmd=46) when using userspace SME
l2_packet_receive: src=ec:4d:47:88:06:9c len=155
wlan0: RX EAPOL from ec:4d:47:88:06:9c
wlan0: IEEE 802.1X RX: version=2 type=3 length=151
wlan0: EAPOL-Key type=2
wlan0: key_info 0x13ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack
MIC Secure Encr)
wlan0: key_length=16 key_data_length=56
replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
key_nonce - hexdump(len=32): 17 a5 1f 85 66 6a c1 d3 69 1b 97 ee 60
88 cd 7a ce 26 ee 92 4d ad e8 64 1f 84 f0 55 4d c4 de 67
key_iv - hexdump(len=16): ce 26 ee 92 4d ad e8 64 1f 84 f0 55 4d c4 de 68
key_rsc - hexdump(len=8): 80 00 00 00 00 00 00 00
key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
key_mic - hexdump(len=16): cd b9 9b 1f 9e 78 fd ad a6 5c 36 21 cd e1 53 c1
RSN: encrypted key data - hexdump(len=56): 8e 86 d3 2e df 59 bd 87 34
d4 20 78 81 f7 a1 1d 8f a4 95 bd 2a 98 dc da 72 3b b4 24 b9 3f e9 bb
17 23 62 2a 1b ec 94 dc e5 4a de c6 f7 1e 4e cf d9 e6 04 10 08 cc 56
9f
WPA: decrypted EAPOL-Key key data - hexdump(len=48): [REMOVED]
wlan0: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
wlan0: WPA: RX message 3 of 4-Way Handshake from ec:4d:47:88:06:9c (ver=2)
WPA: IE KeyData - hexdump(len=48): 30 14 01 00 00 0f ac 04 01 00 00 0f
ac 04 01 00 00 0f ac 02 0c 00 dd 16 00 0f ac 01 01 00 ca 29 23 30 11
da df 6e f4 37 27 4a 5d 69 4c 61 dd 00
WPA: RSN IE in EAPOL-Key - hexdump(len=22): 30 14 01 00 00 0f ac 04 01
00 00 0f ac 04 01 00 00 0f ac 02 0c 00
WPA: GTK in EAPOL-Key - hexdump(len=24): [REMOVED]
wlan0: WPA: Sending EAPOL-Key 4/4
WPA: KCK - hexdump(len=16): [REMOVED]
WPA: Derived Key MIC - hexdump(len=16): b0 94 77 fe e6 83 0f b0 e8 eb
54 08 50 95 26 11
wlan0: WPA: Installing PTK to the driver
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=3 addr=0xf7f9e0
key_idx=0 set_tx=1 seq_len=6 key_len=16
nl80211: KEY_DATA - hexdump(len=16): [REMOVED]
nl80211: KEY_SEQ - hexdump(len=6): 00 00 00 00 00 00
addr=ec:4d:47:88:06:9c
nl80211: set_key failed; err=-2 No such file or directory)
wlan0: WPA: Failed to set PTK to the driver (alg=3 keylen=16
bssid=ec:4d:47:88:06:9c)
wlan0: Request to deauthenticate - bssid=ec:4d:47:88:06:9c
pending_bssid=00:00:00:00:00:00 reason=1 state=4WAY_HANDSHAKE
wpa_driver_nl80211_deauthenticate(addr=ec:4d:47:88:06:9c reason_code=1)
wlan0: Event DEAUTH (12) received
wlan0: Deauthentication notification
wlan0: * reason 1 (locally generated)
Deauthentication frame IE(s) - hexdump(len=0): [NULL]
wlan0: CTRL-EVENT-DISCONNECTED bssid=ec:4d:47:88:06:9c reason=1
locally_generated=1
wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect
wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="open-nandra"
auth_failures=1 duration=10 reason=WRONG_KEY
wlan0: Auto connect enabled: try to reconnect (wps=0/0 wpa_state=7)
wlan0: Setting scan request: 0.100000 sec
wlan0: Radio work 'sme-connect'@0xf7fcb0 done in 0.248528 seconds
wlan0: radio_work_free('sme-connect'@0xf7fcb0: num_active_works --> 0
Added BSSID ec:4d:47:88:06:9c into blacklist
wlan0: Blacklist count 1 --> request scan in 100 ms
wlan0: Ignore new scan request for 0.100000 sec since an earlier
request is scheduled to trigger sooner
wlan0: WPA: Clear old PMK and PTK
wlan0: Disconnect event - remove keys
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=0 addr=(nil)
key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=2 (wlan0) alg=0 addr=0xf7e33c
key_idx=0 set_tx=0 seq_len=0 key_len=0
addr=ec:4d:47:88:06:9c
wlan0: State: 4WAY_HANDSHAKE -> DISCONNECTED
nl80211: Set wlan0 operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=2 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
WMM AC: WMM AC is disabled
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
nl80211: Skip set_supp_port(unauthorized) while not associated
EAPOL: SUPP_BE entering state INITIALIZE
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
wlan0: State: DISCONNECTED -> DISCONNECTED
nl80211: Set wlan0 operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=2 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
wlan0: Ignore new scan request for 5.000000 sec since an earlier
request is scheduled to trigger sooner
RTM_NEWLINK: ifi_index=2 ifname=wlan0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0
nl80211: Delete station ec:4d:47:88:06:9c
nl80211: Drv Event 39 (NL80211_CMD_DEAUTHENTICATE) received for wlan0
nl80211: Deauthenticate event
nl80211: Ignore deauth event triggered due to own deauth request
nl80211: Drv Event 48 (NL80211_CMD_DISCONNECT) received for wlan0
nl80211: Ignore disconnect event when using userspace SME
wlan0: State: DISCONNECTED -> SCANNING
Scan SSID - hexdump_ascii(len=11):
6f 70 65 6e 2d 6e 61 6e 64 72 61 open-nandra
wlan0: Starting AP scan for wildcard SSID
wlan0: Add radio work 'scan'@0xf8ade0
wlan0: First radio work item in the queue - schedule start immediately
wlan0: Starting radio work 'scan'@0xf8ade0 after 0.000035 second wait
wlan0: nl80211: scan request
Scan requested (ret=0) - scan timeout 30 seconds
nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
wlan0: nl80211: Scan trigger
wlan0: Event SCAN_STARTED (47) received
wlan0: Own scan request started a scan in 0.000078 seconds
RTM_NEWLINK: ifi_index=2 ifname=wlan0 operstate=2 linkmode=1
ifi_family=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK: ifi_index=2 ifname=wlan0 wext ifi_family=0 ifi_flags=0x1003 ([UP])
nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
wlan0: nl80211: New scan results available
nl80211: Scan probed for SSID 'open-nandra'
nl80211: Scan probed for SSID ''
nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442
2447 2452 2457 2462 2467 2472
wlan0: Event SCAN_RESULTS (3) received
wlan0: Scan completed in 1.059292 seconds
nl80211: Received scan results (16 BSSes)
wlan0: BSS: Start scan result update 2
wlan0: BSS: Add new id 12 BSSID 54:67:51:c1:26:44 SSID 'UPCCEE32DF' freq 2437
wlan0: BSS: Add new id 13 BSSID de:53:1c:ad:28:91 SSID 'UPC Wi-Free' freq 2462
wlan0: BSS: Add new id 14 BSSID dc:53:7c:ad:28:91 SSID 'dadenko' freq 2462
wlan0: BSS: Add new id 15 BSSID c4:27:95:87:04:1f SSID 'UPC1884789' freq 2412
BSS: last_scan_res_used=16/32
wlan0: New scan results available (own=1 ext=0)
WPS: AP 54:67:51:c1:26:44 type 0 added
WPS: AP dc:53:7c:ad:28:91 type 0 added
WPS: AP[0] 64:7c:34:4d:10:8d type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[1] 14:cc:20:92:21:e4 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[2] 18:a6:f7:4d:3a:03 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[3] c4:e9:84:48:a6:76 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[4] 60:e3:27:f2:0a:16 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[5] 54:67:51:c1:26:44 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[6] dc:53:7c:ad:28:91 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
wlan0: Radio work 'scan'@0xf8ade0 done in 1.080748 seconds
wlan0: radio_work_free('scan'@0xf8ade0: num_active_works --> 0
wlan0: Postpone network selection by 9 seconds since all networks are disabled
EAPOL: startWhen --> 0
EAPOL: disable timer tick
Any ideas what to check? Thanks.
BR,
marek
--
as simple and primitive as possible
-------------------------------------------------
Marek Belisko - OPEN-NANDRA
Freelance Developer
Ruska Nova Ves 219 | Presov, 08005 Slovak Republic
Tel: +421 915 052 184
skype: marekwhite
twitter: #opennandra
web: http://open-nandra.com
More information about the Hostap
mailing list