* Laptop disassociating by local choice (reason=17) @ 2014-11-10 18:12 Brent Taylor 2014-12-01 18:49 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-11-10 18:12 UTC (permalink / raw) To: kvalo, ath6kl, linux-wireless Resending in plain text mode... Hi Kalle, I have a custom built board with an AR6003 WiFi chip running linux-3.10.40 and hostapd 2.2 that I'm trying to use as an access point. My configuration file for hostapd is: interface=wlan0 driver=nl80211 ssid=testssid channel=1 own_ip_addr=********** auth_algs=1 wpa=2 wpa_passphrase=********** wpa_key_mgmt=WPA-PSK wpa_pairwise=TKIP rns_pairwise=CCMP ignore_broadcase_ssid=1 I should mention that this configuration works with a USB WiFi stick with an ath9k chipset running the same linux kernel and hostapd binary. When using the AR6003 chipset the client Laptop (HP EliteBook 8440p running ubuntu 12.04.3 LTS) does not connect and prints the message: wlan0: disassociating from <device mac> by local choice (reason=17) Running Wireshark on the Laptop, I see "Key (msg 1/4)", "Key (msg 2/4)", and then "Key (msg 3/4)" before it tries to reauthenticate. On the access point device, I see the logs from WAP that message 3/4 was sent and the next message received is NL80211_CMD_DEL_STATION. I've searched online for the reason=17 and haven't come across an answer yet. The access point device is not connected to a computer that is connected to the internet, so while getting log files is possible, it 's just not the easiest thing to do. If requested, I could get a log dump from hostapd and/or debug output from the ath6kl driver. Thanks for any help Brent Taylor ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-11-10 18:12 Laptop disassociating by local choice (reason=17) Brent Taylor @ 2014-12-01 18:49 ` Brent Taylor 2014-12-01 19:00 ` Krishna Chaitanya 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-12-01 18:49 UTC (permalink / raw) To: Kalle Valo, ath6kl, linux-wireless On Mon, Nov 10, 2014 at 12:12 PM, Brent Taylor <motobud@gmail.com> wrote: > Resending in plain text mode... > > Hi Kalle, > I have a custom built board with an AR6003 WiFi chip running > linux-3.10.40 and hostapd 2.2 that I'm trying to use as an access > point. > > My configuration file for hostapd is: > interface=wlan0 > driver=nl80211 > ssid=testssid > channel=1 > own_ip_addr=********** > auth_algs=1 > wpa=2 > wpa_passphrase=********** > wpa_key_mgmt=WPA-PSK > wpa_pairwise=TKIP > rns_pairwise=CCMP > ignore_broadcase_ssid=1 > > I should mention that this configuration works with a USB WiFi stick > with an ath9k chipset running the same linux kernel and hostapd > binary. > > When using the AR6003 chipset the client Laptop (HP EliteBook 8440p > running ubuntu 12.04.3 LTS) does not connect and prints the message: > wlan0: disassociating from <device mac> by local choice (reason=17) > > Running Wireshark on the Laptop, I see "Key (msg 1/4)", "Key (msg > 2/4)", and then "Key (msg 3/4)" before it tries to reauthenticate. > > On the access point device, I see the logs from WAP that message 3/4 > was sent and the next message received is NL80211_CMD_DEL_STATION. > > I've searched online for the reason=17 and haven't come across an > answer yet. The access point device is not connected to a computer > that is connected to the internet, so while getting log files is > possible, it 's just not the easiest thing to do. If requested, I > could get a log dump from hostapd and/or debug output from the ath6kl > driver. > > Thanks for any help > Brent Taylor Does anyone know where the "reason=17" comes from? I traced it down in the kernel and it looks like it comes from the WIFI Chip in the Laptop, but I can not find what it means. Is there more information from somewhere that I provide to get some help with this? Thanks, Brent ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-01 18:49 ` Brent Taylor @ 2014-12-01 19:00 ` Krishna Chaitanya 2014-12-02 19:18 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Krishna Chaitanya @ 2014-12-01 19:00 UTC (permalink / raw) To: Brent Taylor; +Cc: Kalle Valo, ath6kl, linux-wireless On Tue, Dec 2, 2014 at 12:19 AM, Brent Taylor <motobud@gmail.com> wrote: > > On Mon, Nov 10, 2014 at 12:12 PM, Brent Taylor <motobud@gmail.com> wrote: > > Resending in plain text mode... > > > > Hi Kalle, > > I have a custom built board with an AR6003 WiFi chip running > > linux-3.10.40 and hostapd 2.2 that I'm trying to use as an access > > point. > > > > My configuration file for hostapd is: > > interface=wlan0 > > driver=nl80211 > > ssid=testssid > > channel=1 > > own_ip_addr=********** > > auth_algs=1 > > wpa=2 > > wpa_passphrase=********** > > wpa_key_mgmt=WPA-PSK > > wpa_pairwise=TKIP > > rns_pairwise=CCMP > > ignore_broadcase_ssid=1 > > > > I should mention that this configuration works with a USB WiFi stick > > with an ath9k chipset running the same linux kernel and hostapd > > binary. > > > > When using the AR6003 chipset the client Laptop (HP EliteBook 8440p > > running ubuntu 12.04.3 LTS) does not connect and prints the message: > > wlan0: disassociating from <device mac> by local choice (reason=17) > > > > Running Wireshark on the Laptop, I see "Key (msg 1/4)", "Key (msg > > 2/4)", and then "Key (msg 3/4)" before it tries to reauthenticate. > > > > On the access point device, I see the logs from WAP that message 3/4 > > was sent and the next message received is NL80211_CMD_DEL_STATION. > > > > I've searched online for the reason=17 and haven't come across an > > answer yet. The access point device is not connected to a computer > > that is connected to the internet, so while getting log files is > > possible, it 's just not the easiest thing to do. If requested, I > > could get a log dump from hostapd and/or debug output from the ath6kl > > driver. > > > > Thanks for any help > > Brent Taylor > > > Does anyone know where the "reason=17" comes from? I traced it down > in the kernel and it looks like it comes from the WIFI Chip in the > Laptop, but I can not find what it means. > > Is there more information from somewhere that I provide to get some > help with this? > > Thanks, > Brent Reason code 17 means: element in 4-Way Handshake different from (Re)Association Request/Probe Response/Beacon frame. When the MSG 3/4 is received hostapd valdiates the IE stored from the beacon/probe and if mismatch it issues deauth with reason code 17. wpa_supplicant_process_3_of_4: wpa_supplicant_validate_ie in hostapd/src/rsn_supp/wpa.c Sniffer capture and hostapd logs might help to try to understand what is mismatched. ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-01 19:00 ` Krishna Chaitanya @ 2014-12-02 19:18 ` Brent Taylor 2014-12-02 19:39 ` Krishna Chaitanya 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-12-02 19:18 UTC (permalink / raw) To: Krishna Chaitanya; +Cc: Kalle Valo, ath6kl, linux-wireless On Mon, Dec 1, 2014 at 1:00 PM, Krishna Chaitanya <chaitanya.mgit@gmail.com> wrote: > On Tue, Dec 2, 2014 at 12:19 AM, Brent Taylor <motobud@gmail.com> wrote: >> >> On Mon, Nov 10, 2014 at 12:12 PM, Brent Taylor <motobud@gmail.com> wrote: >> > Resending in plain text mode... >> > >> > Hi Kalle, >> > I have a custom built board with an AR6003 WiFi chip running >> > linux-3.10.40 and hostapd 2.2 that I'm trying to use as an access >> > point. >> > >> > My configuration file for hostapd is: >> > interface=wlan0 >> > driver=nl80211 >> > ssid=testssid >> > channel=1 >> > own_ip_addr=********** >> > auth_algs=1 >> > wpa=2 >> > wpa_passphrase=********** >> > wpa_key_mgmt=WPA-PSK >> > wpa_pairwise=TKIP >> > rns_pairwise=CCMP >> > ignore_broadcase_ssid=1 >> > >> > I should mention that this configuration works with a USB WiFi stick >> > with an ath9k chipset running the same linux kernel and hostapd >> > binary. >> > >> > When using the AR6003 chipset the client Laptop (HP EliteBook 8440p >> > running ubuntu 12.04.3 LTS) does not connect and prints the message: >> > wlan0: disassociating from <device mac> by local choice (reason=17) >> > >> > Running Wireshark on the Laptop, I see "Key (msg 1/4)", "Key (msg >> > 2/4)", and then "Key (msg 3/4)" before it tries to reauthenticate. >> > >> > On the access point device, I see the logs from WAP that message 3/4 >> > was sent and the next message received is NL80211_CMD_DEL_STATION. >> > >> > I've searched online for the reason=17 and haven't come across an >> > answer yet. The access point device is not connected to a computer >> > that is connected to the internet, so while getting log files is >> > possible, it 's just not the easiest thing to do. If requested, I >> > could get a log dump from hostapd and/or debug output from the ath6kl >> > driver. >> > >> > Thanks for any help >> > Brent Taylor >> >> >> Does anyone know where the "reason=17" comes from? I traced it down >> in the kernel and it looks like it comes from the WIFI Chip in the >> Laptop, but I can not find what it means. >> >> Is there more information from somewhere that I provide to get some >> help with this? >> >> Thanks, >> Brent > > > Reason code 17 means: > element in 4-Way Handshake different from (Re)Association > Request/Probe Response/Beacon frame. > > When the MSG 3/4 is received hostapd valdiates the IE stored from the > beacon/probe and if mismatch it issues deauth with reason code 17. > > wpa_supplicant_process_3_of_4: wpa_supplicant_validate_ie in > hostapd/src/rsn_supp/wpa.c > Sniffer capture and hostapd logs might help to try to understand what > is mismatched. Thanks for the explanation, here is a capture from hostapd-2.2 [linux][~> hostapd -dddd /etc/myapd.conf random: Trying to read entropy from /dev/random Configuration file: /etc/myapd.conf nl80211: Could not add multicast membership for vendor events: -2 (No such file or directory) rfkill: Cannot open RFKILL control device nl80211: RFKILL status not available nl80211: Using driver-based roaming 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: Supported cipher 00-40-96:255 nl80211: Supported cipher 00-14-72:1 nl80211: Supports Probe Response offload in AP mode nl80211: Disable use_monitor with device_ap_sme since no monitor mode support detected nl80211: interface wlan0 in phy phy1 nl80211: Set mode ifindex 4 iftype 3 (AP) nl80211: Setup AP(wlan0) - device_ap_sme=1 use_monitor=0 nl80211: Subscribe to mgmt frames with AP handle 0x6807c8 (device SME) nl80211: Register frame type=0xd0 nl_handle=0x6807c8 match= nl80211: Enable Probe Request reporting nl_preq=0x67f8c8 nl80211: Register frame type=0x40 nl_handle=0x67f8c8 match= nl80211: Add own interface ifindex 4 nl80211: if_indices[16]: 4 phy: phy1 BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits) nl80211: Regulatory information - country=00 nl80211: 2402-2472 @ 40 MHz 20 mBm nl80211: 2457-2482 @ 40 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: 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 Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm Completing interface initialization Mode: IEEE 802.11b Channel: 1 Frequency: 2412 MHz DFS 0 channels required radar detection nl80211: Set freq 2412 (ht_enabled=0, vht_enabled=0, bandwidth=20 MHz, cf1=2412 MHz, cf2=0 MHz) RATE[0] rate=10 flags=0x1 RATE[1] rate=20 flags=0x1 RATE[2] rate=55 flags=0x0 RATE[3] rate=110 flags=0x0 hostapd_setup_bss(hapd=0x67fdd8 (wlan0), first=1) wlan0: Flushing old station entries nl80211: flush -> DEL_STATION wlan0 (all) wlan0: Deauthenticate all stations nl80211: sta_remove -> DEL_STATION wlan0 ff:ff:ff:ff:ff:ff --> 0 (Success) wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0 wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0 wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0 wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0 Using interface wlan0 with hwaddr 00:03:7f:be:f0:a0 and ssid "testssid" Deriving WPA PSK based on passphrase SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid PSK (ASCII passphrase) - hexdump_ascii(len=10): [REMOVED] PSK (from passphrase) - hexdump(len=32): [REMOVED] random: Got 18/20 bytes from /dev/random random: Only 18/20 bytes of strong random data available from /dev/random random: Not enough entropy pool available for secure operations WPA: Not enough entropy in random pool for secure operations - update keys later when the first station connects Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] GMK - hexdump(len=32): [REMOVED] Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] Key Counter - hexdump(len=32): [REMOVED] WPA: Delay group state machine start until Beacon frames have been configured nl80211: Set beacon (beacon_set=0) nl80211: Beacon head - hexdump(len=55): 80 00 00 00 ff ff ff ff ff ff 00 03 7f be f0 a0 00 03 7f be f0 a0 00 00 00 00 00 00 00 00 00 00 64 00 11 00 00 08 74 65 73 74 73 73 69 64 01 04 82 84 0b 16 03 01 01 nl80211: Beacon tail - 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: ifindex=4 nl80211: beacon_int=100 nl80211: dtim_period=2 nl80211: ssid - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid nl80211: proberesp (offload) - hexdump(len=77): 50 00 00 00 00 00 00 00 00 00 00 03 7f be f0 a0 00 03 7f be f0 a0 00 00 00 00 00 00 00 00 00 00 64 00 11 00 00 08 74 65 73 74 73 73 69 64 01 04 82 84 0b 16 03 01 01 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 nl80211: hidden SSID not in use nl80211: privacy=1 nl80211: auth_algs=0x1 nl80211: wpa_version=0x2 nl80211: key_mgmt_suites=0x2 nl80211: pairwise_ciphers=0x18 nl80211: group_cipher=0x10 WPA: Start group state machine to set initial keys WPA: group state machine entering state GTK_INIT (VLAN-ID 0) Get randomness: len=16 entropy=0 random from os_get_random - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=16): [REMOVED] GTK - hexdump(len=16): [REMOVED] WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0) wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c key_idx=1 set_tx=1 seq_len=0 key_len=16 nl80211: KEY_DATA - hexdump(len=16): [REMOVED] broadcast key nl80211: Set wlan0 operstate 0->1 (UP) netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=6 (IF_OPER_UP) Failed to set TX queue parameters for queue 0. Failed to set TX queue parameters for queue 1. Failed to set TX queue parameters for queue 2. Failed to set TX queue parameters for queue 3. wlan0: interface state UNINITIALIZED->ENABLED wlan0: AP-ENABLED wlan0: Setup of interface done. ctrl_iface not configured! nl80211: Event message available nl80211: Drv Event 88 (NL80211_CMD_CH_SWITCH_NOTIFY) received for wlan0 nl80211: Channel switch event wlan0: Event CH_SWITCH (41) received wlan0: IEEE 802.11 driver had channel switch: freq=2412, ht=1, offset=0, width=1, cf1=2412, cf2=0 nl80211: Event message available nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 nl80211: New station 00:27:10:75:60:28 nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 01 00 wlan0: Event ASSOC (0) received Add randomness: count=1 entropy=0 random pool - hexdump(len=128): [REMOVED] random_mix_pool - hexdump(len=8): [REMOVED] random_mix_pool - hexdump(len=6): [REMOVED] random pool - hexdump(len=128): [REMOVED] wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated STA included RSN IE in (Re)AssocReq New STA ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS wlan0: STA 00:27:10:75:60:28 WPA: start authentication WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 WPA: Re-initialize GMK/Counter on first station random: Cannot read from /dev/random: Resource temporarily unavailable random: Got 0/2 bytes from /dev/random random: Only 18/20 bytes of strong random data available from /dev/random random: Not enough entropy pool available for secure operations WPA: Not enough entropy in random pool to proceed - reject first 4-way handshake Get randomness: len=32 entropy=1 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] GMK - hexdump(len=32): [REMOVED] Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] Key Counter - hexdump(len=32): [REMOVED] Get randomness: len=16 entropy=0 random from os_get_random - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=16): [REMOVED] GTK - hexdump(len=16): [REMOVED] wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c key_idx=1 set_tx=1 seq_len=0 key_len=16 nl80211: KEY_DATA - hexdump(len=16): [REMOVED] broadcast key Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] WPA: Assign ANonce - hexdump(len=32): 60 b4 5b f8 1d 19 7e e6 ba 2e a8 ec b7 a3 69 e5 37 dc a8 c1 4f b2 16 79 73 67 c4 56 a2 2e 55 dd WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0) WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) wlan0: Event EAPOL_RX (26) received IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 IEEE 802.1X: version=1 type=3 length=117 WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 key_data_length=22 WPA: Received Key Nonce - hexdump(len=32): 40 6b 10 a0 8f a6 a5 75 6f 72 a0 86 ae ff e1 3e 2c 66 79 77 20 94 27 1c 7f 7d fa e3 61 88 6d b2 WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Add randomness: count=2 entropy=0 random pool - hexdump(len=128): [REMOVED] random_mix_pool - hexdump(len=8): [REMOVED] random_mix_pool - hexdump(len=32): [REMOVED] random pool - hexdump(len=128): [REMOVED] WPA: Reject 4-way handshake to collect more entropy for random number generation random: Mark internal entropy pool to be ready (count=1/2) wpa_sta_disconnect STA 00:27:10:75:60:28 hostapd_wpa_auth_disconnect: WPA authenticator requests disconnect: STA 00:27:10:75:60:28 reason 2 nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) wlan0: STA 00:27:10:75:60:28 WPA: event 3 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port ap_sta_disconnect: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (5 seconds - AP_MAX_INACTIVITY_AFTER_DEAUTH) Removing STA 00:27:10:75:60:28 from kernel driver nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) wlan0: STA 00:27:10:75:60:28 MLME: MLME-DEAUTHENTICATE.indication(00:27:10:75:60:28, 2) wlan0: STA 00:27:10:75:60:28 MLME: MLME-DELETEKEYS.request(00:27:10:75:60:28) wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 nl80211: Event message available nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 nl80211: Delete station 00:27:10:75:60:28 wlan0: Event DISASSOC (1) received wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 nl80211: Event message available nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 nl80211: New station 00:27:10:75:60:28 nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 01 00 wlan0: Event ASSOC (0) received Add randomness: count=3 entropy=1 random pool - hexdump(len=128): [REMOVED] random_mix_pool - hexdump(len=8): [REMOVED] random_mix_pool - hexdump(len=6): [REMOVED] random pool - hexdump(len=128): [REMOVED] wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated STA included RSN IE in (Re)AssocReq New STA ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS wlan0: STA 00:27:10:75:60:28 WPA: start authentication WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 WPA: Re-initialize GMK/Counter on first station random: Cannot read from /dev/random: Resource temporarily unavailable random: Got 0/2 bytes from /dev/random random: Only 18/20 bytes of strong random data available from /dev/random random: Not enough entropy pool available for secure operations WPA: Not enough entropy in random pool to proceed - reject first 4-way handshake Get randomness: len=32 entropy=2 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] GMK - hexdump(len=32): [REMOVED] Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] Key Counter - hexdump(len=32): [REMOVED] Get randomness: len=16 entropy=0 random from os_get_random - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=16): [REMOVED] GTK - hexdump(len=16): [REMOVED] wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c key_idx=1 set_tx=1 seq_len=0 key_len=16 nl80211: KEY_DATA - hexdump(len=16): [REMOVED] broadcast key Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] WPA: Assign ANonce - hexdump(len=32): fb e1 df 2b 38 e9 ba 57 64 eb f9 e1 8b 6b c8 1e 45 88 b9 6f 77 d1 85 8a ad fb f2 1f e0 ec 3e 42 WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0) WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) wlan0: Event EAPOL_RX (26) received IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 IEEE 802.1X: version=1 type=3 length=117 WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 key_data_length=22 WPA: Received Key Nonce - hexdump(len=32): 5c b7 3a 2d 8b ec 17 5f 68 44 0e 88 7c 0a 42 67 a7 aa 59 f6 17 64 ab a5 85 8b 86 5c 84 8d 5d 84 WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Add randomness: count=4 entropy=0 random pool - hexdump(len=128): [REMOVED] random_mix_pool - hexdump(len=8): [REMOVED] random_mix_pool - hexdump(len=32): [REMOVED] random pool - hexdump(len=128): [REMOVED] WPA: Reject 4-way handshake to collect more entropy for random number generation random: Mark internal entropy pool to be ready (count=2/2) wpa_sta_disconnect STA 00:27:10:75:60:28 hostapd_wpa_auth_disconnect: WPA authenticator requests disconnect: STA 00:27:10:75:60:28 reason 2 nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) wlan0: STA 00:27:10:75:60:28 WPA: event 3 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port ap_sta_disconnect: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (5 seconds - AP_MAX_INACTIVITY_AFTER_DEAUTH) Removing STA 00:27:10:75:60:28 from kernel driver nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) wlan0: STA 00:27:10:75:60:28 MLME: MLME-DEAUTHENTICATE.indication(00:27:10:75:60:28, 2) wlan0: STA 00:27:10:75:60:28 MLME: MLME-DELETEKEYS.request(00:27:10:75:60:28) wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 nl80211: Event message available nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 nl80211: Delete station 00:27:10:75:60:28 wlan0: Event DISASSOC (1) received wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 nl80211: Event message available nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 nl80211: New station 00:27:10:75:60:28 nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 01 00 wlan0: Event ASSOC (0) received Add randomness: count=5 entropy=1 random pool - hexdump(len=128): [REMOVED] random_mix_pool - hexdump(len=8): [REMOVED] random_mix_pool - hexdump(len=6): [REMOVED] random pool - hexdump(len=128): [REMOVED] wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated STA included RSN IE in (Re)AssocReq New STA ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS wlan0: STA 00:27:10:75:60:28 WPA: start authentication WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 WPA: Re-initialize GMK/Counter on first station random: Cannot read from /dev/random: Resource temporarily unavailable random: Got 0/2 bytes from /dev/random random: Only 18/20 bytes of strong random data available from /dev/random random: Allow operation to proceed based on internal entropy Get randomness: len=32 entropy=2 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] GMK - hexdump(len=32): [REMOVED] Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] Key Counter - hexdump(len=32): [REMOVED] Get randomness: len=16 entropy=0 random from os_get_random - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=16): [REMOVED] GTK - hexdump(len=16): [REMOVED] wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c key_idx=1 set_tx=1 seq_len=0 key_len=16 nl80211: KEY_DATA - hexdump(len=16): [REMOVED] broadcast key Get randomness: len=32 entropy=0 random from os_get_random - hexdump(len=32): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] random_mix_pool - hexdump(len=20): [REMOVED] random from internal pool - hexdump(len=16): [REMOVED] mixed random - hexdump(len=32): [REMOVED] WPA: Assign ANonce - hexdump(len=32): 1f a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f d4 55 7d 6f 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0) WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) wlan0: Event EAPOL_RX (26) received IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 IEEE 802.1X: version=1 type=3 length=117 WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 key_data_length=22 WPA: Received Key Nonce - hexdump(len=32): 76 5f b9 4a d6 73 01 1e d4 bd 44 78 c4 35 41 05 eb 57 c2 6c 70 4b be bb 3a 36 bc 56 35 23 35 0c WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 Add randomness: count=6 entropy=0 random pool - hexdump(len=128): [REMOVED] random_mix_pool - hexdump(len=8): [REMOVED] random_mix_pool - hexdump(len=32): [REMOVED] random pool - hexdump(len=128): [REMOVED] wlan0: STA 00:27:10:75:60:28 WPA: received EAPOL-Key frame (2/4 Pairwise) WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) WPA: PTK derivation - A1=00:03:7f:be:f0:a0 A2=00:27:10:75:60:28 WPA: Nonce1 - hexdump(len=32): 1f a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f d4 55 7d 6f 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 WPA: Nonce2 - hexdump(len=32): 76 5f b9 4a d6 73 01 1e d4 bd 44 78 c4 35 41 05 eb 57 c2 6c 70 4b be bb 3a 36 bc 56 35 23 35 0c WPA: PMK - hexdump(len=32): [REMOVED] WPA: PTK - hexdump(len=48): [REMOVED] WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING2 WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKINITNEGOTIATING wlan0: STA 00:27:10:75:60:28 WPA: sending 3/4 msg of 4-Way Handshake WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=1 kde_len=46 keyidx=1 encr=1) Plaintext EAPOL-Key Key Data - hexdump(len=56): [REMOVED] WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) nl80211: Event message available nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 nl80211: Delete station 00:27:10:75:60:28 wlan0: Event DISASSOC (1) received wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 addr=00:27:10:75:60:28 wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 And here is the capture from wireshark from the Laptop: No. Time Source Destination Protocol Length Info 5 6.248045 AtherosC_be:f0:a0 IntelCor_75:60:28 EAPOL 113 Key (msg 1/4) Frame 5: 113 bytes on wire (904 bits), 113 bytes captured (904 bits) Arrival Time: Dec 2, 2014 18:53:45.046303000 UTC Epoch Time: 1417546425.046303000 seconds [Time delta from previous captured frame: 3.113203000 seconds] [Time delta from previous displayed frame: 3.113203000 seconds] [Time since reference or first frame: 6.248045000 seconds] Frame Number: 5 Frame Length: 113 bytes (904 bits) Capture Length: 113 bytes (904 bits) [Frame is marked: True] [Frame is ignored: False] [Protocols in frame: eth:eapol] Ethernet II, Src: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0), Dst: IntelCor_75:60:28 (00:27:10:75:60:28) Destination: IntelCor_75:60:28 (00:27:10:75:60:28) Address: IntelCor_75:60:28 (00:27:10:75:60:28) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) Address: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: 802.1X Authentication (0x888e) 802.1X Authentication Version: 2 Type: Key (3) Length: 95 Descriptor Type: EAPOL RSN key (2) Key Information: 0x008a .... .... .... .010 = Key Descriptor Version: HMAC-SHA1 for MIC and AES key wrap for encryption (2) .... .... .... 1... = Key Type: Pairwise key .... .... ..00 .... = Key Index: 0 .... .... .0.. .... = Install flag: Not set .... .... 1... .... = Key Ack flag: Set .... ...0 .... .... = Key MIC flag: Not set .... ..0. .... .... = Secure flag: Not set .... .0.. .... .... = Error flag: Not set .... 0... .... .... = Request flag: Not set ...0 .... .... .... = Encrypted Key Data flag: Not set Key Length: 16 Replay Counter: 1 Nonce: 1fa41c8042c3fe2b643e5c236fd4557d6f1621052d88082a... Key IV: 00000000000000000000000000000000 WPA Key RSC: 0000000000000000 WPA Key ID: 0000000000000000 WPA Key MIC: 00000000000000000000000000000000 WPA Key Length: 0 0000 00 27 10 75 60 28 00 03 7f be f0 a0 88 8e 02 03 .'.u`(.......... 0010 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 1f ._.............. 0020 a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f d4 55 7d 6f ...B..+d>\#o.U}o 0030 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 00 .!.-..*..Gk~R&a. 0040 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 0050 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 0060 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 0070 00 . No. Time Source Destination Protocol Length Info 6 6.249396 IntelCor_75:60:28 AtherosC_be:f0:a0 EAPOL 135 Key (msg 2/4) Frame 6: 135 bytes on wire (1080 bits), 135 bytes captured (1080 bits) Arrival Time: Dec 2, 2014 18:53:45.047654000 UTC Epoch Time: 1417546425.047654000 seconds [Time delta from previous captured frame: 0.001351000 seconds] [Time delta from previous displayed frame: 0.001351000 seconds] [Time since reference or first frame: 6.249396000 seconds] Frame Number: 6 Frame Length: 135 bytes (1080 bits) Capture Length: 135 bytes (1080 bits) [Frame is marked: True] [Frame is ignored: False] [Protocols in frame: eth:eapol] Ethernet II, Src: IntelCor_75:60:28 (00:27:10:75:60:28), Dst: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) Destination: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) Address: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: IntelCor_75:60:28 (00:27:10:75:60:28) Address: IntelCor_75:60:28 (00:27:10:75:60:28) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: 802.1X Authentication (0x888e) 802.1X Authentication Version: 1 Type: Key (3) Length: 117 Descriptor Type: EAPOL RSN key (2) Key Information: 0x010a .... .... .... .010 = Key Descriptor Version: HMAC-SHA1 for MIC and AES key wrap for encryption (2) .... .... .... 1... = Key Type: Pairwise key .... .... ..00 .... = Key Index: 0 .... .... .0.. .... = Install flag: Not set .... .... 0... .... = Key Ack flag: Not set .... ...1 .... .... = Key MIC flag: Set .... ..0. .... .... = Secure flag: Not set .... .0.. .... .... = Error flag: Not set .... 0... .... .... = Request flag: Not set ...0 .... .... .... = Encrypted Key Data flag: Not set Key Length: 0 Replay Counter: 1 Nonce: 765fb94ad673011ed4bd4478c4354105eb57c26c704bbebb... Key IV: 00000000000000000000000000000000 WPA Key RSC: 0000000000000000 WPA Key ID: 0000000000000000 WPA Key MIC: 7ac0bc8b21e5af46ff4e82259762ad8a WPA Key Length: 22 WPA Key: 30140100000fac040100000fac040100000fac020000 Tag: RSN Information Tag Number: RSN Information (48) Tag length: 20 RSN Version: 1 Group Cipher Suite: 00-0f-ac (Ieee8021) AES (CCM) Group Cipher Suite OUI: 00-0f-ac (Ieee8021) Group Cipher Suite type: AES (CCM) (4) Pairwise Cipher Suite Count: 1 Pairwise Cipher Suite List 00-0f-ac (Ieee8021) AES (CCM) Pairwise Cipher Suite: 00-0f-ac (Ieee8021) AES (CCM) Pairwise Cipher Suite OUI: 00-0f-ac (Ieee8021) Pairwise Cipher Suite type: AES (CCM) (4) Auth Key Management (AKM) Suite Count: 1 Auth Key Management (AKM) List 00-0f-ac (Ieee8021) PSK Auth Key Management (AKM) Suite: 00-0f-ac (Ieee8021) PSK Auth Key Management (AKM) OUI: 00-0f-ac (Ieee8021) Auth Key Management (AKM) type: PSK (2) RSN Capabilities: 0x0000 .... .... .... ...0 = RSN Pre-Auth capabilities: Transmitter does not support pre-authentication .... .... .... ..0. = RSN No Pairwise capabilities: Transmitter can support WEP default key 0 simultaneously with Pairwise key .... .... .... 00.. = RSN PTKSA Replay Counter capabilities: 1 replay counter per PTKSA/GTKSA/STAKeySA (0x0000) .... .... ..00 .... = RSN GTKSA Replay Counter capabilities: 1 replay counter per PTKSA/GTKSA/STAKeySA (0x0000) .... .... .0.. .... = Management Frame Protection Required: False .... .... 0... .... = Management Frame Protection Capable: False .... ..0. .... .... = PeerKey Enabled: False 0000 00 03 7f be f0 a0 00 27 10 75 60 28 88 8e 01 03 .......'.u`(.... 0010 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 76 .u.............v 0020 5f b9 4a d6 73 01 1e d4 bd 44 78 c4 35 41 05 eb _.J.s....Dx.5A.. 0030 57 c2 6c 70 4b be bb 3a 36 bc 56 35 23 35 0c 00 W.lpK..:6.V5#5.. 0040 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 0050 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7a ...............z 0060 c0 bc 8b 21 e5 af 46 ff 4e 82 25 97 62 ad 8a 00 ...!..F.N.%.b... 0070 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 .0.............. 0080 00 00 0f ac 02 00 00 ....... No. Time Source Destination Protocol Length Info 7 6.586001 AtherosC_be:f0:a0 IntelCor_75:60:28 EAPOL 169 Key (msg 3/4) Frame 7: 169 bytes on wire (1352 bits), 169 bytes captured (1352 bits) Arrival Time: Dec 2, 2014 18:53:45.384259000 UTC Epoch Time: 1417546425.384259000 seconds [Time delta from previous captured frame: 0.336605000 seconds] [Time delta from previous displayed frame: 0.336605000 seconds] [Time since reference or first frame: 6.586001000 seconds] Frame Number: 7 Frame Length: 169 bytes (1352 bits) Capture Length: 169 bytes (1352 bits) [Frame is marked: True] [Frame is ignored: False] [Protocols in frame: eth:eapol] Ethernet II, Src: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0), Dst: IntelCor_75:60:28 (00:27:10:75:60:28) Destination: IntelCor_75:60:28 (00:27:10:75:60:28) Address: IntelCor_75:60:28 (00:27:10:75:60:28) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) Address: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: 802.1X Authentication (0x888e) 802.1X Authentication Version: 2 Type: Key (3) Length: 151 Descriptor Type: EAPOL RSN key (2) Key Information: 0x13ca .... .... .... .010 = Key Descriptor Version: HMAC-SHA1 for MIC and AES key wrap for encryption (2) .... .... .... 1... = Key Type: Pairwise key .... .... ..00 .... = Key Index: 0 .... .... .1.. .... = Install flag: Set .... .... 1... .... = Key Ack flag: Set .... ...1 .... .... = Key MIC flag: Set .... ..1. .... .... = Secure flag: Set .... .0.. .... .... = Error flag: Not set .... 0... .... .... = Request flag: Not set ...1 .... .... .... = Encrypted Key Data flag: Set Key Length: 16 Replay Counter: 2 Nonce: 1fa41c8042c3fe2b643e5c236fd4557d6f1621052d88082a... Key IV: 00000000000000000000000000000000 WPA Key RSC: 0000000000000000 WPA Key ID: 0000000000000000 WPA Key MIC: 59ea20877a955f12c8947d38d1235da9 WPA Key Length: 56 WPA Key: d95c249b5103ec9f435a98d90c728ddc239d9b68598447f5... 0000 00 27 10 75 60 28 00 03 7f be f0 a0 88 8e 02 03 .'.u`(.......... 0010 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 1f ................ 0020 a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f d4 55 7d 6f ...B..+d>\#o.U}o 0030 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 00 .!.-..*..Gk~R&a. 0040 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 0050 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 59 ...............Y 0060 ea 20 87 7a 95 5f 12 c8 94 7d 38 d1 23 5d a9 00 . .z._...}8.#].. 0070 38 d9 5c 24 9b 51 03 ec 9f 43 5a 98 d9 0c 72 8d 8.\$.Q...CZ...r. 0080 dc 23 9d 9b 68 59 84 47 f5 6e 58 95 aa 71 e7 b5 .#..hY.G.nX..q.. 0090 cd 08 e2 29 19 d1 f7 77 fc 62 3c ea 30 00 c3 f4 ...)...w.b<.0... 00a0 22 6d 0b 0b 97 6d 01 de e3 "m...m... ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-02 19:18 ` Brent Taylor @ 2014-12-02 19:39 ` Krishna Chaitanya 2014-12-02 22:35 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Krishna Chaitanya @ 2014-12-02 19:39 UTC (permalink / raw) To: Brent Taylor; +Cc: Kalle Valo, ath6kl, linux-wireless On Wed, Dec 3, 2014 at 12:48 AM, Brent Taylor <motobud@gmail.com> wrote: > On Mon, Dec 1, 2014 at 1:00 PM, Krishna Chaitanya > <chaitanya.mgit@gmail.com> wrote: >> On Tue, Dec 2, 2014 at 12:19 AM, Brent Taylor <motobud@gmail.com> wrote: >>> >>> On Mon, Nov 10, 2014 at 12:12 PM, Brent Taylor <motobud@gmail.com> wrote: >>> > Resending in plain text mode... >>> > >>> > Hi Kalle, >>> > I have a custom built board with an AR6003 WiFi chip running >>> > linux-3.10.40 and hostapd 2.2 that I'm trying to use as an access >>> > point. >>> > >>> > My configuration file for hostapd is: >>> > interface=wlan0 >>> > driver=nl80211 >>> > ssid=testssid >>> > channel=1 >>> > own_ip_addr=********** >>> > auth_algs=1 >>> > wpa=2 >>> > wpa_passphrase=********** >>> > wpa_key_mgmt=WPA-PSK >>> > wpa_pairwise=TKIP >>> > rns_pairwise=CCMP >>> > ignore_broadcase_ssid=1 >>> > >>> > I should mention that this configuration works with a USB WiFi stick >>> > with an ath9k chipset running the same linux kernel and hostapd >>> > binary. >>> > >>> > When using the AR6003 chipset the client Laptop (HP EliteBook 8440p >>> > running ubuntu 12.04.3 LTS) does not connect and prints the message: >>> > wlan0: disassociating from <device mac> by local choice (reason=17) >>> > >>> > Running Wireshark on the Laptop, I see "Key (msg 1/4)", "Key (msg >>> > 2/4)", and then "Key (msg 3/4)" before it tries to reauthenticate. >>> > >>> > On the access point device, I see the logs from WAP that message 3/4 >>> > was sent and the next message received is NL80211_CMD_DEL_STATION. >>> > >>> > I've searched online for the reason=17 and haven't come across an >>> > answer yet. The access point device is not connected to a computer >>> > that is connected to the internet, so while getting log files is >>> > possible, it 's just not the easiest thing to do. If requested, I >>> > could get a log dump from hostapd and/or debug output from the ath6kl >>> > driver. >>> > >>> > Thanks for any help >>> > Brent Taylor >>> >>> >>> Does anyone know where the "reason=17" comes from? I traced it down >>> in the kernel and it looks like it comes from the WIFI Chip in the >>> Laptop, but I can not find what it means. >>> >>> Is there more information from somewhere that I provide to get some >>> help with this? >>> >>> Thanks, >>> Brent >> >> >> Reason code 17 means: >> element in 4-Way Handshake different from (Re)Association >> Request/Probe Response/Beacon frame. >> >> When the MSG 3/4 is received hostapd valdiates the IE stored from the >> beacon/probe and if mismatch it issues deauth with reason code 17. >> >> wpa_supplicant_process_3_of_4: wpa_supplicant_validate_ie in >> hostapd/src/rsn_supp/wpa.c >> Sniffer capture and hostapd logs might help to try to understand what >> is mismatched. > > Thanks for the explanation, here is a capture from hostapd-2.2 > > [linux][~> hostapd -dddd /etc/myapd.conf > random: Trying to read entropy from /dev/random > Configuration file: /etc/myapd.conf > nl80211: Could not add multicast membership for vendor events: -2 (No > such file or directory) > rfkill: Cannot open RFKILL control device > nl80211: RFKILL status not available > nl80211: Using driver-based roaming > 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: Supported cipher 00-40-96:255 > nl80211: Supported cipher 00-14-72:1 > nl80211: Supports Probe Response offload in AP mode > nl80211: Disable use_monitor with device_ap_sme since no monitor mode > support detected > nl80211: interface wlan0 in phy phy1 > nl80211: Set mode ifindex 4 iftype 3 (AP) > nl80211: Setup AP(wlan0) - device_ap_sme=1 use_monitor=0 > nl80211: Subscribe to mgmt frames with AP handle 0x6807c8 (device SME) > nl80211: Register frame type=0xd0 nl_handle=0x6807c8 match= > nl80211: Enable Probe Request reporting nl_preq=0x67f8c8 > nl80211: Register frame type=0x40 nl_handle=0x67f8c8 match= > nl80211: Add own interface ifindex 4 > nl80211: if_indices[16]: 4 > phy: phy1 > BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits) > nl80211: Regulatory information - country=00 > nl80211: 2402-2472 @ 40 MHz 20 mBm > nl80211: 2457-2482 @ 40 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: 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 > Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm > Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm > Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm > Completing interface initialization > Mode: IEEE 802.11b Channel: 1 Frequency: 2412 MHz > DFS 0 channels required radar detection > nl80211: Set freq 2412 (ht_enabled=0, vht_enabled=0, bandwidth=20 MHz, > cf1=2412 MHz, cf2=0 MHz) > RATE[0] rate=10 flags=0x1 > RATE[1] rate=20 flags=0x1 > RATE[2] rate=55 flags=0x0 > RATE[3] rate=110 flags=0x0 > hostapd_setup_bss(hapd=0x67fdd8 (wlan0), first=1) > wlan0: Flushing old station entries > nl80211: flush -> DEL_STATION wlan0 (all) > wlan0: Deauthenticate all stations > nl80211: sta_remove -> DEL_STATION wlan0 ff:ff:ff:ff:ff:ff --> 0 (Success) > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) > key_idx=0 set_tx=0 seq_len=0 key_len=0 > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) > key_idx=1 set_tx=0 seq_len=0 key_len=0 > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) > key_idx=2 set_tx=0 seq_len=0 key_len=0 > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) > key_idx=3 set_tx=0 seq_len=0 key_len=0 > Using interface wlan0 with hwaddr 00:03:7f:be:f0:a0 and ssid "testssid" > Deriving WPA PSK based on passphrase > SSID - hexdump_ascii(len=8): > 74 65 73 74 73 73 69 64 testssid > PSK (ASCII passphrase) - hexdump_ascii(len=10): [REMOVED] > PSK (from passphrase) - hexdump(len=32): [REMOVED] > random: Got 18/20 bytes from /dev/random > random: Only 18/20 bytes of strong random data available from /dev/random > random: Not enough entropy pool available for secure operations > WPA: Not enough entropy in random pool for secure operations - update > keys later when the first station connects > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > GMK - hexdump(len=32): [REMOVED] > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > Key Counter - hexdump(len=32): [REMOVED] > WPA: Delay group state machine start until Beacon frames have been configured > nl80211: Set beacon (beacon_set=0) > nl80211: Beacon head - hexdump(len=55): 80 00 00 00 ff ff ff ff ff ff > 00 03 7f be f0 a0 00 03 7f be f0 a0 00 00 00 00 00 00 00 00 00 00 64 > 00 11 00 00 08 74 65 73 74 73 73 69 64 01 04 82 84 0b 16 03 01 01 > nl80211: Beacon tail - 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: ifindex=4 > nl80211: beacon_int=100 > nl80211: dtim_period=2 > nl80211: ssid - hexdump_ascii(len=8): > 74 65 73 74 73 73 69 64 testssid > nl80211: proberesp (offload) - hexdump(len=77): 50 00 00 00 00 00 00 > 00 00 00 00 03 7f be f0 a0 00 03 7f be f0 a0 00 00 00 00 00 00 00 00 > 00 00 64 00 11 00 00 08 74 65 73 74 73 73 69 64 01 04 82 84 0b 16 03 > 01 01 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 > 00 > nl80211: hidden SSID not in use > nl80211: privacy=1 > nl80211: auth_algs=0x1 > nl80211: wpa_version=0x2 > nl80211: key_mgmt_suites=0x2 > nl80211: pairwise_ciphers=0x18 > nl80211: group_cipher=0x10 > WPA: Start group state machine to set initial keys > WPA: group state machine entering state GTK_INIT (VLAN-ID 0) > Get randomness: len=16 entropy=0 > random from os_get_random - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=16): [REMOVED] > GTK - hexdump(len=16): [REMOVED] > WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0) > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c > key_idx=1 set_tx=1 seq_len=0 key_len=16 > nl80211: KEY_DATA - hexdump(len=16): [REMOVED] > broadcast key > nl80211: Set wlan0 operstate 0->1 (UP) > netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=6 (IF_OPER_UP) > Failed to set TX queue parameters for queue 0. > Failed to set TX queue parameters for queue 1. > Failed to set TX queue parameters for queue 2. > Failed to set TX queue parameters for queue 3. > wlan0: interface state UNINITIALIZED->ENABLED > wlan0: AP-ENABLED > wlan0: Setup of interface done. > ctrl_iface not configured! > nl80211: Event message available > nl80211: Drv Event 88 (NL80211_CMD_CH_SWITCH_NOTIFY) received for wlan0 > nl80211: Channel switch event > wlan0: Event CH_SWITCH (41) received > wlan0: IEEE 802.11 driver had channel switch: freq=2412, ht=1, > offset=0, width=1, cf1=2412, cf2=0 > > > nl80211: Event message available > nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 > nl80211: New station 00:27:10:75:60:28 > nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 > 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f > ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 > 01 00 > wlan0: Event ASSOC (0) received > Add randomness: count=1 entropy=0 > random pool - hexdump(len=128): [REMOVED] > random_mix_pool - hexdump(len=8): [REMOVED] > random_mix_pool - hexdump(len=6): [REMOVED] > random pool - hexdump(len=128): [REMOVED] > wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated > STA included RSN IE in (Re)AssocReq > New STA > ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 > (300 seconds - ap_max_inactivity) > wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS > wlan0: STA 00:27:10:75:60:28 WPA: start authentication > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE > WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION > WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 > WPA: Re-initialize GMK/Counter on first station > random: Cannot read from /dev/random: Resource temporarily unavailable > random: Got 0/2 bytes from /dev/random > random: Only 18/20 bytes of strong random data available from /dev/random > random: Not enough entropy pool available for secure operations > WPA: Not enough entropy in random pool to proceed - reject first 4-way handshake > Get randomness: len=32 entropy=1 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > GMK - hexdump(len=32): [REMOVED] > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > Key Counter - hexdump(len=32): [REMOVED] > Get randomness: len=16 entropy=0 > random from os_get_random - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=16): [REMOVED] > GTK - hexdump(len=16): [REMOVED] > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c > key_idx=1 set_tx=1 seq_len=0 key_len=16 > nl80211: KEY_DATA - hexdump(len=16): [REMOVED] > broadcast key > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > WPA: Assign ANonce - hexdump(len=32): 60 b4 5b f8 1d 19 7e e6 ba 2e a8 > ec b7 a3 69 e5 37 dc a8 c1 4f b2 16 79 73 67 c4 56 a2 2e 55 dd > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART > wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake > WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 > kde_len=0 keyidx=0 encr=0) > WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) > hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for > 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) > wlan0: Event EAPOL_RX (26) received > IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 > IEEE 802.1X: version=1 type=3 length=117 > WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 > key_data_length=22 > WPA: Received Key Nonce - hexdump(len=32): 40 6b 10 a0 8f a6 a5 75 6f > 72 a0 86 ae ff e1 3e 2c 66 79 77 20 94 27 1c 7f 7d fa e3 61 88 6d b2 > WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 > Add randomness: count=2 entropy=0 > random pool - hexdump(len=128): [REMOVED] > random_mix_pool - hexdump(len=8): [REMOVED] > random_mix_pool - hexdump(len=32): [REMOVED] > random pool - hexdump(len=128): [REMOVED] > WPA: Reject 4-way handshake to collect more entropy for random number generation > random: Mark internal entropy pool to be ready (count=1/2) > wpa_sta_disconnect STA 00:27:10:75:60:28 > hostapd_wpa_auth_disconnect: WPA authenticator requests disconnect: > STA 00:27:10:75:60:28 reason 2 > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > wlan0: STA 00:27:10:75:60:28 WPA: event 3 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > ap_sta_disconnect: reschedule ap_handle_timer timeout for > 00:27:10:75:60:28 (5 seconds - AP_MAX_INACTIVITY_AFTER_DEAUTH) > Removing STA 00:27:10:75:60:28 from kernel driver > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > wlan0: STA 00:27:10:75:60:28 MLME: > MLME-DEAUTHENTICATE.indication(00:27:10:75:60:28, 2) > wlan0: STA 00:27:10:75:60:28 MLME: MLME-DELETEKEYS.request(00:27:10:75:60:28) > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > nl80211: Event message available > nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 > nl80211: Delete station 00:27:10:75:60:28 > wlan0: Event DISASSOC (1) received > wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated > wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 > nl80211: Event message available > nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 > nl80211: New station 00:27:10:75:60:28 > nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 > 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f > ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 > 01 00 > wlan0: Event ASSOC (0) received > Add randomness: count=3 entropy=1 > random pool - hexdump(len=128): [REMOVED] > random_mix_pool - hexdump(len=8): [REMOVED] > random_mix_pool - hexdump(len=6): [REMOVED] > random pool - hexdump(len=128): [REMOVED] > wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated > STA included RSN IE in (Re)AssocReq > New STA > ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 > (300 seconds - ap_max_inactivity) > wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS > wlan0: STA 00:27:10:75:60:28 WPA: start authentication > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE > WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION > WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 > WPA: Re-initialize GMK/Counter on first station > random: Cannot read from /dev/random: Resource temporarily unavailable > random: Got 0/2 bytes from /dev/random > random: Only 18/20 bytes of strong random data available from /dev/random > random: Not enough entropy pool available for secure operations > WPA: Not enough entropy in random pool to proceed - reject first 4-way handshake > Get randomness: len=32 entropy=2 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > GMK - hexdump(len=32): [REMOVED] > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > Key Counter - hexdump(len=32): [REMOVED] > Get randomness: len=16 entropy=0 > random from os_get_random - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=16): [REMOVED] > GTK - hexdump(len=16): [REMOVED] > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c > key_idx=1 set_tx=1 seq_len=0 key_len=16 > nl80211: KEY_DATA - hexdump(len=16): [REMOVED] > broadcast key > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > WPA: Assign ANonce - hexdump(len=32): fb e1 df 2b 38 e9 ba 57 64 eb f9 > e1 8b 6b c8 1e 45 88 b9 6f 77 d1 85 8a ad fb f2 1f e0 ec 3e 42 > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART > wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake > WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 > kde_len=0 keyidx=0 encr=0) > WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) > hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for > 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) > wlan0: Event EAPOL_RX (26) received > IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 > IEEE 802.1X: version=1 type=3 length=117 > WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 > key_data_length=22 > WPA: Received Key Nonce - hexdump(len=32): 5c b7 3a 2d 8b ec 17 5f 68 > 44 0e 88 7c 0a 42 67 a7 aa 59 f6 17 64 ab a5 85 8b 86 5c 84 8d 5d 84 > WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 > Add randomness: count=4 entropy=0 > random pool - hexdump(len=128): [REMOVED] > random_mix_pool - hexdump(len=8): [REMOVED] > random_mix_pool - hexdump(len=32): [REMOVED] > random pool - hexdump(len=128): [REMOVED] > WPA: Reject 4-way handshake to collect more entropy for random number generation > random: Mark internal entropy pool to be ready (count=2/2) > wpa_sta_disconnect STA 00:27:10:75:60:28 > hostapd_wpa_auth_disconnect: WPA authenticator requests disconnect: > STA 00:27:10:75:60:28 reason 2 > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > wlan0: STA 00:27:10:75:60:28 WPA: event 3 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > ap_sta_disconnect: reschedule ap_handle_timer timeout for > 00:27:10:75:60:28 (5 seconds - AP_MAX_INACTIVITY_AFTER_DEAUTH) > Removing STA 00:27:10:75:60:28 from kernel driver > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > wlan0: STA 00:27:10:75:60:28 MLME: > MLME-DEAUTHENTICATE.indication(00:27:10:75:60:28, 2) > wlan0: STA 00:27:10:75:60:28 MLME: MLME-DELETEKEYS.request(00:27:10:75:60:28) > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > nl80211: Event message available > nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 > nl80211: Delete station 00:27:10:75:60:28 > wlan0: Event DISASSOC (1) received > wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated > wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 > nl80211: Event message available > nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 > nl80211: New station 00:27:10:75:60:28 > nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 > 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f > ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 > 01 00 > wlan0: Event ASSOC (0) received > Add randomness: count=5 entropy=1 > random pool - hexdump(len=128): [REMOVED] > random_mix_pool - hexdump(len=8): [REMOVED] > random_mix_pool - hexdump(len=6): [REMOVED] > random pool - hexdump(len=128): [REMOVED] > wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated > STA included RSN IE in (Re)AssocReq > New STA > ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 > (300 seconds - ap_max_inactivity) > wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS > wlan0: STA 00:27:10:75:60:28 WPA: start authentication > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE > WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION > WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 > WPA: Re-initialize GMK/Counter on first station > random: Cannot read from /dev/random: Resource temporarily unavailable > random: Got 0/2 bytes from /dev/random > random: Only 18/20 bytes of strong random data available from /dev/random > random: Allow operation to proceed based on internal entropy > Get randomness: len=32 entropy=2 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > GMK - hexdump(len=32): [REMOVED] > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > Key Counter - hexdump(len=32): [REMOVED] > Get randomness: len=16 entropy=0 > random from os_get_random - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=16): [REMOVED] > GTK - hexdump(len=16): [REMOVED] > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=3 addr=0xa305c > key_idx=1 set_tx=1 seq_len=0 key_len=16 > nl80211: KEY_DATA - hexdump(len=16): [REMOVED] > broadcast key > Get randomness: len=32 entropy=0 > random from os_get_random - hexdump(len=32): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > random_mix_pool - hexdump(len=20): [REMOVED] > random from internal pool - hexdump(len=16): [REMOVED] > mixed random - hexdump(len=32): [REMOVED] > WPA: Assign ANonce - hexdump(len=32): 1f a4 1c 80 42 c3 fe 2b 64 3e 5c > 23 6f d4 55 7d 6f 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART > wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake > WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 > kde_len=0 keyidx=0 encr=0) > WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) > hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for > 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) > wlan0: Event EAPOL_RX (26) received > IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 > IEEE 802.1X: version=1 type=3 length=117 > WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 > key_data_length=22 > WPA: Received Key Nonce - hexdump(len=32): 76 5f b9 4a d6 73 01 1e d4 > bd 44 78 c4 35 41 05 eb 57 c2 6c 70 4b be bb 3a 36 bc 56 35 23 35 0c > WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 > Add randomness: count=6 entropy=0 > random pool - hexdump(len=128): [REMOVED] > random_mix_pool - hexdump(len=8): [REMOVED] > random_mix_pool - hexdump(len=32): [REMOVED] > random pool - hexdump(len=128): [REMOVED] > wlan0: STA 00:27:10:75:60:28 WPA: received EAPOL-Key frame (2/4 Pairwise) > WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING > Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) > WPA: PTK derivation - A1=00:03:7f:be:f0:a0 A2=00:27:10:75:60:28 > WPA: Nonce1 - hexdump(len=32): 1f a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f > d4 55 7d 6f 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 > WPA: Nonce2 - hexdump(len=32): 76 5f b9 4a d6 73 01 1e d4 bd 44 78 c4 > 35 41 05 eb 57 c2 6c 70 4b be bb 3a 36 bc 56 35 23 35 0c > WPA: PMK - hexdump(len=32): [REMOVED] > WPA: PTK - hexdump(len=48): [REMOVED] > WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING2 > WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKINITNEGOTIATING > wlan0: STA 00:27:10:75:60:28 WPA: sending 3/4 msg of 4-Way Handshake > WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=1 > kde_len=46 keyidx=1 encr=1) > Plaintext EAPOL-Key Key Data - hexdump(len=56): [REMOVED] > WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) > nl80211: Event message available > nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 > nl80211: Delete station 00:27:10:75:60:28 > wlan0: Event DISASSOC (1) received > wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated > wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED > WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE > wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=0x6822c8 > key_idx=0 set_tx=1 seq_len=0 key_len=0 > addr=00:27:10:75:60:28 > wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port > nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) > ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 > > > And here is the capture from wireshark from the Laptop: > > No. Time Source Destination > Protocol Length Info > 5 6.248045 AtherosC_be:f0:a0 IntelCor_75:60:28 EAPOL > 113 Key (msg 1/4) > > Frame 5: 113 bytes on wire (904 bits), 113 bytes captured (904 bits) > Arrival Time: Dec 2, 2014 18:53:45.046303000 UTC > Epoch Time: 1417546425.046303000 seconds > [Time delta from previous captured frame: 3.113203000 seconds] > [Time delta from previous displayed frame: 3.113203000 seconds] > [Time since reference or first frame: 6.248045000 seconds] > Frame Number: 5 > Frame Length: 113 bytes (904 bits) > Capture Length: 113 bytes (904 bits) > [Frame is marked: True] > [Frame is ignored: False] > [Protocols in frame: eth:eapol] > Ethernet II, Src: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0), Dst: > IntelCor_75:60:28 (00:27:10:75:60:28) > Destination: IntelCor_75:60:28 (00:27:10:75:60:28) > Address: IntelCor_75:60:28 (00:27:10:75:60:28) > .... ...0 .... .... .... .... = IG bit: Individual address (unicast) > .... ..0. .... .... .... .... = LG bit: Globally unique > address (factory default) > Source: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > Address: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > .... ...0 .... .... .... .... = IG bit: Individual address (unicast) > .... ..0. .... .... .... .... = LG bit: Globally unique > address (factory default) > Type: 802.1X Authentication (0x888e) > 802.1X Authentication > Version: 2 > Type: Key (3) > Length: 95 > Descriptor Type: EAPOL RSN key (2) > Key Information: 0x008a > .... .... .... .010 = Key Descriptor Version: HMAC-SHA1 for > MIC and AES key wrap for encryption (2) > .... .... .... 1... = Key Type: Pairwise key > .... .... ..00 .... = Key Index: 0 > .... .... .0.. .... = Install flag: Not set > .... .... 1... .... = Key Ack flag: Set > .... ...0 .... .... = Key MIC flag: Not set > .... ..0. .... .... = Secure flag: Not set > .... .0.. .... .... = Error flag: Not set > .... 0... .... .... = Request flag: Not set > ...0 .... .... .... = Encrypted Key Data flag: Not set > Key Length: 16 > Replay Counter: 1 > Nonce: 1fa41c8042c3fe2b643e5c236fd4557d6f1621052d88082a... > Key IV: 00000000000000000000000000000000 > WPA Key RSC: 0000000000000000 > WPA Key ID: 0000000000000000 > WPA Key MIC: 00000000000000000000000000000000 > WPA Key Length: 0 > > 0000 00 27 10 75 60 28 00 03 7f be f0 a0 88 8e 02 03 .'.u`(.......... > 0010 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 1f ._.............. > 0020 a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f d4 55 7d 6f ...B..+d>\#o.U}o > 0030 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 00 .!.-..*..Gk~R&a. > 0040 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ > 0050 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ > 0060 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ > 0070 00 . > > No. Time Source Destination > Protocol Length Info > 6 6.249396 IntelCor_75:60:28 AtherosC_be:f0:a0 EAPOL > 135 Key (msg 2/4) > > Frame 6: 135 bytes on wire (1080 bits), 135 bytes captured (1080 bits) > Arrival Time: Dec 2, 2014 18:53:45.047654000 UTC > Epoch Time: 1417546425.047654000 seconds > [Time delta from previous captured frame: 0.001351000 seconds] > [Time delta from previous displayed frame: 0.001351000 seconds] > [Time since reference or first frame: 6.249396000 seconds] > Frame Number: 6 > Frame Length: 135 bytes (1080 bits) > Capture Length: 135 bytes (1080 bits) > [Frame is marked: True] > [Frame is ignored: False] > [Protocols in frame: eth:eapol] > Ethernet II, Src: IntelCor_75:60:28 (00:27:10:75:60:28), Dst: > AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > Destination: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > Address: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > .... ...0 .... .... .... .... = IG bit: Individual address (unicast) > .... ..0. .... .... .... .... = LG bit: Globally unique > address (factory default) > Source: IntelCor_75:60:28 (00:27:10:75:60:28) > Address: IntelCor_75:60:28 (00:27:10:75:60:28) > .... ...0 .... .... .... .... = IG bit: Individual address (unicast) > .... ..0. .... .... .... .... = LG bit: Globally unique > address (factory default) > Type: 802.1X Authentication (0x888e) > 802.1X Authentication > Version: 1 > Type: Key (3) > Length: 117 > Descriptor Type: EAPOL RSN key (2) > Key Information: 0x010a > .... .... .... .010 = Key Descriptor Version: HMAC-SHA1 for > MIC and AES key wrap for encryption (2) > .... .... .... 1... = Key Type: Pairwise key > .... .... ..00 .... = Key Index: 0 > .... .... .0.. .... = Install flag: Not set > .... .... 0... .... = Key Ack flag: Not set > .... ...1 .... .... = Key MIC flag: Set > .... ..0. .... .... = Secure flag: Not set > .... .0.. .... .... = Error flag: Not set > .... 0... .... .... = Request flag: Not set > ...0 .... .... .... = Encrypted Key Data flag: Not set > Key Length: 0 > Replay Counter: 1 > Nonce: 765fb94ad673011ed4bd4478c4354105eb57c26c704bbebb... > Key IV: 00000000000000000000000000000000 > WPA Key RSC: 0000000000000000 > WPA Key ID: 0000000000000000 > WPA Key MIC: 7ac0bc8b21e5af46ff4e82259762ad8a > WPA Key Length: 22 > WPA Key: 30140100000fac040100000fac040100000fac020000 > Tag: RSN Information > Tag Number: RSN Information (48) > Tag length: 20 > RSN Version: 1 > Group Cipher Suite: 00-0f-ac (Ieee8021) AES (CCM) > Group Cipher Suite OUI: 00-0f-ac (Ieee8021) > Group Cipher Suite type: AES (CCM) (4) > Pairwise Cipher Suite Count: 1 > Pairwise Cipher Suite List 00-0f-ac (Ieee8021) AES (CCM) > Pairwise Cipher Suite: 00-0f-ac (Ieee8021) AES (CCM) > Pairwise Cipher Suite OUI: 00-0f-ac (Ieee8021) > Pairwise Cipher Suite type: AES (CCM) (4) > Auth Key Management (AKM) Suite Count: 1 > Auth Key Management (AKM) List 00-0f-ac (Ieee8021) PSK > Auth Key Management (AKM) Suite: 00-0f-ac (Ieee8021) PSK > Auth Key Management (AKM) OUI: 00-0f-ac (Ieee8021) > Auth Key Management (AKM) type: PSK (2) > RSN Capabilities: 0x0000 > .... .... .... ...0 = RSN Pre-Auth capabilities: > Transmitter does not support pre-authentication > .... .... .... ..0. = RSN No Pairwise capabilities: > Transmitter can support WEP default key 0 simultaneously with Pairwise > key > .... .... .... 00.. = RSN PTKSA Replay Counter > capabilities: 1 replay counter per PTKSA/GTKSA/STAKeySA (0x0000) > .... .... ..00 .... = RSN GTKSA Replay Counter > capabilities: 1 replay counter per PTKSA/GTKSA/STAKeySA (0x0000) > .... .... .0.. .... = Management Frame Protection > Required: False > .... .... 0... .... = Management Frame Protection Capable: False > .... ..0. .... .... = PeerKey Enabled: False > > 0000 00 03 7f be f0 a0 00 27 10 75 60 28 88 8e 01 03 .......'.u`(.... > 0010 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 76 .u.............v > 0020 5f b9 4a d6 73 01 1e d4 bd 44 78 c4 35 41 05 eb _.J.s....Dx.5A.. > 0030 57 c2 6c 70 4b be bb 3a 36 bc 56 35 23 35 0c 00 W.lpK..:6.V5#5.. > 0040 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ > 0050 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7a ...............z > 0060 c0 bc 8b 21 e5 af 46 ff 4e 82 25 97 62 ad 8a 00 ...!..F.N.%.b... > 0070 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 .0.............. > 0080 00 00 0f ac 02 00 00 ....... > > No. Time Source Destination > Protocol Length Info > 7 6.586001 AtherosC_be:f0:a0 IntelCor_75:60:28 EAPOL > 169 Key (msg 3/4) > > Frame 7: 169 bytes on wire (1352 bits), 169 bytes captured (1352 bits) > Arrival Time: Dec 2, 2014 18:53:45.384259000 UTC > Epoch Time: 1417546425.384259000 seconds > [Time delta from previous captured frame: 0.336605000 seconds] > [Time delta from previous displayed frame: 0.336605000 seconds] > [Time since reference or first frame: 6.586001000 seconds] > Frame Number: 7 > Frame Length: 169 bytes (1352 bits) > Capture Length: 169 bytes (1352 bits) > [Frame is marked: True] > [Frame is ignored: False] > [Protocols in frame: eth:eapol] > Ethernet II, Src: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0), Dst: > IntelCor_75:60:28 (00:27:10:75:60:28) > Destination: IntelCor_75:60:28 (00:27:10:75:60:28) > Address: IntelCor_75:60:28 (00:27:10:75:60:28) > .... ...0 .... .... .... .... = IG bit: Individual address (unicast) > .... ..0. .... .... .... .... = LG bit: Globally unique > address (factory default) > Source: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > Address: AtherosC_be:f0:a0 (00:03:7f:be:f0:a0) > .... ...0 .... .... .... .... = IG bit: Individual address (unicast) > .... ..0. .... .... .... .... = LG bit: Globally unique > address (factory default) > Type: 802.1X Authentication (0x888e) > 802.1X Authentication > Version: 2 > Type: Key (3) > Length: 151 > Descriptor Type: EAPOL RSN key (2) > Key Information: 0x13ca > .... .... .... .010 = Key Descriptor Version: HMAC-SHA1 for > MIC and AES key wrap for encryption (2) > .... .... .... 1... = Key Type: Pairwise key > .... .... ..00 .... = Key Index: 0 > .... .... .1.. .... = Install flag: Set > .... .... 1... .... = Key Ack flag: Set > .... ...1 .... .... = Key MIC flag: Set > .... ..1. .... .... = Secure flag: Set > .... .0.. .... .... = Error flag: Not set > .... 0... .... .... = Request flag: Not set > ...1 .... .... .... = Encrypted Key Data flag: Set > Key Length: 16 > Replay Counter: 2 > Nonce: 1fa41c8042c3fe2b643e5c236fd4557d6f1621052d88082a... > Key IV: 00000000000000000000000000000000 > WPA Key RSC: 0000000000000000 > WPA Key ID: 0000000000000000 > WPA Key MIC: 59ea20877a955f12c8947d38d1235da9 > WPA Key Length: 56 > WPA Key: d95c249b5103ec9f435a98d90c728ddc239d9b68598447f5... > > 0000 00 27 10 75 60 28 00 03 7f be f0 a0 88 8e 02 03 .'.u`(.......... > 0010 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 1f ................ > 0020 a4 1c 80 42 c3 fe 2b 64 3e 5c 23 6f d4 55 7d 6f ...B..+d>\#o.U}o > 0030 16 21 05 2d 88 08 2a d1 ae 47 6b 7e 52 26 61 00 .!.-..*..Gk~R&a. > 0040 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ > 0050 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 59 ...............Y > 0060 ea 20 87 7a 95 5f 12 c8 94 7d 38 d1 23 5d a9 00 . .z._...}8.#].. > 0070 38 d9 5c 24 9b 51 03 ec 9f 43 5a 98 d9 0c 72 8d 8.\$.Q...CZ...r. > 0080 dc 23 9d 9b 68 59 84 47 f5 6e 58 95 aa 71 e7 b5 .#..hY.G.nX..q.. > 0090 cd 08 e2 29 19 d1 f7 77 fc 62 3c ea 30 00 c3 f4 ...)...w.b<.0... > 00a0 22 6d 0b 0b 97 6d 01 de e3 "m...m... Sorry i meant wpa_supplicant logs on STA side. Hostapd is sending 3/4 but STA is rejecting it. Is it possible to share the pcap file from connection attempt, we need to check the IE at connection time w.r.t M3/4? -- Thanks, Regards, Chaitanya T K. ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-02 19:39 ` Krishna Chaitanya @ 2014-12-02 22:35 ` Brent Taylor 2014-12-02 23:32 ` Dan Williams 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-12-02 22:35 UTC (permalink / raw) To: Krishna Chaitanya; +Cc: Kalle Valo, ath6kl, linux-wireless On Tue, Dec 2, 2014 at 1:39 PM, Krishna Chaitanya <chaitanya.mgit@gmail.com> wrote: > On Wed, Dec 3, 2014 at 12:48 AM, Brent Taylor <motobud@gmail.com> wrote: >> On Mon, Dec 1, 2014 at 1:00 PM, Krishna Chaitanya >> <chaitanya.mgit@gmail.com> wrote: >>> On Tue, Dec 2, 2014 at 12:19 AM, Brent Taylor <motobud@gmail.com> wrote: >>>> >>>> On Mon, Nov 10, 2014 at 12:12 PM, Brent Taylor <motobud@gmail.com> wrote: >>>> > Resending in plain text mode... >>>> > >>>> > Hi Kalle, >>>> > I have a custom built board with an AR6003 WiFi chip running >>>> > linux-3.10.40 and hostapd 2.2 that I'm trying to use as an access >>>> > point. >>>> > >>>> > My configuration file for hostapd is: >>>> > interface=wlan0 >>>> > driver=nl80211 >>>> > ssid=testssid >>>> > channel=1 >>>> > own_ip_addr=********** >>>> > auth_algs=1 >>>> > wpa=2 >>>> > wpa_passphrase=********** >>>> > wpa_key_mgmt=WPA-PSK >>>> > wpa_pairwise=TKIP >>>> > rns_pairwise=CCMP >>>> > ignore_broadcase_ssid=1 >>>> > >>>> > I should mention that this configuration works with a USB WiFi stick >>>> > with an ath9k chipset running the same linux kernel and hostapd >>>> > binary. >>>> > >>>> > When using the AR6003 chipset the client Laptop (HP EliteBook 8440p >>>> > running ubuntu 12.04.3 LTS) does not connect and prints the message: >>>> > wlan0: disassociating from <device mac> by local choice (reason=17) >>>> > >>>> > Running Wireshark on the Laptop, I see "Key (msg 1/4)", "Key (msg >>>> > 2/4)", and then "Key (msg 3/4)" before it tries to reauthenticate. >>>> > >>>> > On the access point device, I see the logs from WAP that message 3/4 >>>> > was sent and the next message received is NL80211_CMD_DEL_STATION. >>>> > >>>> > I've searched online for the reason=17 and haven't come across an >>>> > answer yet. The access point device is not connected to a computer >>>> > that is connected to the internet, so while getting log files is >>>> > possible, it 's just not the easiest thing to do. If requested, I >>>> > could get a log dump from hostapd and/or debug output from the ath6kl >>>> > driver. >>>> > >>>> > Thanks for any help >>>> > Brent Taylor >>>> >>>> >>>> Does anyone know where the "reason=17" comes from? I traced it down >>>> in the kernel and it looks like it comes from the WIFI Chip in the >>>> Laptop, but I can not find what it means. >>>> >>>> Is there more information from somewhere that I provide to get some >>>> help with this? >>>> >>>> Thanks, >>>> Brent >>> >>> >>> Reason code 17 means: >>> element in 4-Way Handshake different from (Re)Association >>> Request/Probe Response/Beacon frame. >>> >>> When the MSG 3/4 is received hostapd valdiates the IE stored from the >>> beacon/probe and if mismatch it issues deauth with reason code 17. >>> >>> wpa_supplicant_process_3_of_4: wpa_supplicant_validate_ie in >>> hostapd/src/rsn_supp/wpa.c >>> Sniffer capture and hostapd logs might help to try to understand what >>> is mismatched. >> -- snip old log information -- Laptop wpa_supplicant log: 1417557226.372027: Scan SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417557226.372135: nl80211: Scan SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417557226.372166: nl80211: Scan SSID - hexdump_ascii(len=0): [NULL] 1417557228.979261: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00 1417557228.979342: 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 1417557228.979370: FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=0): 1417557228.979845: * SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417557228.979880: * IEs - hexdump(len=0): [NULL] 1417557228.991852: nl80211: MLME event frame - hexdump(len=30): b0 08 3a 01 00 27 10 75 60 28 00 03 7f be f0 a0 00 03 7f be f0 a0 f0 38 00 00 02 00 00 00 1417557228.991905: SME: Authentication response IEs - hexdump(len=0): [NULL] 1417557228.992083: 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 1417557228.992159: * SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417557228.992195: * IEs - 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 1417557228.998296: nl80211: MLME event frame - hexdump(len=72): 30 00 3a 01 00 27 10 75 60 28 00 03 7f be f0 a0 00 03 7f be f0 a0 00 39 31 04 00 00 01 c0 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60 6c 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 1417557228.998395: resp_ies - hexdump(len=42): 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60 6c 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 1417557228.998452: FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=0): 1417557229.333000: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417557229.333182: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01 1417557229.333203: key_nonce - hexdump(len=32): d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 1417557229.333233: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417557229.333253: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 1417557229.333268: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1417557229.333282: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417557229.333304: WPA: RX EAPOL-Key - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417557229.333506: RSN: msg 1/4 key data - hexdump(len=0): 1417557229.334841: WPA: Renewed SNonce - hexdump(len=32): 74 40 89 6b ab de 2b 76 ef 09 1a 37 18 59 7b fa 4a 7e b1 f5 83 59 e5 f7 17 f1 80 11 7a e4 ae 6d 1417557229.334958: WPA: PMK - hexdump(len=32): b7 9d 92 a0 21 ad 3b ce 4b 49 e6 a9 01 ea 68 7f 97 4f ab d1 d7 b5 07 eb a1 05 00 40 60 0e d2 c5 1417557229.334992: WPA: PTK - hexdump(len=48): 27 51 b0 5b 2c 0a fa 68 f2 e1 e9 2c de 95 96 e4 1c 2c 9a a0 e3 b6 21 6f 48 e4 83 53 ff 4d 68 f9 cb e7 2f c3 91 2d 7a 48 28 cd fa 52 c8 8c ff b6 1417557229.335031: 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 1417557229.335081: WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 74 40 89 6b ab de 2b 76 ef 09 1a 37 18 59 7b fa 4a 7e b1 f5 83 59 e5 f7 17 f1 80 11 7a e4 ae 6d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 8b c7 41 7f 25 45 ed 64 9c 15 d7 25 32 50 30 85 00 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 1417557229.338749: RX EAPOL - hexdump(len=155): 02 03 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1d 01 37 5e 02 5d af 50 4b 48 87 d8 86 85 43 0f 00 38 72 53 14 63 c6 fd 9b 69 55 dd c0 4c fa 54 e7 e0 26 b2 9a 17 52 d2 c0 2c a6 89 eb 69 3e 7c a2 2f ba 47 72 09 7f 57 9f d1 82 38 ec 4c 42 1b e8 cc b4 a8 d9 a2 0f d9 7a cc 1417557229.338934: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 1417557229.338944: key_nonce - hexdump(len=32): d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 1417557229.338955: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417557229.338962: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 1417557229.338967: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1417557229.338973: key_mic - hexdump(len=16): 1d 01 37 5e 02 5d af 50 4b 48 87 d8 86 85 43 0f 1417557229.338983: WPA: RX EAPOL-Key - hexdump(len=155): 02 03 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1d 01 37 5e 02 5d af 50 4b 48 87 d8 86 85 43 0f 00 38 72 53 14 63 c6 fd 9b 69 55 dd c0 4c fa 54 e7 e0 26 b2 9a 17 52 d2 c0 2c a6 89 eb 69 3e 7c a2 2f ba 47 72 09 7f 57 9f d1 82 38 ec 4c 42 1b e8 cc b4 a8 d9 a2 0f d9 7a cc 1417557229.339056: RSN: encrypted key data - hexdump(len=56): 72 53 14 63 c6 fd 9b 69 55 dd c0 4c fa 54 e7 e0 26 b2 9a 17 52 d2 c0 2c a6 89 eb 69 3e 7c a2 2f ba 47 72 09 7f 57 9f d1 82 38 ec 4c 42 1b e8 cc b4 a8 d9 a2 0f d9 7a cc 1417557229.339095: WPA: decrypted EAPOL-Key key data - hexdump(len=48): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 16 00 0f ac 01 02 00 4f 1d 5e 07 73 b9 a8 28 2e c4 33 17 51 6a a7 2b dd 00 1417557229.339128: 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 00 00 dd 16 00 0f ac 01 02 00 4f 1d 5e 07 73 b9 a8 28 2e c4 33 17 51 6a a7 2b dd 00 1417557229.339147: 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 00 00 1417557229.339157: WPA: GTK in EAPOL-Key - hexdump(len=24): dd 16 00 0f ac 01 02 00 4f 1d 5e 07 73 b9 a8 28 2e c4 33 17 51 6a a7 2b 1417557229.339179: WPA: RSN IE in Beacon/ProbeResp - hexdump(len=26): 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00 1417557229.339192: WPA: RSN IE in 3/4 msg - 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 1417557229.388018: nl80211: MLME event frame - hexdump(len=26): a0 00 00 00 00 03 7f be f0 a0 00 27 10 75 60 28 00 03 7f be f0 a0 00 00 11 00 1417557229.389152: nl80211: MLME event frame - hexdump(len=26): c0 00 3a 01 00 27 10 75 60 28 00 03 7f be f0 a0 00 03 7f be f0 a0 50 39 11 00 Device Host APD 2.2 Log: 1417535885.275867: nl80211: Event message available 1417535885.275965: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 1417535885.275998: nl80211: New station 00:27:10:75:60:28 1417535885.276014: nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 01 00 1417535885.276113: wlan0: Event ASSOC (0) received 1417535885.276129: Add randomness: count=71 entropy=1 1417535885.276145: random pool - hexdump(len=128): 9a 14 47 7a e2 b2 dd b5 23 ab c0 71 69 25 c0 c7 e0 98 6d 40 58 75 55 3a 69 f1 1b 46 fe 96 04 d4 f9 b4 9d 66 c2 5d 2f 56 3f a3 61 3b 78 d9 4a b1 58 46 de af d9 13 f5 f9 dd ba 02 e3 4c 48 31 34 b6 2f 83 7c 0b e3 f4 20 3e 41 6c 3d 09 ce 3d 42 f1 f4 00 8a 57 c5 6c 0e 3f 72 e1 6b dd 07 a5 46 2d 79 29 c5 10 a0 a7 03 26 77 94 cb 1c 7b 97 09 c4 a6 84 f1 ae 36 0d 38 5f 96 c5 ae c5 e9 41 f5 1417535885.276293: random_mix_pool - hexdump(len=8): 8d e1 7d 54 b1 36 04 00 1417535885.276326: random_mix_pool - hexdump(len=6): 00 27 10 75 60 28 1417535885.276342: random pool - hexdump(len=128): 9a 14 47 7a e2 b2 dd b5 23 ab c0 71 69 25 c0 c7 e0 98 6d 40 58 75 55 3a 69 f1 1b 46 fe 96 04 d4 f9 b4 9d 66 c2 5d 2f 56 3f a3 61 3b 78 d9 4a b1 58 46 de af d9 13 f5 f9 e5 05 a3 26 bb 0e b2 ff 29 18 b2 37 23 af 73 26 98 cf 6e d8 7c 58 4a a8 2d 02 0c a0 39 24 00 14 bc 72 35 fa 1c ea 0c e8 2f 96 28 6e 1c db 67 73 c6 2b 18 38 60 77 68 6a c4 a6 84 f1 ae 36 0d 38 5f 96 c5 ae c5 e9 41 f5 1417535885.276490: 1417535885.276507: wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated 1417535885.276572: STA included RSN IE in (Re)AssocReq 1417535885.276589: New STA 1417535885.276605: ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) 1417535885.276671: 1417535885.276671: wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification 1417535885.276736: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x13e72c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417535885.276785: addr=00:27:10:75:60:28 1417535885.276868: IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS 1417535885.276900: 1417535885.276900: wlan0: STA 00:27:10:75:60:28 WPA: start authentication 1417535885.276917: WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE 1417535885.276966: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x13e72c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417535885.276999: addr=00:27:10:75:60:28 1417535885.277508: 1417535885.277508: wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port 1417535885.277540: WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE 1417535885.277557: WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION 1417535885.277573: WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 1417535885.277590: Get randomness: len=32 entropy=2 1417535885.280084: random from os_get_random - hexdump(len=32): b1 b2 5c 9b 30 5c 2b e8 d1 92 91 1e a8 62 f0 3c b4 ed 46 04 9d 50 c8 47 41 99 28 23 76 3f ec d4 1417535885.280199: random_mix_pool - hexdump(len=20): 0c 08 62 e3 5a 21 11 c8 66 98 5c 80 ca ab 4f a7 7c 35 a1 e4 1417535885.280264: random from internal pool - hexdump(len=16): 63 f2 90 ef 17 78 c9 91 be 2f 66 9c 73 0d 83 5e 1417535885.280314: random_mix_pool - hexdump(len=20): 44 55 9e 91 da 10 1e e7 e3 af 87 8a b8 7d 52 49 0d 32 56 41 1417535885.280379: random from internal pool - hexdump(len=16): 96 44 89 3b 18 71 85 d8 01 c7 62 79 d9 7a c1 37 1417535885.280412: mixed random - hexdump(len=32): d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 1417535885.280445: WPA: Assign ANonce - hexdump(len=32): d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 1417535885.280511: WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK 1417535885.280527: Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) 1417535885.280543: Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) 1417535885.280560: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART 1417535885.280593: 1417535885.280593: wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake 1417535885.614101: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0) 1417535885.614200: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) 1417535885.614249: hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) 1417535885.618942: wlan0: Event EAPOL_RX (26) received 1417535885.618991: IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 1417535885.619008: IEEE 802.1X: version=1 type=3 length=117 1417535885.619024: WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 key_data_length=22 1417535885.619057: WPA: Received Key Nonce - hexdump(len=32): 74 40 89 6b ab de 2b 76 ef 09 1a 37 18 59 7b fa 4a 7e b1 f5 83 59 e5 f7 17 f1 80 11 7a e4 ae 6d 1417535885.619106: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 1417535885.619123: Add randomness: count=72 entropy=0 1417535885.619139: random pool - hexdump(len=128): 25 b7 50 e6 d2 21 13 5f 80 58 9d 5b c7 65 36 43 94 3c f4 8f 51 7f 20 b4 49 e2 9d 03 15 0e 0c 6e b8 72 d2 4b cf 72 1d 49 03 42 02 29 fe 9d 82 17 96 6c a6 68 b6 dd 90 13 cd e9 7c 37 c0 46 7c 84 b4 b6 00 6f c6 f5 9e b4 83 38 d0 8a 0f a5 e6 15 1a 71 bf ed fd 59 0c 72 fd bc 40 4e ff 7b 4c 45 1a 53 f8 66 ad c2 15 13 2f 51 3a a9 b6 24 91 05 a4 fc 48 41 42 8e 8c d2 30 dd 1e 13 63 f9 1e 91 1417535885.619287: random_mix_pool - hexdump(len=8): 8d e1 7d 54 83 72 09 00 1417535885.619320: random_mix_pool - hexdump(len=32): 74 40 89 6b ab de 2b 76 ef 09 1a 37 18 59 7b fa 4a 7e b1 f5 83 59 e5 f7 17 f1 80 11 7a e4 ae 6d 1417535885.619369: random pool - hexdump(len=128): cd 8a 90 a8 9f 92 16 61 d4 00 7e 37 4a 0b d6 2a e0 72 24 b1 4e bf 9d 0e 31 9e 59 67 53 39 7f 5f 7a c0 27 28 c9 68 84 0d 0e 0e e4 54 82 28 df 17 4c 1b 24 83 d9 38 6a ef 6a cd f8 45 f8 6f b6 b4 3e 13 7c 76 4c 09 a1 6e 75 de ea 05 52 86 27 bf bc dc d6 26 95 de de 14 93 bb 02 af 92 e4 5b b7 3c 41 36 00 cc a8 9f 7a df 94 a9 12 21 d2 09 a4 61 ce ac 52 90 21 ce b5 4d 8e 5c e4 d2 93 aa 32 1417535885.619549: 1417535885.619549: wlan0: STA 00:27:10:75:60:28 WPA: received EAPOL-Key frame (2/4 Pairwise) 1417535885.619566: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING 1417535885.619599: Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) 1417535885.619697: WPA: PTK derivation - A1=00:03:7f:be:f0:a0 A2=00:27:10:75:60:28 1417535885.619713: WPA: Nonce1 - hexdump(len=32): d2 40 cc 74 27 24 e2 79 6f bd f7 82 db 6f 73 62 22 a9 cf 3f 85 21 4d 9f 40 5e 4a 5a af 45 2d e3 1417535885.619763: WPA: Nonce2 - hexdump(len=32): 74 40 89 6b ab de 2b 76 ef 09 1a 37 18 59 7b fa 4a 7e b1 f5 83 59 e5 f7 17 f1 80 11 7a e4 ae 6d 1417535885.619812: WPA: PMK - hexdump(len=32): b7 9d 92 a0 21 ad 3b ce 4b 49 e6 a9 01 ea 68 7f 97 4f ab d1 d7 b5 07 eb a1 05 00 40 60 0e d2 c5 1417535885.619861: WPA: PTK - hexdump(len=48): 27 51 b0 5b 2c 0a fa 68 f2 e1 e9 2c de 95 96 e4 1c 2c 9a a0 e3 b6 21 6f 48 e4 83 53 ff 4d 68 f9 cb e7 2f c3 91 2d 7a 48 28 cd fa 52 c8 8c ff b6 1417535885.619943: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING2 1417535885.619976: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKINITNEGOTIATING 1417535885.620157: 1417535885.620157: wlan0: STA 00:27:10:75:60:28 WPA: sending 3/4 msg of 4-Way Handshake 1417535885.620189: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=1 kde_len=46 keyidx=2 encr=1) 1417535885.620222: Plaintext EAPOL-Key Key Data - hexdump(len=56): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 16 00 0f ac 01 02 00 4f 1d 5e 07 73 b9 a8 28 2e c4 33 17 51 6a a7 2b dd 00 00 00 00 00 00 00 00 00 1417535885.620452: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) 1417535885.626113: nl80211: Event message available 1417535885.626245: nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 1417535885.626261: nl80211: Delete station 00:27:10:75:60:28 1417535885.626294: wlan0: Event DISASSOC (1) received 1417535885.959802: 1417535885.959802: wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated 1417535885.960065: 1417535885.960065: wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification 1417535885.960131: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x13e72c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417535885.960163: addr=00:27:10:75:60:28 1417535885.960245: WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED 1417535885.960262: WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE 1417535885.960311: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x13e72c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417535885.960344: addr=00:27:10:75:60:28 1417535885.960639: 1417535885.960639: wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port 1417535885.960803: nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) 1417535885.960918: ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 Thank you for looking into this. I'm not familiar with the details of the wpa_supplicant or the authentication handshake of a WPA WiFi link. -- Brent ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-02 22:35 ` Brent Taylor @ 2014-12-02 23:32 ` Dan Williams 2014-12-02 23:43 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Dan Williams @ 2014-12-02 23:32 UTC (permalink / raw) To: Brent Taylor; +Cc: Krishna Chaitanya, Kalle Valo, ath6kl, linux-wireless On Tue, 2014-12-02 at 16:35 -0600, Brent Taylor wrote: > 1417557229.339147: 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 00 00 > 1417557229.339179: WPA: RSN IE in Beacon/ProbeResp - hexdump(len=26): > 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac > 02 00 00 > 1417557229.339192: WPA: RSN IE in 3/4 msg - 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 On the STA side: The RSN IE in the EAPOL-Key and the 3/4msg are the same, and contain only the CCMP pairwise suite. The RSN IE in the Beacon/ProbeResp debug message contains both CCMP and TKIP pairwise suites. Earlier in the run we have: 1417557228.979261: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00 1417557228.979342: 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 which is where the extra TKIP bit comes from. I'm guessing this is from wpa_supplicant_set_suites() because of the closely paired AP RSN IE/own WPA IE calls. Brent, is there any chance you can run the supplicant on the laptop with "-dddt" to get *all* the debug messages? It seems the logs are missing quite a few that could tell us where wpa_supplicant_set_suites() is getting called. Dan ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-02 23:32 ` Dan Williams @ 2014-12-02 23:43 ` Brent Taylor 2014-12-03 5:24 ` Kalle Valo 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-12-02 23:43 UTC (permalink / raw) To: Dan Williams; +Cc: Krishna Chaitanya, Kalle Valo, ath6kl, linux-wireless On Tue, Dec 2, 2014 at 5:32 PM, Dan Williams <dcbw@redhat.com> wrote: > On Tue, 2014-12-02 at 16:35 -0600, Brent Taylor wrote: >> 1417557229.339147: 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 00 00 >> 1417557229.339179: WPA: RSN IE in Beacon/ProbeResp - hexdump(len=26): >> 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac >> 02 00 00 >> 1417557229.339192: WPA: RSN IE in 3/4 msg - 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 > > On the STA side: > > The RSN IE in the EAPOL-Key and the 3/4msg are the same, and contain > only the CCMP pairwise suite. The RSN IE in the Beacon/ProbeResp debug > message contains both CCMP and TKIP pairwise suites. > > Earlier in the run we have: > > 1417557228.979261: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 > 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00 > 1417557228.979342: 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 > > which is where the extra TKIP bit comes from. I'm guessing this is from > wpa_supplicant_set_suites() because of the closely paired AP RSN IE/own > WPA IE calls. > > Brent, is there any chance you can run the supplicant on the laptop with > "-dddt" to get *all* the debug messages? It seems the logs are missing > quite a few that could tell us where wpa_supplicant_set_suites() is > getting called. > > Dan > Dan, I actually ran the wpa_supplicant with -ddddt as the parameter not knowing how many 'd's I could put there before I hit the limit of the debugging. The wpa_supplicant on the ubuntu laptop is v0.7.3. I'll change the -ddddt to just -dddt and re-run the test again. -- Brent ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-02 23:43 ` Brent Taylor @ 2014-12-03 5:24 ` Kalle Valo [not found] ` <CAP+RiCCE1Z96z6+YX=Cfnb0O3vGdnZeivJH5T3XQ5DWbCbbH_w@mail.gmail.com> 0 siblings, 1 reply; 13+ messages in thread From: Kalle Valo @ 2014-12-03 5:24 UTC (permalink / raw) To: Brent Taylor; +Cc: Dan Williams, Krishna Chaitanya, ath6kl, linux-wireless Brent Taylor <motobud@gmail.com> writes: > I actually ran the wpa_supplicant with -ddddt as the parameter not > knowing how many 'd's I could put there before I hit the limit of the > debugging. The wpa_supplicant on the ubuntu laptop is v0.7.3. I'll > change the -ddddt to just -dddt and re-run the test again. IMHO 0.7.3 is ancient. Please use something more recent. -- Kalle Valo ^ permalink raw reply [flat|nested] 13+ messages in thread
[parent not found: <CAP+RiCCE1Z96z6+YX=Cfnb0O3vGdnZeivJH5T3XQ5DWbCbbH_w@mail.gmail.com>]
* Re: Laptop disassociating by local choice (reason=17) [not found] ` <CAP+RiCCE1Z96z6+YX=Cfnb0O3vGdnZeivJH5T3XQ5DWbCbbH_w@mail.gmail.com> @ 2014-12-03 5:37 ` Kalle Valo 2014-12-03 14:05 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Kalle Valo @ 2014-12-03 5:37 UTC (permalink / raw) To: Brent Taylor; +Cc: linux-wireless, Dan Williams, Krishna Chaitanya, ath6kl Brent Taylor <motobud@gmail.com> writes: > On Dec 2, 2014 11:24 PM, "Kalle Valo" <kvalo@qca.qualcomm.com> wrote: >> >> Brent Taylor <motobud@gmail.com> writes: >> >> > I actually ran the wpa_supplicant with -ddddt as the parameter not >> > knowing how many 'd's I could put there before I hit the limit of the >> > debugging. The wpa_supplicant on the ubuntu laptop is v0.7.3. I'll >> > change the -ddddt to just -dddt and re-run the test again. >> >> IMHO 0.7.3 is ancient. Please use something more recent. > > Ancient it may be, but why would it work with the ath9k chip and not > the ath6kl chip? Because of a bug we are not aware of? ath6kl and ath9k are very different drivers and 0.7.3 might have some problem which only comes with ath6kl. Up to you, but I would not waste time testing on that old wpasupplicant. -- Kalle Valo ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-03 5:37 ` Kalle Valo @ 2014-12-03 14:05 ` Brent Taylor 2014-12-03 16:58 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-12-03 14:05 UTC (permalink / raw) To: Kalle Valo; +Cc: linux-wireless, Dan Williams, Krishna Chaitanya, ath6kl On Tue, Dec 2, 2014 at 11:37 PM, Kalle Valo <kvalo@qca.qualcomm.com> wrote: > Brent Taylor <motobud@gmail.com> writes: > >> On Dec 2, 2014 11:24 PM, "Kalle Valo" <kvalo@qca.qualcomm.com> wrote: >>> >>> Brent Taylor <motobud@gmail.com> writes: >>> >>> > I actually ran the wpa_supplicant with -ddddt as the parameter not >>> > knowing how many 'd's I could put there before I hit the limit of the >>> > debugging. The wpa_supplicant on the ubuntu laptop is v0.7.3. I'll >>> > change the -ddddt to just -dddt and re-run the test again. >>> >>> IMHO 0.7.3 is ancient. Please use something more recent. >> >> Ancient it may be, but why would it work with the ath9k chip and not >> the ath6kl chip? > > Because of a bug we are not aware of? ath6kl and ath9k are very > different drivers and 0.7.3 might have some problem which only comes > with ath6kl. Up to you, but I would not waste time testing on that old > wpasupplicant. > > -- > Kalle Valo Apologies for my last reply not making it through the mailing lists, I sent it from my phone and it was automatically formatted in HTML and not Plain Text. The ultimate goal is to use an Android device to communicate with my custom device being the Access Point. The laptop is easier to debug (at the moment) than an Android device. I'm currently using a Samsung S4 running 4.4.4 and it was not able to complete the authentication step either. This is when I turned to using the laptop. Any idea what version of the wpa_supplicant is running on Kit Kat? -- Brent Taylor ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-03 14:05 ` Brent Taylor @ 2014-12-03 16:58 ` Brent Taylor 2014-12-03 17:26 ` Brent Taylor 0 siblings, 1 reply; 13+ messages in thread From: Brent Taylor @ 2014-12-03 16:58 UTC (permalink / raw) To: Kalle Valo; +Cc: linux-wireless, Dan Williams, Krishna Chaitanya, ath6kl On Wed, Dec 3, 2014 at 8:05 AM, Brent Taylor <motobud@gmail.com> wrote: > On Tue, Dec 2, 2014 at 11:37 PM, Kalle Valo <kvalo@qca.qualcomm.com> wrote: >> Brent Taylor <motobud@gmail.com> writes: >> >>> On Dec 2, 2014 11:24 PM, "Kalle Valo" <kvalo@qca.qualcomm.com> wrote: >>>> >>>> Brent Taylor <motobud@gmail.com> writes: >>>> >>>> > I actually ran the wpa_supplicant with -ddddt as the parameter not >>>> > knowing how many 'd's I could put there before I hit the limit of the >>>> > debugging. The wpa_supplicant on the ubuntu laptop is v0.7.3. I'll >>>> > change the -ddddt to just -dddt and re-run the test again. >>>> >>>> IMHO 0.7.3 is ancient. Please use something more recent. >>> >>> Ancient it may be, but why would it work with the ath9k chip and not >>> the ath6kl chip? >> >> Because of a bug we are not aware of? ath6kl and ath9k are very >> different drivers and 0.7.3 might have some problem which only comes >> with ath6kl. Up to you, but I would not waste time testing on that old >> wpasupplicant. >> >> -- >> Kalle Valo > > Apologies for my last reply not making it through the mailing lists, I > sent it from my phone and it was automatically formatted in HTML and > not Plain Text. > > The ultimate goal is to use an Android device to communicate with my > custom device being the Access Point. The laptop is easier to debug > (at the moment) than an Android device. I'm currently using a Samsung > S4 running 4.4.4 and it was not able to complete the authentication > step either. This is when I turned to using the laptop. Any idea > what version of the wpa_supplicant is running on Kit Kat? > > -- Brent Taylor Here is another log capture from the device and the laptop. This time both were using -dddt as the debug parameter to wpa_supplicant. Laptop wpa_supplicant Log 1417624587.555713: ssid - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417624587.555788: PSK (ASCII passphrase) - hexdump_ascii(len=10): 74 65 73 74 69 6e 67 31 32 33 testing123 1417624587.574956: PSK (from passphrase) - hexdump(len=32): b7 9d 92 a0 21 ad 3b ce 4b 49 e6 a9 01 ea 68 7f 97 4f ab d1 d7 b5 07 eb a1 05 00 40 60 0e d2 c5 1417624587.576603: Scan SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417624587.576839: nl80211: Scan SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417624587.576897: nl80211: Scan SSID - hexdump_ascii(len=0): [NULL] 1417624590.197270: WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00 1417624590.197378: 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 1417624590.197412: FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=0): 1417624590.197667: * SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417624590.197697: * IEs - hexdump(len=0): [NULL] 1417624590.209534: nl80211: MLME event frame - hexdump(len=30): b0 08 3a 01 00 27 10 75 60 28 00 03 7f be f0 a0 00 03 7f be f0 a0 c0 3d 00 00 02 00 00 00 1417624590.209614: SME: Authentication response IEs - hexdump(len=0): [NULL] 1417624590.209776: 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 1417624590.209853: * SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417624590.209885: * IEs - 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 1417624590.215806: nl80211: MLME event frame - hexdump(len=72): 10 00 3a 01 00 27 10 75 60 28 00 03 7f be f0 a0 00 03 7f be f0 a0 d0 3d 31 04 00 00 01 c0 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60 6c 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 1417624590.215901: resp_ies - hexdump(len=42): 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60 6c 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 1417624590.215950: FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=0): 1417624590.231601: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417624590.231764: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01 1417624590.231785: key_nonce - hexdump(len=32): 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 1417624590.231814: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417624590.231835: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 1417624590.231850: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1417624590.231865: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417624590.231888: WPA: RX EAPOL-Key - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417624590.232134: RSN: msg 1/4 key data - hexdump(len=0): 1417624590.233139: WPA: Renewed SNonce - hexdump(len=32): 62 cf af e9 96 cd 04 bc cf 5e 57 0d ae 0e 38 e5 77 eb 45 a3 1c c5 5a 4d e1 52 92 d4 bc 08 ac d6 1417624590.233244: WPA: PMK - hexdump(len=32): b7 9d 92 a0 21 ad 3b ce 4b 49 e6 a9 01 ea 68 7f 97 4f ab d1 d7 b5 07 eb a1 05 00 40 60 0e d2 c5 1417624590.233272: WPA: PTK - hexdump(len=48): f1 aa 93 36 37 80 84 e6 28 da 4e 22 9a 7c cd 44 1d 41 5e 4a 4f 18 be 04 04 2c c3 db 3d 3d 97 10 a6 b2 af 13 5b 47 f3 7b 30 99 22 41 fe 5a 6d 6c 1417624590.233303: 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 1417624590.233356: WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 62 cf af e9 96 cd 04 bc cf 5e 57 0d ae 0e 38 e5 77 eb 45 a3 1c c5 5a 4d e1 52 92 d4 bc 08 ac d6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ad 7b 5c bb a4 2f a4 84 dc a8 60 94 70 83 fa ac 00 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 1417624590.241579: RX EAPOL - hexdump(len=155): 02 03 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 de 15 82 9a 5f 0e fa 84 12 a6 26 09 20 f3 8b 42 00 38 ab 1b 05 98 67 6c 9d d6 9a 7d a6 9a 13 30 ec 02 20 23 60 75 ba 2d f5 4c a0 0a ef b0 ae 1d 07 b0 05 5d 04 c1 fb ad 2f ab c8 17 e0 4d b9 ce c9 4d eb ab f0 37 a7 f6 85 f4 1417624590.241742: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 1417624590.241758: key_nonce - hexdump(len=32): 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 1417624590.241775: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1417624590.241786: key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 1417624590.241794: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1417624590.241802: key_mic - hexdump(len=16): de 15 82 9a 5f 0e fa 84 12 a6 26 09 20 f3 8b 42 1417624590.241816: WPA: RX EAPOL-Key - hexdump(len=155): 02 03 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 de 15 82 9a 5f 0e fa 84 12 a6 26 09 20 f3 8b 42 00 38 ab 1b 05 98 67 6c 9d d6 9a 7d a6 9a 13 30 ec 02 20 23 60 75 ba 2d f5 4c a0 0a ef b0 ae 1d 07 b0 05 5d 04 c1 fb ad 2f ab c8 17 e0 4d b9 ce c9 4d eb ab f0 37 a7 f6 85 f4 1417624590.241885: RSN: encrypted key data - hexdump(len=56): ab 1b 05 98 67 6c 9d d6 9a 7d a6 9a 13 30 ec 02 20 23 60 75 ba 2d f5 4c a0 0a ef b0 ae 1d 07 b0 05 5d 04 c1 fb ad 2f ab c8 17 e0 4d b9 ce c9 4d eb ab f0 37 a7 f6 85 f4 1417624590.241969: WPA: decrypted EAPOL-Key key data - hexdump(len=48): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 16 00 0f ac 01 01 00 44 86 41 6d 8f 3f 09 5c 43 3e 25 55 f5 ec 4a 2f dd 00 1417624590.242044: 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 00 00 dd 16 00 0f ac 01 01 00 44 86 41 6d 8f 3f 09 5c 43 3e 25 55 f5 ec 4a 2f dd 00 1417624590.242086: 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 00 00 1417624590.242110: WPA: GTK in EAPOL-Key - hexdump(len=24): dd 16 00 0f ac 01 01 00 44 86 41 6d 8f 3f 09 5c 43 3e 25 55 f5 ec 4a 2f 1417624590.242153: WPA: RSN IE in Beacon/ProbeResp - hexdump(len=26): 30 18 01 00 00 0f ac 04 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00 1417624590.242184: WPA: RSN IE in 3/4 msg - 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 1417624590.279576: nl80211: MLME event frame - hexdump(len=26): a0 00 00 00 00 03 7f be f0 a0 00 27 10 75 60 28 00 03 7f be f0 a0 00 00 11 00 1417624590.280742: nl80211: MLME event frame - hexdump(len=26): c0 00 3a 01 00 27 10 75 60 28 00 03 7f be f0 a0 00 03 7f be f0 a0 e0 3d 11 00 Host APD Log [linux][~> hostapd -dddt -K /etc/myapd.conf 1417603224.341394: random: Trying to read entropy from /dev/random 1417603224.341640: Configuration file: /etc/myapd.conf 1417603224.343872: nl80211: Could not add multicast membership for vendor events: -2 (No such file or directory) 1417603224.344348: rfkill: Cannot open RFKILL control device 1417603224.344709: nl80211: RFKILL status not available 1417603224.345349: nl80211: Using driver-based roaming 1417603224.345661: nl80211: Supported cipher 00-0f-ac:1 1417603224.345973: nl80211: Supported cipher 00-0f-ac:5 1417603224.346235: nl80211: Supported cipher 00-0f-ac:2 1417603224.346514: nl80211: Supported cipher 00-0f-ac:4 1417603224.346777: nl80211: Supported cipher 00-40-96:255 1417603224.347039: nl80211: Supported cipher 00-14-72:1 1417603224.347269: nl80211: Supports Probe Response offload in AP mode 1417603224.347696: nl80211: Disable use_monitor with device_ap_sme since no monitor mode support detected 1417603224.347975: nl80211: interface wlan0 in phy phy0 1417603224.348336: nl80211: Set mode ifindex 3 iftype 3 (AP) 1417603224.348680: nl80211: Setup AP(wlan0) - device_ap_sme=1 use_monitor=0 1417603224.349025: nl80211: Subscribe to mgmt frames with AP handle 0x11097c8 (device SME) 1417603224.349304: nl80211: Register frame type=0xd0 nl_handle=0x11097c8 match= 1417603224.349649: nl80211: Enable Probe Request reporting nl_preq=0x11088c8 1417603224.349944: nl80211: Register frame type=0x40 nl_handle=0x11088c8 match= 1417603224.350469: nl80211: Add own interface ifindex 3 1417603224.350748: nl80211: if_indices[16]: 3 1417603224.351060: phy: phy0 1417603224.351355: BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits) 1417603224.352094: nl80211: Regulatory information - country=00 1417603224.352373: nl80211: 2402-2472 @ 40 MHz 20 mBm 1417603224.352652: nl80211: 2457-2482 @ 40 MHz 20 mBm (no IR) 1417603224.352898: nl80211: 2474-2494 @ 20 MHz 20 mBm (no OFDM) (no IR) 1417603224.353193: nl80211: 5170-5250 @ 80 MHz 20 mBm (no IR) 1417603224.353439: nl80211: 5735-5835 @ 80 MHz 20 mBm (no IR) 1417603224.353686: nl80211: 57240-63720 @ 2160 MHz 0 mBm 1417603224.354030: nl80211: Added 802.11b mode based on 802.11g information 1417603224.354309: Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm 1417603224.354605: Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm 1417603224.354867: Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm 1417603224.354916: Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm 1417603224.354966: Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm 1417603224.354998: Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm 1417603224.355179: Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm 1417603224.355228: Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm 1417603224.355261: Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm 1417603224.355294: Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm 1417603224.355474: Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm 1417603224.355524: Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm 1417603224.355573: Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm 1417603224.355606: Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm 1417603224.355671: Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm 1417603224.355819: Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm 1417603224.355885: Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm 1417603224.355917: Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm 1417603224.355950: Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm 1417603224.356098: Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm 1417603224.356164: Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm 1417603224.356229: Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm 1417603224.356393: Completing interface initialization 1417603224.356459: Mode: IEEE 802.11b Channel: 1 Frequency: 2412 MHz 1417603224.356656: DFS 0 channels required radar detection 1417603224.356738: nl80211: Set freq 2412 (ht_enabled=0, vht_enabled=0, bandwidth=20 MHz, cf1=2412 MHz, cf2=0 MHz) 1417603224.356984: RATE[0] rate=10 flags=0x1 1417603224.357148: RATE[1] rate=20 flags=0x1 1417603224.357181: RATE[2] rate=55 flags=0x0 1417603224.357345: RATE[3] rate=110 flags=0x0 1417603224.357394: hostapd_setup_bss(hapd=0x1108dd8 (wlan0), first=1) 1417603224.357476: wlan0: Flushing old station entries 1417603224.357673: nl80211: flush -> DEL_STATION wlan0 (all) 1417603224.358133: wlan0: Deauthenticate all stations 1417603224.358477: nl80211: sta_remove -> DEL_STATION wlan0 ff:ff:ff:ff:ff:ff --> 0 (Success) 1417603224.358740: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0 1417603224.358855: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0 1417603224.359479: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0 1417603224.359593: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0 1417603224.359823: Using interface wlan0 with hwaddr 00:03:7f:be:f0:a0 and ssid "testssid" 1417603224.359987: Deriving WPA PSK based on passphrase 1417603224.360036: SSID - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417603224.360315: PSK (ASCII passphrase) - hexdump_ascii(len=10): 74 65 73 74 69 6e 67 31 32 33 testing123 1417603224.439807: PSK (from passphrase) - hexdump(len=32): b7 9d 92 a0 21 ad 3b ce 4b 49 e6 a9 01 ea 68 7f 97 4f ab d1 d7 b5 07 eb a1 05 00 40 60 0e d2 c5 1417603224.440300: random: Got 20/20 bytes from /dev/random 1417603224.440644: Get randomness: len=32 entropy=0 1417603224.443467: random from os_get_random - hexdump(len=32): ab 53 c5 45 c8 0c 8c 03 70 fb 16 52 60 a0 d0 5d fc 52 c3 b2 b4 86 d3 10 f0 e9 ec 65 23 82 07 47 1417603224.443861: random_mix_pool - hexdump(len=20): 05 2f 25 05 07 70 66 c0 6f 4e 0d 20 91 6c 24 9f c7 e6 21 bd 1417603224.444189: random from internal pool - hexdump(len=16): 2e 63 8e 56 40 38 4f 30 86 ad be 93 53 1c b0 53 1417603224.444517: random_mix_pool - hexdump(len=20): b7 bb b0 d1 fa 9b 6b cd 40 f2 58 d2 5e 38 c3 11 37 66 a2 3d 1417603224.444829: random from internal pool - hexdump(len=16): 71 9b 1a 64 08 47 d5 d6 6f d4 f6 35 84 5d 5b 82 1417603224.445141: mixed random - hexdump(len=32): 85 30 4b 13 88 34 c3 33 f6 56 a8 c1 33 bc 60 0e 8d c9 d9 d6 bc c1 06 c6 9f 3d 1a 50 a7 df 5c c5 1417603224.445436: GMK - hexdump(len=32): 85 30 4b 13 88 34 c3 33 f6 56 a8 c1 33 bc 60 0e 8d c9 d9 d6 bc c1 06 c6 9f 3d 1a 50 a7 df 5c c5 1417603224.445764: Get randomness: len=32 entropy=0 1417603224.448505: random from os_get_random - hexdump(len=32): 2e 0e 20 7f f5 62 5f 18 2f f4 dc 63 65 c3 3a c2 66 c7 93 4f fe cc 28 fd 33 de 70 e1 df d4 3c 06 1417603224.448817: random_mix_pool - hexdump(len=20): ef cb d3 6c f9 c5 74 ff 60 aa 2d 84 5c 03 83 ca 38 9e 0a 9f 1417603224.449145: random from internal pool - hexdump(len=16): bc 1e b0 97 5d f9 46 97 4e 80 e9 de 23 d8 e5 c9 1417603224.449457: random_mix_pool - hexdump(len=20): 71 50 bd b3 96 55 f0 ed 44 8c b9 53 5d 37 5b aa 17 6b 1b f8 1417603224.449785: random from internal pool - hexdump(len=16): a2 d7 14 97 18 21 f0 9c 01 53 66 98 63 a9 69 3f 1417603224.450064: mixed random - hexdump(len=32): 92 10 90 e8 a8 9b 19 8f 61 74 35 bd 46 1b df 0b c4 10 87 d8 e6 ed d8 61 32 8d 16 79 bc 7d 55 39 1417603224.450441: Key Counter - hexdump(len=32): 1b 83 19 4f 43 9d 8f fc fc 3b a3 fd 14 7b ab b8 98 4c 14 11 ea ad 91 70 f2 9e c7 1d cc f8 b1 60 1417603224.450737: WPA: Delay group state machine start until Beacon frames have been configured 1417603224.451147: nl80211: Set beacon (beacon_set=0) 1417603224.451442: nl80211: Beacon head - hexdump(len=55): 80 00 00 00 ff ff ff ff ff ff 00 03 7f be f0 a0 00 03 7f be f0 a0 00 00 00 00 00 00 00 00 00 00 64 00 11 00 00 08 74 65 73 74 73 73 69 64 01 04 82 84 0b 16 03 01 01 1417603224.451787: nl80211: Beacon tail - 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 1417603224.452132: nl80211: ifindex=3 1417603224.452394: nl80211: beacon_int=100 1417603224.452640: nl80211: dtim_period=2 1417603224.452936: nl80211: ssid - hexdump_ascii(len=8): 74 65 73 74 73 73 69 64 testssid 1417603224.453461: nl80211: proberesp (offload) - hexdump(len=77): 50 00 00 00 00 00 00 00 00 00 00 03 7f be f0 a0 00 03 7f be f0 a0 00 00 00 00 00 00 00 00 00 00 64 00 11 00 00 08 74 65 73 74 73 73 69 64 01 04 82 84 0b 16 03 01 01 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 1417603224.453838: nl80211: hidden SSID not in use 1417603224.454084: nl80211: privacy=1 1417603224.454347: nl80211: auth_algs=0x1 1417603224.454610: nl80211: wpa_version=0x2 1417603224.454872: nl80211: key_mgmt_suites=0x2 1417603224.455135: nl80211: pairwise_ciphers=0x18 1417603224.455299: nl80211: group_cipher=0x10 1417603224.456628: WPA: Start group state machine to set initial keys 1417603224.456923: WPA: group state machine entering state GTK_INIT (VLAN-ID 0) 1417603224.457202: Get randomness: len=16 entropy=0 1417603224.459959: random from os_get_random - hexdump(len=16): 25 1c 90 24 a9 53 e3 15 e7 f9 42 6d 11 97 77 2b 1417603224.460271: random_mix_pool - hexdump(len=20): 82 a9 1b c3 ac 6a b4 33 e4 00 5e d1 cd a6 a4 27 8d 7b cd 6d 1417603224.460747: random from internal pool - hexdump(len=16): 9f bf 3c e5 e9 1d 08 a7 74 28 3c 24 07 51 cf fe 1417603224.461042: mixed random - hexdump(len=16): ba a3 ac c1 40 4e eb b2 93 d1 7e 49 16 c6 b8 d5 1417603224.461354: GTK - hexdump(len=16): 14 4a d4 da eb 5d bb e4 2a b2 fb 7f d3 30 1f af 1417603224.461633: WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0) 1417603224.462388: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=3 addr=0xa305c key_idx=1 set_tx=1 seq_len=0 key_len=16 1417603224.462683: nl80211: KEY_DATA - hexdump(len=16): 14 4a d4 da eb 5d bb e4 2a b2 fb 7f d3 30 1f af 1417603224.462979: broadcast key 1417603224.463586: nl80211: Set wlan0 operstate 0->1 (UP) 1417603224.464357: netlink: Operstate: ifindex=3 linkmode=-1 (no change), operstate=6 (IF_OPER_UP) 1417603224.464800: Failed to set TX queue parameters for queue 0. 1417603224.465161: Failed to set TX queue parameters for queue 1. 1417603224.465539: Failed to set TX queue parameters for queue 2. 1417603224.465883: Failed to set TX queue parameters for queue 3. 1417603224.466179: wlan0: interface state UNINITIALIZED->ENABLED 1417603224.466458: wlan0: AP-ENABLED 1417603224.466704: wlan0: Setup of interface done. 1417603224.466967: ctrl_iface not configured! 1417603224.467344: nl80211: Event message available 1417603224.467656: nl80211: Drv Event 88 (NL80211_CMD_CH_SWITCH_NOTIFY) received for wlan0 1417603224.467820: nl80211: Channel switch event 1417603224.468017: wlan0: Event CH_SWITCH (41) received 1417603224.468082: 1417603224.468082: wlan0: IEEE 802.11 driver had channel switch: freq=2412, ht=1, offset=0, width=1, cf1=2412, cf2=0 1417603247.029351: nl80211: Event message available 1417603247.029416: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0 1417603247.029449: nl80211: New station 00:27:10:75:60:28 1417603247.029482: nl80211: Assoc Req IEs - hexdump(len=57): 00 08 74 65 73 74 73 73 69 64 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 07 00 50 f2 02 00 01 00 1417603247.029564: wlan0: Event ASSOC (0) received 1417603247.029580: Add randomness: count=1 entropy=0 1417603247.029597: random pool - hexdump(len=128): e9 af 70 97 0f b1 a6 d7 21 48 8f af aa 47 ee ff 09 ee ee df 1e 5f 41 51 72 49 c3 98 40 61 e6 c3 f5 f9 9d 05 29 5f d3 01 63 de da 0c b7 de ad 45 73 8e 5c 69 39 d1 ed f6 cb 2c ab ed 6d e5 1d 54 33 9a f1 ee 6d c7 07 96 de c7 fb dc 50 fd b4 2a 5f 2a 77 b4 d3 20 bb dd 51 9e fe a7 43 55 45 45 37 90 39 3c 99 60 33 b7 11 4c 02 d9 c4 50 11 4b 34 b6 fb 27 23 f1 b4 70 8f 8f 08 b0 e4 3e 3f 7c 1417603247.029745: random_mix_pool - hexdump(len=8): af e8 7e 54 9d 73 00 00 1417603247.029777: random_mix_pool - hexdump(len=6): 00 27 10 75 60 28 1417603247.029794: random pool - hexdump(len=128): e9 af 70 97 0f b1 a6 d7 21 48 8f af aa 47 ee ff 09 ee ee df 1e 5f 41 51 72 49 c3 98 40 61 e6 c3 f5 f9 9d 05 29 5f d3 01 63 de da 0c b7 de ad 45 73 8e 5c 69 39 d1 ed f6 1c 31 b4 41 94 e8 31 20 84 2f 77 83 3e 9f 20 fc 6f f8 a3 87 38 4b ba e2 7c a5 1d e7 9c 41 01 21 a8 61 1b 83 ee dd 72 b1 bf 60 b7 f5 f0 32 17 ad 9e 19 12 57 38 c4 e6 87 34 b6 fb 27 23 f1 b4 70 8f 8f 08 b0 e4 3e 3f 7c 1417603247.029958: 1417603247.029958: wlan0: STA 00:27:10:75:60:28 IEEE 802.11: associated 1417603247.030023: STA included RSN IE in (Re)AssocReq 1417603247.030056: New STA 1417603247.030073: ap_sta_add: register ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) 1417603247.030122: 1417603247.030138: wlan0: STA 00:27:10:75:60:28 WPA: event 1 notification 1417603247.030204: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x110b2c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417603247.030237: addr=00:27:10:75:60:28 1417603247.030335: IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS 1417603247.030368: 1417603247.030368: wlan0: STA 00:27:10:75:60:28 WPA: start authentication 1417603247.030385: WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE 1417603247.030434: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x110b2c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417603247.030467: addr=00:27:10:75:60:28 1417603247.030942: 1417603247.030942: wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port 1417603247.030959: WPA: 00:27:10:75:60:28 WPA_PTK_GROUP entering state IDLE 1417603247.030992: WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION 1417603247.031008: WPA: 00:27:10:75:60:28 WPA_PTK entering state AUTHENTICATION2 1417603247.031025: WPA: Re-initialize GMK/Counter on first station 1417603247.031041: Get randomness: len=32 entropy=1 1417603247.033568: random from os_get_random - hexdump(len=32): b1 ee 13 91 e8 7e df db 3e 6b 9b c6 4a ce 38 db 08 9d d6 80 6a 6b 7d 3b 15 76 be be 6a 14 16 2f 1417603247.033650: random_mix_pool - hexdump(len=20): f9 af ed 36 be 42 2f 34 81 b1 d0 05 c9 8e 39 88 8e 08 25 9a 1417603247.033716: random from internal pool - hexdump(len=16): 65 46 ce a4 d2 6c cf 83 78 e8 81 73 fc 87 59 ca 1417603247.033781: random_mix_pool - hexdump(len=20): b5 43 d9 7e a0 32 4a 20 d0 ba 3a 10 be 1d 0e ef 8b 59 e9 18 1417603247.033831: random from internal pool - hexdump(len=16): fe f8 80 b0 14 bf 86 f2 e9 58 f4 4d c1 0d 82 b1 1417603247.033864: mixed random - hexdump(len=32): d4 a8 dd 35 3a 12 10 58 46 83 1a b5 b6 49 61 11 f6 65 56 30 7e d4 fb c9 fc 2e 4a f3 ab 19 94 9e 1417603247.033913: GMK - hexdump(len=32): d4 a8 dd 35 3a 12 10 58 46 83 1a b5 b6 49 61 11 f6 65 56 30 7e d4 fb c9 fc 2e 4a f3 ab 19 94 9e 1417603247.033962: Get randomness: len=32 entropy=0 1417603247.036407: random from os_get_random - hexdump(len=32): c6 6f e4 1e 49 f0 98 0e 05 6e 12 b3 45 3d e8 55 11 f8 64 3b 00 48 c7 da 01 f6 36 42 32 b5 f8 99 1417603247.036489: random_mix_pool - hexdump(len=20): c8 d9 43 81 02 90 26 66 34 84 28 ba 0e 10 4b 6c ab 18 dc 87 1417603247.036555: random from internal pool - hexdump(len=16): 9e 12 32 69 b5 b5 a4 84 50 43 a0 22 38 07 f2 b4 1417603247.036604: random_mix_pool - hexdump(len=20): 3e cc fa 2e 40 87 ef 6e 77 3a 69 3c 10 f2 0f 09 9c 32 d2 6b 1417603247.036653: random from internal pool - hexdump(len=16): c5 f3 65 1e 9f 03 e3 d6 86 3e fd 29 58 ce c9 43 1417603247.036686: mixed random - hexdump(len=32): 58 7d d6 77 fc 45 3c 8a 55 2d b2 91 7d 3a 1a e1 d4 0b 01 25 9f 4b 24 0c 87 c8 cb 6b 6a 7b 31 da 1417603247.036768: Key Counter - hexdump(len=32): dd 56 df 47 f3 e2 47 10 dd eb 18 2c 29 84 4f 8b 38 fd ed ae 2b fb a7 65 ae cf 79 9d 32 39 65 09 1417603247.036817: Get randomness: len=16 entropy=0 1417603247.039263: random from os_get_random - hexdump(len=16): 36 48 ed df 78 f0 33 f6 98 ec 18 5b f0 86 67 9e 1417603247.039312: random_mix_pool - hexdump(len=20): ed 00 0a ac fb 02 55 df 65 a8 63 ce f1 10 a1 6b 30 d9 35 21 1417603247.039377: random from internal pool - hexdump(len=16): dc 37 b1 2f 8e 08 e7 0c 4d 1d b5 70 f1 c0 1e 0a 1417603247.039410: mixed random - hexdump(len=16): ea 7f 5c f0 f6 f8 d4 fa d5 f1 ad 2b 01 46 79 94 1417603247.039459: GTK - hexdump(len=16): 44 86 41 6d 8f 3f 09 5c 43 3e 25 55 f5 ec 4a 2f 1417603247.039525: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=3 addr=0xa305c key_idx=1 set_tx=1 seq_len=0 key_len=16 1417603247.039558: nl80211: KEY_DATA - hexdump(len=16): 44 86 41 6d 8f 3f 09 5c 43 3e 25 55 f5 ec 4a 2f 1417603247.039607: broadcast key 1417603247.040772: Get randomness: len=32 entropy=0 1417603247.044317: random from os_get_random - hexdump(len=32): b2 18 32 0a c6 cf 61 b5 e5 dd 01 aa 1b b3 eb 12 f1 ef 0a 4e 3a 0f ce 37 ee e5 bf 76 74 51 9b 97 1417603247.044662: random_mix_pool - hexdump(len=20): 0f dc 96 bf c1 b5 47 fa c1 22 19 77 b6 1d 03 d2 1e bd f7 8d 1417603247.045023: random from internal pool - hexdump(len=16): df 6e 8f a6 04 05 a7 f4 c1 1c 44 c6 e6 5d 61 76 1417603247.045384: random_mix_pool - hexdump(len=20): 48 be d3 0f 03 fe e9 a2 ca 52 d6 90 66 67 d7 1f 22 df 48 40 1417603247.045712: random from internal pool - hexdump(len=16): 2f 96 dc 3e ee 75 ca 1d f6 fe 30 74 da 7e 96 00 1417603247.046007: mixed random - hexdump(len=32): 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 1417603247.046319: WPA: Assign ANonce - hexdump(len=32): 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 1417603247.046631: WPA: 00:27:10:75:60:28 WPA_PTK entering state INITPSK 1417603247.046893: Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) 1417603247.047156: Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) 1417603247.047419: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKSTART 1417603247.047714: 1417603247.047714: wlan0: STA 00:27:10:75:60:28 WPA: sending 1/4 msg of 4-Way Handshake 1417603247.047993: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0) 1417603247.048403: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) 1417603247.048879: hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 00:27:10:75:60:28 (300 seconds - ap_max_inactivity) 1417603247.052391: wlan0: Event EAPOL_RX (26) received 1417603247.052670: IEEE 802.1X: 121 bytes from 00:27:10:75:60:28 1417603247.052932: IEEE 802.1X: version=1 type=3 length=117 1417603247.053211: WPA: Received EAPOL-Key from 00:27:10:75:60:28 key_info=0x10a type=2 key_data_length=22 1417603247.053523: WPA: Received Key Nonce - hexdump(len=32): 62 cf af e9 96 cd 04 bc cf 5e 57 0d ae 0e 38 e5 77 eb 45 a3 1c c5 5a 4d e1 52 92 d4 bc 08 ac d6 1417603247.053819: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01 1417603247.054098: Add randomness: count=2 entropy=0 1417603247.054360: random pool - hexdump(len=128): 54 d1 9e c9 5f 2e 0b 37 ea 5d 7c d8 a6 6b 86 70 3e 0f f9 33 b2 b8 f8 1e f3 ef c1 49 d0 68 73 95 07 75 36 1e e0 77 18 1d a4 8a 11 62 c0 53 e8 29 dd e0 6f 08 49 c2 c8 6f c3 d5 ff b4 e0 31 8e 67 fe 1d c7 80 c6 ae 95 bf 60 2d 2c 23 b7 08 74 31 9c 4c 77 40 d1 36 a1 cf 3d 91 2b 5b 57 76 20 df 92 e4 5d 39 8f c3 28 2b 42 eb 7f 57 9e 5a da 77 0a ed bc d2 f4 43 80 4d 25 36 80 77 e6 fb 1f 4b 1417603247.054787: random_mix_pool - hexdump(len=8): af e8 7e 54 58 d4 00 00 1417603247.054967: random_mix_pool - hexdump(len=32): 62 cf af e9 96 cd 04 bc cf 5e 57 0d ae 0e 38 e5 77 eb 45 a3 1c c5 5a 4d e1 52 92 d4 bc 08 ac d6 1417603247.055148: random pool - hexdump(len=128): f2 17 95 c7 c5 10 87 3c b2 69 d2 ea f9 05 33 21 00 24 2e b5 e3 f7 86 f8 76 17 13 e3 10 2a 25 f5 47 6f de 95 b4 62 f2 58 5c b3 2a af 1c 12 6e 3f e6 ed a3 0a 67 35 31 e3 30 08 1e 3b 3f e2 d9 be 00 9d 5f cf da fa 80 f1 71 3d a7 e8 7a 89 2f a8 d3 4e 0e 75 f0 3a b8 ff 13 a5 a1 66 f3 ba b6 1c 73 95 c0 15 b8 4e 35 61 86 6e 7c 65 32 26 83 56 72 cb 2d f9 02 ae 58 f0 ab 88 ea d6 12 19 e9 fe 1417603247.055624: 1417603247.055624: wlan0: STA 00:27:10:75:60:28 WPA: received EAPOL-Key frame (2/4 Pairwise) 1417603247.055804: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING 1417603247.055853: Searching a PSK for 00:27:10:75:60:28 prev_psk=(nil) 1417603247.055968: WPA: PTK derivation - A1=00:03:7f:be:f0:a0 A2=00:27:10:75:60:28 1417603247.056165: WPA: Nonce1 - hexdump(len=32): 6d 76 bd ac c2 ca c6 41 24 c1 45 6c fd ee 8a 64 de 79 d6 70 d4 7a 04 2a 18 1b 8f 02 ae 2f 0d 97 1417603247.056395: WPA: Nonce2 - hexdump(len=32): 62 cf af e9 96 cd 04 bc cf 5e 57 0d ae 0e 38 e5 77 eb 45 a3 1c c5 5a 4d e1 52 92 d4 bc 08 ac d6 1417603247.056461: WPA: PMK - hexdump(len=32): b7 9d 92 a0 21 ad 3b ce 4b 49 e6 a9 01 ea 68 7f 97 4f ab d1 d7 b5 07 eb a1 05 00 40 60 0e d2 c5 1417603247.056690: WPA: PTK - hexdump(len=48): f1 aa 93 36 37 80 84 e6 28 da 4e 22 9a 7c cd 44 1d 41 5e 4a 4f 18 be 04 04 2c c3 db 3d 3d 97 10 a6 b2 af 13 5b 47 f3 7b 30 99 22 41 fe 5a 6d 6c 1417603247.056937: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKCALCNEGOTIATING2 1417603247.057117: WPA: 00:27:10:75:60:28 WPA_PTK entering state PTKINITNEGOTIATING 1417603247.057330: 1417603247.057330: wlan0: STA 00:27:10:75:60:28 WPA: sending 3/4 msg of 4-Way Handshake 1417603247.057609: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=1 kde_len=46 keyidx=1 encr=1) 1417603247.057790: Plaintext EAPOL-Key Key Data - hexdump(len=56): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 dd 16 00 0f ac 01 01 00 44 86 41 6d 8f 3f 09 5c 43 3e 25 55 f5 ec 4a 2f dd 00 00 00 00 00 00 00 00 00 1417603247.058463: WPA: Use EAPOL-Key timeout of 1000 ms (retry counter 1) 1417603247.062418: nl80211: Event message available 1417603247.062779: nl80211: Drv Event 20 (NL80211_CMD_DEL_STATION) received for wlan0 1417603247.062828: nl80211: Delete station 00:27:10:75:60:28 1417603247.063025: wlan0: Event DISASSOC (1) received 1417603247.063074: 1417603247.063074: wlan0: STA 00:27:10:75:60:28 IEEE 802.11: disassociated 1417603247.063369: 1417603247.063386: wlan0: STA 00:27:10:75:60:28 WPA: event 2 notification 1417603247.063616: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x110b2c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417603247.063796: addr=00:27:10:75:60:28 1417603247.063927: WPA: 00:27:10:75:60:28 WPA_PTK entering state DISCONNECTED 1417603247.064190: WPA: 00:27:10:75:60:28 WPA_PTK entering state INITIALIZE 1417603247.064272: wpa_driver_nl80211_set_key: ifindex=3 (wlan0) alg=0 addr=0x110b2c8 key_idx=0 set_tx=1 seq_len=0 key_len=0 1417603247.064469: addr=00:27:10:75:60:28 1417603247.064846: 1417603247.064846: wlan0: STA 00:27:10:75:60:28 IEEE 802.1X: unauthorizing port 1417603247.065289: nl80211: sta_remove -> DEL_STATION wlan0 00:27:10:75:60:28 --> 0 (Success) 1417603247.065536: ap_free_sta: cancel ap_handle_timer for 00:27:10:75:60:28 ^ permalink raw reply [flat|nested] 13+ messages in thread
* Re: Laptop disassociating by local choice (reason=17) 2014-12-03 16:58 ` Brent Taylor @ 2014-12-03 17:26 ` Brent Taylor 0 siblings, 0 replies; 13+ messages in thread From: Brent Taylor @ 2014-12-03 17:26 UTC (permalink / raw) To: Kalle Valo; +Cc: linux-wireless, Dan Williams, Krishna Chaitanya, ath6kl On Wed, Dec 3, 2014 at 10:58 AM, Brent Taylor <motobud@gmail.com> wrote: > On Wed, Dec 3, 2014 at 8:05 AM, Brent Taylor <motobud@gmail.com> wrote: >> On Tue, Dec 2, 2014 at 11:37 PM, Kalle Valo <kvalo@qca.qualcomm.com> wrote: >>> Brent Taylor <motobud@gmail.com> writes: >>> >>>> On Dec 2, 2014 11:24 PM, "Kalle Valo" <kvalo@qca.qualcomm.com> wrote: >>>>> >>>>> Brent Taylor <motobud@gmail.com> writes: >>>>> >>>>> > I actually ran the wpa_supplicant with -ddddt as the parameter not >>>>> > knowing how many 'd's I could put there before I hit the limit of the >>>>> > debugging. The wpa_supplicant on the ubuntu laptop is v0.7.3. I'll >>>>> > change the -ddddt to just -dddt and re-run the test again. >>>>> >>>>> IMHO 0.7.3 is ancient. Please use something more recent. >>>> >>>> Ancient it may be, but why would it work with the ath9k chip and not >>>> the ath6kl chip? >>> >>> Because of a bug we are not aware of? ath6kl and ath9k are very >>> different drivers and 0.7.3 might have some problem which only comes >>> with ath6kl. Up to you, but I would not waste time testing on that old >>> wpasupplicant. >>> >>> -- >>> Kalle Valo >> >> Apologies for my last reply not making it through the mailing lists, I >> sent it from my phone and it was automatically formatted in HTML and >> not Plain Text. >> >> The ultimate goal is to use an Android device to communicate with my >> custom device being the Access Point. The laptop is easier to debug >> (at the moment) than an Android device. I'm currently using a Samsung >> S4 running 4.4.4 and it was not able to complete the authentication >> step either. This is when I turned to using the laptop. Any idea >> what version of the wpa_supplicant is running on Kit Kat? >> >> -- Brent Taylor > > > Here is another log capture from the device and the laptop. This time > both were using -dddt as the debug parameter to wpa_supplicant. > > Laptop wpa_supplicant Log > -- snip last log data from 2014-12-03 -- I just found out that the Samsung S4 that we are testing with has wpa_supplicant version v2.0-devel-4.2.2 which is not KitKat, but Jelly Bean. Does anyone know if the logging can be turned on without rooting the phone? -- Brent Taylor ^ permalink raw reply [flat|nested] 13+ messages in thread
end of thread, other threads:[~2014-12-03 17:26 UTC | newest]
Thread overview: 13+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2014-11-10 18:12 Laptop disassociating by local choice (reason=17) Brent Taylor
2014-12-01 18:49 ` Brent Taylor
2014-12-01 19:00 ` Krishna Chaitanya
2014-12-02 19:18 ` Brent Taylor
2014-12-02 19:39 ` Krishna Chaitanya
2014-12-02 22:35 ` Brent Taylor
2014-12-02 23:32 ` Dan Williams
2014-12-02 23:43 ` Brent Taylor
2014-12-03 5:24 ` Kalle Valo
[not found] ` <CAP+RiCCE1Z96z6+YX=Cfnb0O3vGdnZeivJH5T3XQ5DWbCbbH_w@mail.gmail.com>
2014-12-03 5:37 ` Kalle Valo
2014-12-03 14:05 ` Brent Taylor
2014-12-03 16:58 ` Brent Taylor
2014-12-03 17:26 ` Brent Taylor
This is a public inbox, see mirroring instructions for how to clone and mirror all data and code used for this inbox; as well as URLs for NNTP newsgroup(s).