[rt2x00-users] canonical 14.04 x64 hostapd with RT2790 trouble (12.06.14)

asdfsdfsf asfsdf idksomecrap at yandex.ru
Thu Jun 12 17:24:47 AEST 2014


I wasn't sure where to address that, so reporting it here, hoping that you will help me to figure out what could be really wrong there and find the right address eventually if needed.

02:00.0 Network controller: Ralink corp. RT2790 Wireless 802.11n 1T/2R PCIe
Linux 3.13.0-29-generic #53-Ubuntu SMP Wed Jun 4 21:00:20 UTC 2014 x86_64 x86_64 x86_64 GNU/Linux

--------------- Hostapd version
Section: universe/net
Version: 1:2.1-0ubuntu1
Maintainer: Ubuntu Developers <ubuntu-devel-discuss at lists.ubuntu.com>
Architecture: amd64
The current version includes support for other drivers, an integrated EAP authenticator (i.e., allow full authentication without requiring an external RADIUS authentication server), and RADIUS authentication server for EAP authentication. 
 
 hostapd works with the following drivers: 
 
 * mac80211 based drivers with support for master mode [linux] 
 * Host AP driver for Prism2/2.5/3 [linux] 
 * Driver interface for FreeBSD net80211 layer [kfreebsd] 
 * Any wired Ethernet driver for wired IEEE 802.1X authentication.
--------------- EOF

--------------- /etc/hostapd/hostapd.FAILconf
interface=wlan0
driver=nl80211
ssid=test 
channel=1
ignore_broadcast_ssid=0
country_code=RU
hw_mode=g
macaddr_acl=0
ieee80211w=2
ieee80211n=1
ieee80211d=1
wmm_enabled=1
wmm_ac_bk_acm=0
wmm_ac_be_acm=0
wmm_ac_vi_acm=0
wmm_ac_vo_acm=0
dynamic_vlan=0
ht_capab=[SMPS-STATIC][GF][SHORT-GI-20][SHORT-GI-40][HT40-][HT40+][RX-STBC12][TX-STBC1]
auth_algs=1
wpa_passphrase=testtest123
wpa=2
wpa_key_mgmt=WPA-PSK
wpa_pairwise=CCMP
rsn_pairwise=CCMP
rsn_preauth=0
disable_pmksa_caching=1
--------------- EOF

Client is a windows 7 home basic with latest atheros driver for AR9485, situation is settled by commenting the "ieee80211w=2" line. It appeared to be not that easy, at least to me and a couple of helpful @freenode guys, to figure out, that:

nl80211: set_key failed;err=-22
is actually expanded as
nl80211: set_key failed;err=-22 (was IEEE 802.11w support in wpa_supplicant('Management frame protection required,but client did not enable it' error))

As it written on subject here http://readlist.com/lists/shmoo.com/hostap/2/13231.html

Below there will be debug listing of hostapd, where from my very newbish perspective there are strange moments still, like this one:
1402550657.894225: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=4 addr=0x4a6a6a key_idx=4 set_tx=1 seq_len=0 key_len=16
1402550657.894301:    broadcast key
1402550657.894396: nl80211: set_key failed; err=-22 Invalid argument)
1402550657.894433: WPA: group state machine entering state FATAL_FAILURE

In the very end of the message there is a hostapd listing with "ieee80211w=2" commented out.

--------------- MODINFO (being loaded with nohwcrypt=1):
filename:       /lib/modules/3.13.0-29-generic/kernel/drivers/net/wireless/rt2x00/rt2800pci.ko
license:        GPL
firmware:       rt2860.bin
description:    Ralink RT2800 PCI & PCMCIA Wireless LAN driver.
version:        2.3.0
author:         http://rt2x00.serialmonkey.com
srcversion:     D876F002AA85529257D61EB
alias:          pci:v00001814d0000539Fsv*sd*bc*sc*i*
alias:          pci:v00001814d0000539Bsv*sd*bc*sc*i*
alias:          pci:v00001814d0000539Asv*sd*bc*sc*i*
alias:          pci:v00001814d00005392sv*sd*bc*sc*i*
alias:          pci:v00001814d00005390sv*sd*bc*sc*i*
alias:          pci:v00001814d00005362sv*sd*bc*sc*i*
alias:          pci:v00001814d00005360sv*sd*bc*sc*i*
alias:          pci:v00001814d0000359Fsv*sd*bc*sc*i*
alias:          pci:v00001814d00003593sv*sd*bc*sc*i*
alias:          pci:v00001814d00003592sv*sd*bc*sc*i*
alias:          pci:v00001814d00003562sv*sd*bc*sc*i*
alias:          pci:v00001814d00003062sv*sd*bc*sc*i*
alias:          pci:v00001814d00003060sv*sd*bc*sc*i*
alias:          pci:v00001432d00007722sv*sd*bc*sc*i*
alias:          pci:v00001432d00007711sv*sd*bc*sc*i*
alias:          pci:v00001814d00003390sv*sd*bc*sc*i*
alias:          pci:v00001814d00003290sv*sd*bc*sc*i*
alias:          pci:v00001A3Bd00001059sv*sd*bc*sc*i*
alias:          pci:v00001462d0000891Asv*sd*bc*sc*i*
alias:          pci:v00001432d00007768sv*sd*bc*sc*i*
alias:          pci:v00001432d00007758sv*sd*bc*sc*i*
alias:          pci:v00001432d00007748sv*sd*bc*sc*i*
alias:          pci:v00001432d00007738sv*sd*bc*sc*i*
alias:          pci:v00001432d00007728sv*sd*bc*sc*i*
alias:          pci:v00001432d00007727sv*sd*bc*sc*i*
alias:          pci:v00001432d00007708sv*sd*bc*sc*i*
alias:          pci:v00001814d00003092sv*sd*bc*sc*i*
alias:          pci:v00001814d00003091sv*sd*bc*sc*i*
alias:          pci:v00001814d00003090sv*sd*bc*sc*i*
alias:          pci:v00001814d00000781sv*sd*bc*sc*i*
alias:          pci:v00001814d00000701sv*sd*bc*sc*i*
alias:          pci:v00001814d00000681sv*sd*bc*sc*i*
alias:          pci:v00001814d00000601sv*sd*bc*sc*i*
depends:        rt2x00lib,rt2800lib,rt2800mmio,rt2x00mmio,rt2x00pci,eeprom_93cx6
intree:         Y
vermagic:       3.13.0-29-generic SMP mod_unload modversions 
signer:         Magrathea: Glacier signing key
sig_key:        66:02:CB:36:F1:31:3B:EA:01:C4:BD:A9:65:67:CF:A7:23:C9:70:D8
sig_hashalgo:   sha512
parm:           nohwcrypt:Disable hardware encryption. (bool)
--------------- EOF

--------------- Citing syslog:
[445100.737414] ieee80211 phy2: rt2x00_set_rt: Info - RT chipset 2872, rev 0200 detected
[445100.755377] ieee80211 phy2: rt2x00_set_rf: Info - RF chipset 0003 detected
[445100.755758] ieee80211 phy2: Selected rate control algorithm 'minstrel_ht'
[445100.840829] ieee80211 phy2: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2860.bin'
[445100.840902] ieee80211 phy2: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.34
--------------- EOF

--------------- HOSTAPD -ddd (uncommented MFP):
1402550656.709730: random: Trying to read entropy from /dev/random
1402550656.709908: Configuration file: /etc/hostapd/hostapd.conf
1402550656.711455: nl80211: Could not add multicast membership for vendor events: -2 (No such file or directory)
1402550656.711637: rfkill: initial event: idx=2 type=1 op=0 soft=0 hard=0
1402550656.712198: nl80211: Supported cipher 00-0f-ac:1
1402550656.712274: nl80211: Supported cipher 00-0f-ac:5
1402550656.712310: nl80211: Supported cipher 00-0f-ac:2
1402550656.712342: nl80211: Supported cipher 00-0f-ac:4
1402550656.712436: nl80211: Using driver-based off-channel TX
1402550656.712560: nl80211: interface wlan0 in phy phy2
1402550656.712717: nl80211: Set mode ifindex 6 iftype 3 (AP)
1402550656.712860: nl80211: Failed to set interface 6 to mode 3: -16 (Device or resource busy)
1402550656.712976: nl80211: Try mode change after setting interface down
1402550656.726906: nl80211: Set mode ifindex 6 iftype 3 (AP)
1402550656.790516: nl80211: Mode change succeeded while interface is down
1402550656.790621: nl80211: Setup AP(wlan0) - device_ap_sme=0 use_monitor=0
1402550656.790719: nl80211: Subscribe to mgmt frames with AP handle 0x16769f0
1402550656.790791: nl80211: Register frame type=0xb0 nl_handle=0x16769f0 match=
1402550656.790900: nl80211: Register frame type=0x0 nl_handle=0x16769f0 match=
1402550656.790997: nl80211: Register frame type=0x20 nl_handle=0x16769f0 match=
1402550656.791085: nl80211: Register frame type=0xa0 nl_handle=0x16769f0 match=
1402550656.791172: nl80211: Register frame type=0xc0 nl_handle=0x16769f0 match=
1402550656.791256: nl80211: Register frame type=0xd0 nl_handle=0x16769f0 match=
1402550656.791348: nl80211: Register frame type=0x40 nl_handle=0x16769f0 match=
1402550656.791789: nl80211: Add own interface ifindex 6
1402550656.791891: phy: phy2
1402550656.791966: BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits)
1402550656.792149: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE
1402550656.792386: Previous country code RU, new country code RU 
1402550656.792871: nl80211: Regulatory information - country=RU
1402550656.792929: nl80211: 2402-2482 @ 40 MHz 20 mBm
1402550656.792965: nl80211: 5735-5835 @ 20 MHz 30 mBm
1402550656.793028: nl80211: Added 802.11b mode based on 802.11g information
1402550656.793069: Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm
1402550656.793106: Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm
1402550656.793140: Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm
1402550656.793171: Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm
1402550656.793203: Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm
1402550656.793235: Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm
1402550656.793266: Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm
1402550656.793298: Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm
1402550656.793329: Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm
1402550656.793361: Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm
1402550656.793392: Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm
1402550656.793424: Allowed channel: mode=1 chan=12 freq=2467 MHz max_tx_power=20 dBm
1402550656.793456: Allowed channel: mode=1 chan=13 freq=2472 MHz max_tx_power=20 dBm
1402550656.793488: Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm
1402550656.793520: Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm
1402550656.793604: Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm
1402550656.793638: Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm
1402550656.793668: Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm
1402550656.793697: Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm
1402550656.793727: Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm
1402550656.793758: Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm
1402550656.793789: Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm
1402550656.793820: Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm
1402550656.793852: Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm
1402550656.793883: Allowed channel: mode=0 chan=12 freq=2467 MHz max_tx_power=20 dBm
1402550656.793912: Allowed channel: mode=0 chan=13 freq=2472 MHz max_tx_power=20 dBm
1402550656.794045: wlan0: interface state COUNTRY_UPDATE->HT_SCAN
1402550656.794085: Scan for neighboring BSSes prior to enabling 40 MHz channel
1402550656.794118: 40 MHz affected channel range: [2402,2452] MHz
1402550656.794179: wlan0: nl80211: scan request
1402550656.794243: nl80211: Scan frequency 2412 MHz
1402550656.794280: nl80211: Scan frequency 2417 MHz
1402550656.794312: nl80211: Scan frequency 2422 MHz
1402550656.794342: nl80211: Scan frequency 2427 MHz
1402550656.794371: nl80211: Scan frequency 2432 MHz
1402550656.794400: nl80211: Scan frequency 2437 MHz
1402550656.794428: nl80211: Scan frequency 2442 MHz
1402550656.794456: nl80211: Scan frequency 2447 MHz
1402550656.794485: nl80211: Scan frequency 2452 MHz
1402550656.794763: Scan requested (ret=0) - scan timeout 10 seconds
1402550656.794852: Interface initialization will be completed in a callback
1402550656.794884: ctrl_iface not configured!
1402550656.795005: random: Got 20/20 bytes from /dev/random
1402550656.795194: RTM_NEWLINK: ifi_index=6 ifname=wlan0 operstate=2 linkmode=0 ifi_flags=0x1002 ()
1402550656.795328: nl80211: Ignore interface down event since interface wlan0 is up
1402550656.795387: RTM_NEWLINK: ifi_index=6 ifname=wlan0 operstate=2 linkmode=0 ifi_flags=0x1003 ([UP])
1402550656.795477: nl80211: Event message available
1402550656.795562: nl80211: Drv Event 33 (NL80211_CMD_TRIGGER_SCAN) received for wlan0
1402550656.795614: wlan0: nl80211: Scan trigger
1402550656.795668: wlan0: Event SCAN_STARTED (49) received
1402550656.795711: Unknown event 49
1402550657.432080: nl80211: Beacon event message available
1402550657.432194: wlan0: Event RX_MGMT (20) received
1402550657.432263: unknown vendor specific information element ignored (vendor OUI 00:10:18 len=9)
1402550657.432334: Add randomness: count=1 entropy=0
1402550657.432368: random pool - hexdump(len=128): [REMOVED]
1402550657.432407: random_mix_pool - hexdump(len=16): [REMOVED]
1402550657.432439: random_mix_pool - hexdump(len=12): [REMOVED]
1402550657.432469: random pool - hexdump(len=128): [REMOVED]
1402550657.634312: nl80211: Beacon event message available
1402550657.634431: wlan0: Event RX_MGMT (20) received
1402550657.634469: unknown vendor specific information element ignored (vendor OUI 00:0c:43 len=7)
1402550657.634512: Add randomness: count=2 entropy=1
1402550657.634541: random pool - hexdump(len=128): [REMOVED]
1402550657.634569: random_mix_pool - hexdump(len=16): [REMOVED]
1402550657.634599: random_mix_pool - hexdump(len=12): [REMOVED]
1402550657.634626: random pool - hexdump(len=128): [REMOVED]
1402550657.838054: nl80211: Event message available
1402550657.838161: nl80211: Drv Event 34 (NL80211_CMD_NEW_SCAN_RESULTS) received for wlan0
1402550657.838200: wlan0: nl80211: New scan results available
1402550657.838237: nl80211: Scan included frequencies: 2412 2417 2422 2427 2432 2437 2442 2447 2452
1402550657.838268: wlan0: Event SCAN_RESULTS (3) received
1402550657.838393: nl80211: Received scan results (2 BSSes)
1402550657.838480: 40 MHz affected channel range: [2402,2452] MHz
1402550657.838510: unknown vendor specific information element ignored (vendor OUI 00:10:18 len=9)
1402550657.838572: Neighboring BSS: 00:23:54:f6:67:ad freq=2437 pri=6 sec=2
1402550657.838600: 40 MHz pri/sec mismatch with BSS 00:23:54:f6:67:ad <2437,2417> (chan=6-) vs. <2417,2437>
1402550657.838631: 20/40 MHz operation not permitted on channel pri=2 sec=6 based on overlapping BSSes
1402550657.838656: Completing interface initialization
1402550657.838679: Mode: IEEE 802.11g  Channel: 2  Frequency: 2417 MHz
1402550657.838716: DFS 0 channels required radar detection
1402550657.838741: nl80211: Set freq 2417 (ht_enabled=1, vht_enabled=0, bandwidth=20 MHz, cf1=2417 MHz, cf2=0 MHz)
1402550657.838814: RATE[0] rate=10 flags=0x1
1402550657.838842: RATE[1] rate=20 flags=0x1
1402550657.838866: RATE[2] rate=55 flags=0x1
1402550657.838889: RATE[3] rate=110 flags=0x1
1402550657.838912: RATE[4] rate=60 flags=0x0
1402550657.838935: RATE[5] rate=90 flags=0x0
1402550657.838959: RATE[6] rate=120 flags=0x0
1402550657.838982: RATE[7] rate=180 flags=0x0
1402550657.839006: RATE[8] rate=240 flags=0x0
1402550657.839030: RATE[9] rate=360 flags=0x0
1402550657.839052: RATE[10] rate=480 flags=0x0
1402550657.839075: RATE[11] rate=540 flags=0x0
1402550657.839099: hostapd_setup_bss(hapd=0x1677380 (wlan0), first=1)
1402550657.839130: wlan0: Flushing old station entries
1402550657.839157: nl80211: flush -> DEL_STATION wlan0 (all)
1402550657.846121: wlan0: Deauthenticate all stations
1402550657.846209: nl80211: send_mlme - noack=0 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0xc0 nlmode=3
1402550657.846247: nl80211: send_mlme -> send_frame
1402550657.846277: nl80211: send_frame - Use bss->freq=2417
1402550657.846307: nl80211: send_frame -> send_frame_cmd
1402550657.846342: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=0 offchanok=0
1402550657.846374: CMD_FRAME - hexdump(len=26): c0 00 00 00 ff ff ff ff ff ff 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 02 00
1402550657.846522: nl80211: Frame command failed: ret=-16 (Device or resource busy) (freq=2417 wait=0)
1402550657.846653: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
1402550657.846817: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
1402550657.846970: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
1402550657.847116: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
1402550657.847229: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=0 addr=(nil) key_idx=4 set_tx=0 seq_len=0 key_len=0
1402550657.847314: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=0 addr=(nil) key_idx=5 set_tx=0 seq_len=0 key_len=0
1402550657.847374: Using interface wlan0 with hwaddr 00:15:af:ed:a9:6c and ssid "test"
1402550657.847401: Deriving WPA PSK based on passphrase
1402550657.847423: SSID - hexdump_ascii(len=4):
     74 65 73 74                                       test            
1402550657.847492: PSK (ASCII passphrase) - hexdump_ascii(len=11): [REMOVED]
1402550657.870595: PSK (from passphrase) - hexdump(len=32): [REMOVED]
1402550657.870733: Get randomness: len=32 entropy=2
1402550657.872052: random from os_get_random - hexdump(len=32): [REMOVED]
1402550657.872145: random_mix_pool - hexdump(len=20): [REMOVED]
1402550657.872191: random from internal pool - hexdump(len=16): [REMOVED]
1402550657.872237: random_mix_pool - hexdump(len=20): [REMOVED]
1402550657.872290: random from internal pool - hexdump(len=16): [REMOVED]
1402550657.872321: mixed random - hexdump(len=32): [REMOVED]
1402550657.872351: GMK - hexdump(len=32): [REMOVED]
1402550657.872404: Get randomness: len=32 entropy=0
1402550657.873663: random from os_get_random - hexdump(len=32): [REMOVED]
1402550657.873731: random_mix_pool - hexdump(len=20): [REMOVED]
1402550657.873766: random from internal pool - hexdump(len=16): [REMOVED]
1402550657.873798: random_mix_pool - hexdump(len=20): [REMOVED]
1402550657.873843: random from internal pool - hexdump(len=16): [REMOVED]
1402550657.873874: mixed random - hexdump(len=32): [REMOVED]
1402550657.874063: Key Counter - hexdump(len=32): [REMOVED]
1402550657.874118: WPA: Delay group state machine start until Beacon frames have been configured
1402550657.882034: VLAN: vlan_set_name_type(name_type=2)
1402550657.882309: nl80211: Set beacon (beacon_set=0)
1402550657.882364: nl80211: Beacon head - hexdump(len=55): 80 00 00 00 ff ff ff ff ff ff 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02
1402550657.882444: nl80211: Beacon tail - hexdump(len=133): 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550657.882583: nl80211: ifindex=6
1402550657.882614: nl80211: beacon_int=100
1402550657.882647: nl80211: dtim_period=2
1402550657.882678: nl80211: ssid - hexdump_ascii(len=4):
     74 65 73 74                                       test            
1402550657.882749: nl80211: hidden SSID not in use
1402550657.882780: nl80211: privacy=1
1402550657.882809: nl80211: auth_algs=0x1
1402550657.882841: nl80211: wpa_version=0x2
1402550657.882879: nl80211: key_mgmt_suites=0x2
1402550657.882909: nl80211: pairwise_ciphers=0x10
1402550657.882940: nl80211: group_cipher=0x10
1402550657.882971: nl80211: beacon_ies - hexdump(len=10): 7f 08 00 00 00 00 00 00 00 40
1402550657.883017: nl80211: proberesp_ies - hexdump(len=10): 7f 08 00 00 00 00 00 00 00 40
1402550657.883057: nl80211: assocresp_ies - hexdump(len=10): 7f 08 00 00 00 00 00 00 00 40
1402550657.890601: WPA: Start group state machine to set initial keys
1402550657.890658: WPA: group state machine entering state GTK_INIT (VLAN-ID 0)
1402550657.890682: Get randomness: len=16 entropy=0
1402550657.891932: random from os_get_random - hexdump(len=16): [REMOVED]
1402550657.892001: random_mix_pool - hexdump(len=20): [REMOVED]
1402550657.892035: random from internal pool - hexdump(len=16): [REMOVED]
1402550657.892059: mixed random - hexdump(len=16): [REMOVED]
1402550657.892110: GTK - hexdump(len=16): [REMOVED]
1402550657.892144: Get randomness: len=16 entropy=0
1402550657.893375: random from os_get_random - hexdump(len=16): [REMOVED]
1402550657.893439: random_mix_pool - hexdump(len=20): [REMOVED]
1402550657.893472: random from internal pool - hexdump(len=16): [REMOVED]
1402550657.893495: mixed random - hexdump(len=16): [REMOVED]
1402550657.893531: IGTK - hexdump(len=16): [REMOVED]
1402550657.893563: WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
1402550657.893666: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=3 addr=0x4a6a6a key_idx=1 set_tx=1 seq_len=0 key_len=16
1402550657.893726:    broadcast key
1402550657.894225: wpa_driver_nl80211_set_key: ifindex=6 (wlan0) alg=4 addr=0x4a6a6a key_idx=4 set_tx=1 seq_len=0 key_len=16
1402550657.894301:    broadcast key
1402550657.894396: nl80211: set_key failed; err=-22 Invalid argument)
1402550657.894433: WPA: group state machine entering state FATAL_FAILURE
1402550657.894562: RTM_NEWLINK: ifi_index=6 ifname=wlan0 wext ifi_flags=0x1003 ([UP])
1402550657.894686: RTM_NEWLINK: ifi_index=6 ifname=wlan0 operstate=6 linkmode=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1402550657.894792: VLAN: vlan_newlink(wlan0)
1402550668.574652: nl80211: Event message available
1402550668.574763: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550668.574798: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=b0:65:bd:d5:85:77
1402550668.574840: nl80211: MLME event frame - hexdump(len=116): 40 00 00 00 ff ff ff ff ff ff b0 65 bd d5 85 77 ff ff ff ff ff ff a0 00 00 00 01 04 02 04 0b 16 32 08 0c 12 18 24 30 48 60 6c 2d 1a 00 01 19 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 01 02 dd 09 00 10 18 02 00 00 00 00 00 dd 1e 00 90 4c 33 00 01 19 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402550668.575017: nl80211: Frame event
1402550668.575051: nl80211: RX frame freq=2417 ssi_signal=-82 stype=4 len=116
1402550668.575094: wlan0: Event RX_MGMT (20) received
1402550668.575133: unknown vendor specific information element ignored (vendor OUI 00:10:18 len=9)
1402550668.575178: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550668.575205: nl80211: send_mlme -> send_frame
1402550668.575225: nl80211: send_frame - Use bss->freq=2417
1402550668.575245: nl80211: send_frame -> send_frame_cmd
1402550668.575268: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550668.575290: CMD_FRAME - hexdump(len=188): 50 00 00 00 b0 65 bd d5 85 77 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550668.575500: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550668.575542: STA b0:65:bd:d5:85:77 sent probe request for broadcast SSID
1402550668.575578: Add randomness: count=3 entropy=0
1402550668.575612: random pool - hexdump(len=128): [REMOVED]
1402550668.575643: random_mix_pool - hexdump(len=16): [REMOVED]
1402550668.575675: random_mix_pool - hexdump(len=12): [REMOVED]
1402550668.575705: random pool - hexdump(len=128): [REMOVED]
1402550673.988300: nl80211: Event message available
1402550673.988408: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550673.988443: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=44:6d:57:a6:6e:b7
1402550673.988485: nl80211: MLME event frame - hexdump(len=70): 40 00 00 00 ff ff ff ff ff ff 44 6d 57 a6 6e b7 ff ff ff ff ff ff 80 22 00 00 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402550673.988582: nl80211: Frame event
1402550673.988616: nl80211: RX frame freq=2417 ssi_signal=-60 stype=4 len=70
1402550673.988661: wlan0: Event RX_MGMT (20) received
1402550673.988711: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550673.988750: nl80211: send_mlme -> send_frame
1402550673.988781: nl80211: send_frame - Use bss->freq=2417
1402550673.988813: nl80211: send_frame -> send_frame_cmd
1402550673.988840: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550673.988865: CMD_FRAME - hexdump(len=188): 50 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550673.989066: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550673.989095: STA 44:6d:57:a6:6e:b7 sent probe request for broadcast SSID
1402550673.989122: Add randomness: count=4 entropy=1
1402550673.989146: random pool - hexdump(len=128): [REMOVED]
1402550673.989169: random_mix_pool - hexdump(len=16): [REMOVED]
1402550673.989191: random_mix_pool - hexdump(len=12): [REMOVED]
1402550673.989214: random pool - hexdump(len=128): [REMOVED]
1402550674.018025: nl80211: Event message available
1402550674.018127: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550674.018164: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=44:6d:57:a6:6e:b7
1402550674.018251: nl80211: MLME event frame - hexdump(len=70): 40 00 00 00 ff ff ff ff ff ff 44 6d 57 a6 6e b7 ff ff ff ff ff ff 90 22 00 00 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402550674.018347: nl80211: Frame event
1402550674.018379: nl80211: RX frame freq=2417 ssi_signal=-62 stype=4 len=70
1402550674.018420: wlan0: Event RX_MGMT (20) received
1402550674.018468: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550674.018505: nl80211: send_mlme -> send_frame
1402550674.018535: nl80211: send_frame - Use bss->freq=2417
1402550674.018566: nl80211: send_frame -> send_frame_cmd
1402550674.018599: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550674.018630: CMD_FRAME - hexdump(len=188): 50 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550674.018924: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550674.018979: STA 44:6d:57:a6:6e:b7 sent probe request for broadcast SSID
1402550674.019017: Add randomness: count=5 entropy=2
1402550674.019048: random pool - hexdump(len=128): [REMOVED]
1402550674.019079: random_mix_pool - hexdump(len=16): [REMOVED]
1402550674.019110: random_mix_pool - hexdump(len=12): [REMOVED]
1402550674.019141: random pool - hexdump(len=128): [REMOVED]
1402550677.182042: nl80211: Event message available
1402550677.182151: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550677.182185: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=e4:2d:02:cf:22:d4
1402550677.182228: nl80211: MLME event frame - hexdump(len=207): 40 00 00 00 ff ff ff ff ff ff e4 2d 02 cf 22 d4 ff ff ff ff ff ff 90 a5 00 00 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c dd 90 00 50 f2 04 10 4a 00 01 10 10 3a 00 01 00 10 08 00 02 43 88 10 47 00 10 7f 54 81 b9 e3 a1 50 aa b3 e9 ed 77 9d d6 bd b3 10 54 00 08 00 0a 00 50 f2 04 00 05 10 3c 00 01 01 10 02 00 02 00 00 10 09 00 02 00 00 10 12 00 02 00 00 10 21 00 03 54 43 54 10 23 00 0f 4f 4e 45 20 54 4f 55 43 48 20 36 30 31 32 58 10 24 00 0f 4f 4e 45 20 54 4f 55 43 48 20 36 30 31 32 58 10 11 00 0a 43 61 6c 69 66 6f 72 6e 69 61 10 49 00 06 00 37 2a 00 01 20 dd 11 50 6f 9a 09 02 02 00 24 00 06 05 00 58 58 04 51 06
1402550677.182425: nl80211: Frame event
1402550677.182462: nl80211: RX frame freq=2417 ssi_signal=-90 stype=4 len=207
1402550677.182509: wlan0: Event RX_MGMT (20) received
1402550677.182560: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550677.182588: nl80211: send_mlme -> send_frame
1402550677.182610: nl80211: send_frame - Use bss->freq=2417
1402550677.182631: nl80211: send_frame -> send_frame_cmd
1402550677.182655: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550677.182679: CMD_FRAME - hexdump(len=188): 50 00 00 00 e4 2d 02 cf 22 d4 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550677.182879: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550677.182948: STA e4:2d:02:cf:22:d4 sent probe request for broadcast SSID
1402550677.182976: Add randomness: count=6 entropy=3
1402550677.182998: random pool - hexdump(len=128): [REMOVED]
1402550677.183019: random_mix_pool - hexdump(len=16): [REMOVED]
1402550677.183040: random_mix_pool - hexdump(len=12): [REMOVED]
1402550677.183060: random pool - hexdump(len=128): [REMOVED]
1402550677.184030: nl80211: Event message available
1402550677.184115: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550677.184150: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=e4:2d:02:cf:22:d4
1402550677.184190: nl80211: MLME event frame - hexdump(len=207): 40 00 00 00 ff ff ff ff ff ff e4 2d 02 cf 22 d4 ff ff ff ff ff ff a0 a5 00 00 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c dd 90 00 50 f2 04 10 4a 00 01 10 10 3a 00 01 00 10 08 00 02 43 88 10 47 00 10 7f 54 81 b9 e3 a1 50 aa b3 e9 ed 77 9d d6 bd b3 10 54 00 08 00 0a 00 50 f2 04 00 05 10 3c 00 01 01 10 02 00 02 00 00 10 09 00 02 00 00 10 12 00 02 00 00 10 21 00 03 54 43 54 10 23 00 0f 4f 4e 45 20 54 4f 55 43 48 20 36 30 31 32 58 10 24 00 0f 4f 4e 45 20 54 4f 55 43 48 20 36 30 31 32 58 10 11 00 0a 43 61 6c 69 66 6f 72 6e 69 61 10 49 00 06 00 37 2a 00 01 20 dd 11 50 6f 9a 09 02 02 00 24 00 06 05 00 58 58 04 51 06
1402550677.184390: nl80211: Frame event
1402550677.184426: nl80211: RX frame freq=2417 ssi_signal=-86 stype=4 len=207
1402550677.184469: wlan0: Event RX_MGMT (20) received
1402550677.184514: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550677.184547: nl80211: send_mlme -> send_frame
1402550677.184574: nl80211: send_frame - Use bss->freq=2417
1402550677.184601: nl80211: send_frame -> send_frame_cmd
1402550677.184632: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550677.184661: CMD_FRAME - hexdump(len=188): 50 00 00 00 e4 2d 02 cf 22 d4 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550677.184949: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550677.184994: STA e4:2d:02:cf:22:d4 sent probe request for broadcast SSID
1402550677.185027: Add randomness: count=7 entropy=4
1402550677.185057: random pool - hexdump(len=128): [REMOVED]
1402550677.185085: random_mix_pool - hexdump(len=16): [REMOVED]
1402550677.185114: random_mix_pool - hexdump(len=12): [REMOVED]
1402550677.185142: random pool - hexdump(len=128): [REMOVED]
1402550685.718076: nl80211: Event message available
1402550685.718185: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550685.718220: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=00:15:af:ed:a9:6c A2=44:6d:57:a6:6e:b7
1402550685.718264: nl80211: MLME event frame - hexdump(len=74): 40 00 3a 01 00 15 af ed a9 6c 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 10 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402550685.718363: nl80211: Frame event
1402550685.718397: nl80211: RX frame freq=2417 ssi_signal=-54 stype=4 len=74
1402550685.718442: wlan0: Event RX_MGMT (20) received
1402550685.718493: nl80211: send_mlme - noack=0 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550685.718532: nl80211: send_mlme -> send_frame
1402550685.718563: nl80211: send_frame - Use bss->freq=2417
1402550685.718594: nl80211: send_frame -> send_frame_cmd
1402550685.718622: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=0 offchanok=0
1402550685.718646: CMD_FRAME - hexdump(len=188): 50 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550685.718895: nl80211: Frame TX command accepted; cookie 0xffff88007a0d9700
1402550685.718925: STA 44:6d:57:a6:6e:b7 sent probe request for our SSID
1402550685.718951: Add randomness: count=8 entropy=5
1402550685.718973: random pool - hexdump(len=128): [REMOVED]
1402550685.718995: random_mix_pool - hexdump(len=16): [REMOVED]
1402550685.719016: random_mix_pool - hexdump(len=12): [REMOVED]
1402550685.719036: random pool - hexdump(len=128): [REMOVED]
1402550685.721006: nl80211: Event message available
1402550685.721091: nl80211: Drv Event 60 (NL80211_CMD_FRAME_TX_STATUS) received for wlan0
1402550685.721126: nl80211: MLME event 60 (NL80211_CMD_FRAME_TX_STATUS) on wlan0(00:15:af:ed:a9:6c) A1=44:6d:57:a6:6e:b7 A2=00:15:af:ed:a9:6c
1402550685.721165: nl80211: MLME event frame - hexdump(len=188): 50 00 3a 01 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 40 7b 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550685.721349: nl80211: Frame TX status event
1402550685.721394: wlan0: Event TX_STATUS (18) received
1402550685.721429: mgmt::proberesp cb
1402550685.721553: nl80211: Event message available
1402550685.721618: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550685.721651: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=00:15:af:ed:a9:6c A2=44:6d:57:a6:6e:b7
1402550685.721692: nl80211: MLME event frame - hexdump(len=30): b0 00 3a 01 00 15 af ed a9 6c 44 6d 57 a6 6e b7 00 15 af ed a9 6c 10 10 00 00 01 00 00 00
1402550685.721744: nl80211: Frame event
1402550685.721774: nl80211: RX frame freq=2417 ssi_signal=-62 stype=11 len=30
1402550685.721814: wlan0: Event RX_MGMT (20) received
1402550685.721846: mgmt::auth
1402550685.721874: authentication: STA=44:6d:57:a6:6e:b7 auth_alg=0 auth_transaction=1 status_code=0 wep=0
1402550685.721989:   New STA
1402550685.722031: ap_sta_add: register ap_handle_timer timeout for 44:6d:57:a6:6e:b7 (300 seconds - ap_max_inactivity)
1402550685.722085: 1402550685.722089: wlan0: STA 44:6d:57:a6:6e:b7 IEEE 802.11: authentication OK (open system)
1402550685.722155: 1402550685.722159: wlan0: STA 44:6d:57:a6:6e:b7 MLME: MLME-AUTHENTICATE.indication(44:6d:57:a6:6e:b7, OPEN_SYSTEM)
1402550685.722205: 1402550685.722209: wlan0: STA 44:6d:57:a6:6e:b7 MLME: MLME-DELETEKEYS.request(44:6d:57:a6:6e:b7)
1402550685.722236: authentication reply: STA=44:6d:57:a6:6e:b7 auth_alg=0 auth_transaction=2 resp=0 (IE len=0)
1402550685.722271: nl80211: send_mlme - noack=0 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0xb0 nlmode=3
1402550685.722301: nl80211: send_mlme -> send_frame
1402550685.722328: nl80211: send_frame - Use bss->freq=2417
1402550685.722356: nl80211: send_frame -> send_frame_cmd
1402550685.722387: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=0 offchanok=0
1402550685.722417: CMD_FRAME - hexdump(len=30): b0 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 02 00 00 00
1402550685.722591: nl80211: Frame TX command accepted; cookie 0xffff88007a0d9b00
1402550685.722647: Add randomness: count=9 entropy=6
1402550685.722681: random pool - hexdump(len=128): [REMOVED]
1402550685.722756: random_mix_pool - hexdump(len=16): [REMOVED]
1402550685.722788: random_mix_pool - hexdump(len=12): [REMOVED]
1402550685.722819: random pool - hexdump(len=128): [REMOVED]
1402550685.723417: nl80211: Event message available
1402550685.723495: nl80211: Drv Event 60 (NL80211_CMD_FRAME_TX_STATUS) received for wlan0
1402550685.723529: nl80211: MLME event 60 (NL80211_CMD_FRAME_TX_STATUS) on wlan0(00:15:af:ed:a9:6c) A1=44:6d:57:a6:6e:b7 A2=00:15:af:ed:a9:6c
1402550685.723569: nl80211: MLME event frame - hexdump(len=30): b0 00 3a 01 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 50 7b 00 00 02 00 00 00
1402550685.723621: nl80211: Frame TX status event
1402550685.723656: wlan0: Event TX_STATUS (18) received
1402550685.723688: mgmt::auth cb
1402550685.723726: 1402550685.723730: wlan0: STA 44:6d:57:a6:6e:b7 IEEE 802.11: authenticated
1402550685.725466: nl80211: Event message available
1402550685.725554: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550685.725588: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=00:15:af:ed:a9:6c A2=44:6d:57:a6:6e:b7
1402550685.725629: nl80211: MLME event frame - hexdump(len=151): 00 00 3a 01 00 15 af ed a9 6c 44 6d 57 a6 6e b7 00 15 af ed a9 6c 20 10 31 04 01 00 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 3c 00 32 04 30 48 60 6c dd 07 00 50 f2 02 00 01 00 2d 1a 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 dd 1e 00 90 4c 33 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 01 00 00 80 00 00 00 00
1402550685.725787: nl80211: Frame event
1402550685.725819: nl80211: RX frame freq=2417 ssi_signal=-64 stype=0 len=151
1402550685.725862: wlan0: Event RX_MGMT (20) received
1402550685.725899: mgmt::assoc_req
1402550685.725963: association request: STA=44:6d:57:a6:6e:b7 capab_info=0x431 listen_interval=1
1402550685.726008: WMM IE - hexdump(len=7): 00 50 f2 02 00 01 00
1402550685.726059: Validating WMM IE: OUI 00:50:f2  OUI type 2  OUI sub-type 0  version 1  QoS info 0x0
1402550685.726102: Failed to initialize WPA state machine
1402550685.726136: nl80211: send_mlme - noack=0 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x10 nlmode=3
1402550685.726168: nl80211: send_mlme -> send_frame
1402550685.726195: nl80211: send_frame - Use bss->freq=2417
1402550685.726222: nl80211: send_frame -> send_frame_cmd
1402550685.726254: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=0 offchanok=0
1402550685.726284: CMD_FRAME - hexdump(len=134): 10 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 11 04 01 00 00 c0 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550685.726552: nl80211: Frame TX command accepted; cookie 0xffff88007a0d9500
1402550685.726601: Add randomness: count=10 entropy=7
1402550685.726635: random pool - hexdump(len=128): [REMOVED]
1402550685.726663: random_mix_pool - hexdump(len=16): [REMOVED]
1402550685.726693: random_mix_pool - hexdump(len=12): [REMOVED]
1402550685.726721: random pool - hexdump(len=128): [REMOVED]
1402550685.728210: nl80211: Event message available
1402550685.728290: nl80211: Drv Event 60 (NL80211_CMD_FRAME_TX_STATUS) received for wlan0
1402550685.728323: nl80211: MLME event 60 (NL80211_CMD_FRAME_TX_STATUS) on wlan0(00:15:af:ed:a9:6c) A1=44:6d:57:a6:6e:b7 A2=00:15:af:ed:a9:6c
1402550685.728362: nl80211: MLME event frame - hexdump(len=134): 10 00 3a 01 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 60 7b 11 04 01 00 00 c0 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550685.728547: nl80211: Frame TX status event
1402550685.728594: wlan0: Event TX_STATUS (18) received
1402550685.728627: mgmt::assoc_resp cb
1402550686.048731: nl80211: Event message available
1402550686.048875: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550686.048907: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=44:6d:57:a6:6e:b7
1402550686.048947: nl80211: MLME event frame - hexdump(len=70): 40 00 00 00 ff ff ff ff ff ff 44 6d 57 a6 6e b7 ff ff ff ff ff ff a0 10 00 00 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402550686.049039: nl80211: Frame event
1402550686.049071: nl80211: RX frame freq=2417 ssi_signal=-64 stype=4 len=70
1402550686.049113: wlan0: Event RX_MGMT (20) received
1402550686.049163: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550686.049201: nl80211: send_mlme -> send_frame
1402550686.049231: nl80211: send_frame - Use bss->freq=2417
1402550686.049261: nl80211: send_frame -> send_frame_cmd
1402550686.049289: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550686.049312: CMD_FRAME - hexdump(len=188): 50 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550686.049509: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550686.049536: STA 44:6d:57:a6:6e:b7 sent probe request for broadcast SSID
1402550686.049561: Add randomness: count=11 entropy=8
1402550686.049582: random pool - hexdump(len=128): [REMOVED]
1402550686.049603: random_mix_pool - hexdump(len=16): [REMOVED]
1402550686.049625: random_mix_pool - hexdump(len=12): [REMOVED]
1402550686.049645: random pool - hexdump(len=128): [REMOVED]
1402550686.078482: nl80211: Event message available
1402550686.078582: nl80211: BSS Event 59 (NL80211_CMD_FRAME) received for wlan0
1402550686.078613: nl80211: MLME event 59 (NL80211_CMD_FRAME) on wlan0(00:15:af:ed:a9:6c) A1=ff:ff:ff:ff:ff:ff A2=44:6d:57:a6:6e:b7
1402550686.078652: nl80211: MLME event frame - hexdump(len=70): 40 00 00 00 ff ff ff ff ff ff 44 6d 57 a6 6e b7 ff ff ff ff ff ff b0 10 00 00 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c 2d 1a 6e 01 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402550686.078746: nl80211: Frame event
1402550686.078778: nl80211: RX frame freq=2417 ssi_signal=-62 stype=4 len=70
1402550686.078820: wlan0: Event RX_MGMT (20) received
1402550686.078870: nl80211: send_mlme - noack=1 freq=0 no_cck=0 offchanok=0 wait_time=0 fc=0x50 nlmode=3
1402550686.078906: nl80211: send_mlme -> send_frame
1402550686.078937: nl80211: send_frame - Use bss->freq=2417
1402550686.078967: nl80211: send_frame -> send_frame_cmd
1402550686.078999: nl80211: CMD_FRAME freq=2417 wait=0 no_cck=0 no_ack=1 offchanok=0
1402550686.079029: CMD_FRAME - hexdump(len=188): 50 00 00 00 44 6d 57 a6 6e b7 00 15 af ed a9 6c 00 15 af ed a9 6c 00 00 00 00 00 00 00 00 00 00 64 00 11 04 00 04 74 65 73 74 01 08 82 84 8b 96 0c 12 18 24 03 01 02 07 06 52 55 20 01 0d 14 2a 01 04 32 04 30 48 60 6c 30 1a 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 cc 00 00 00 00 0f ac 06 2d 1a 72 02 13 ff ff 00 00 01 00 00 00 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 3d 16 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 00 00 00 00 00 00 00 40 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5d 00 62 32 2e 00
1402550686.079319: nl80211: Frame TX command accepted (no ACK); cookie 0x0
1402550686.079418: STA 44:6d:57:a6:6e:b7 sent probe request for broadcast SSID
1402550686.079458: Add randomness: count=12 entropy=9
1402550686.079490: random pool - hexdump(len=128): [REMOVED]
1402550686.079521: random_mix_pool - hexdump(len=16): [REMOVED]
1402550686.079554: random_mix_pool - hexdump(len=12): [REMOVED]
1402550686.079585: random pool - hexdump(len=128): [REMOVED]
--------------- EOF

--------------- HOSTAPD -ddd (commented out MFP):
1402552593.813322: 1402552593.813326: wlan0: STA 44:6d:57:a6:6e:b7 IEEE 802.11: associated (aid 1)
1402552593.813576: 1402552593.813583: wlan0: STA 44:6d:57:a6:6e:b7 MLME: MLME-ASSOCIATE.indication(44:6d:57:a6:6e:b7)
1402552593.813641: 1402552593.813645: wlan0: STA 44:6d:57:a6:6e:b7 MLME: MLME-DELETEKEYS.request(44:6d:57:a6:6e:b7)
1402552593.813751: wpa_driver_nl80211_set_key: ifindex=7 (wlan0) alg=0 addr=0xfc3ed0 key_idx=0 set_tx=1 seq_len=0 key_len=0
1402552593.813802:    addr=44:6d:57:a6:6e:b7
1402552593.814021: nl80211: sta_remove -> DEL_STATION wlan0 44:6d:57:a6:6e:b7 --> -2 (No such file or directory)
1402552593.814080: nl80211: Add STA 44:6d:57:a6:6e:b7
1402552593.814169:   * supported rates - hexdump(len=12): 82 84 8b 96 0c 12 18 24 30 48 60 6c
1402552593.814217:   * aid=1
1402552593.814247:   * listen_interval=1
1402552593.814276:   * ht_capabilities - hexdump(len=26): 6e 00 03 ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1402552593.814329:   * capability=0x431
1402552593.814361:   * flags set=0xc mask=0xc
1402552593.814393:   * qosinfo=0x0
1402552593.814920: 1402552593.814927: wlan0: STA 44:6d:57:a6:6e:b7 WPA: event 1 notification
1402552593.815054: wpa_driver_nl80211_set_key: ifindex=7 (wlan0) alg=0 addr=0xfc3ed0 key_idx=0 set_tx=1 seq_len=0 key_len=0
1402552593.815102:    addr=44:6d:57:a6:6e:b7
1402552593.815198: IEEE 802.1X: Ignore STA - 802.1X not enabled or forced for WPS
1402552593.815267: 1402552593.815271: wlan0: STA 44:6d:57:a6:6e:b7 WPA: start authentication
1402552593.815299: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state INITIALIZE
1402552593.815386: wpa_driver_nl80211_set_key: ifindex=7 (wlan0) alg=0 addr=0xfc3ed0 key_idx=0 set_tx=1 seq_len=0 key_len=0
1402552593.815441:    addr=44:6d:57:a6:6e:b7
1402552593.815670: 1402552593.815676: wlan0: STA 44:6d:57:a6:6e:b7 IEEE 802.1X: unauthorizing port
1402552593.815718: WPA: 44:6d:57:a6:6e:b7 WPA_PTK_GROUP entering state IDLE
1402552593.815753: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state AUTHENTICATION
1402552593.815787: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state AUTHENTICATION2
1402552593.815819: WPA: Re-initialize GMK/Counter on first station
1402552593.815847: Get randomness: len=32 entropy=119
1402552593.817140: random from os_get_random - hexdump(len=32): [REMOVED]
1402552593.817214: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.817260: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.817307: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.817353: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.817385: mixed random - hexdump(len=32): [REMOVED]
1402552593.817415: GMK - hexdump(len=32): [REMOVED]
1402552593.817446: Get randomness: len=32 entropy=87
1402552593.818657: random from os_get_random - hexdump(len=32): [REMOVED]
1402552593.818716: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.818760: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.818892: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.818945: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.818976: mixed random - hexdump(len=32): [REMOVED]
1402552593.819031: Key Counter - hexdump(len=32): [REMOVED]
1402552593.819064: Get randomness: len=16 entropy=55
1402552593.820273: random from os_get_random - hexdump(len=16): [REMOVED]
1402552593.820332: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.820376: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.820408: mixed random - hexdump(len=16): [REMOVED]
1402552593.820503: GTK - hexdump(len=16): [REMOVED]
1402552593.820595: wpa_driver_nl80211_set_key: ifindex=7 (wlan0) alg=3 addr=0x4a6a6a key_idx=1 set_tx=1 seq_len=0 key_len=16
1402552593.820644:    broadcast key
1402552593.821040: Get randomness: len=32 entropy=39
1402552593.822277: random from os_get_random - hexdump(len=32): [REMOVED]
1402552593.822346: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.822394: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.822440: random_mix_pool - hexdump(len=20): [REMOVED]
1402552593.822487: random from internal pool - hexdump(len=16): [REMOVED]
1402552593.822517: mixed random - hexdump(len=32): [REMOVED]
1402552593.822547: WPA: Assign ANonce - hexdump(len=32): a1 2a 27 78 9b c6 85 d8 49 31 e9 c2 96 ec f8 65 6d 71 e6 99 16 0d 04 26 91 76 d8 b9 4e 2f 35 d2
1402552593.822605: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state INITPSK
1402552593.822639: Searching a PSK for 44:6d:57:a6:6e:b7 p2p_dev_addr=00:00:00:00:00:00 prev_psk=(nil)
1402552593.822678: Searching a PSK for 44:6d:57:a6:6e:b7 p2p_dev_addr=00:00:00:00:00:00 prev_psk=(nil)
1402552593.822715: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state PTKSTART
1402552593.822764: 1402552593.822768: wlan0: STA 44:6d:57:a6:6e:b7 WPA: sending 1/4 msg of 4-Way Handshake
1402552593.822876: WPA: Send EAPOL(version=2 secure=0 mic=0 ack=1 install=0 pairwise=1 kde_len=0 keyidx=0 encr=0)
1402552593.823172: WPA: Use EAPOL-Key timeout of 100 ms (retry counter 1)
1402552593.823228: hostapd_new_assoc_sta: reschedule ap_handle_timer timeout for 44:6d:57:a6:6e:b7 (300 seconds - ap_max_inactivity)
1402552593.823322: nl80211: Event message available
1402552593.823382: nl80211: Drv Event 19 (NL80211_CMD_NEW_STATION) received for wlan0
1402552593.823415: nl80211: New station 44:6d:57:a6:6e:b7
1402552593.823553: wlan0: Event EAPOL_TX_STATUS (40) received
1402552593.823599: IEEE 802.1X: 44:6d:57:a6:6e:b7 TX status - version=2 type=3 length=95 - ack=1
1402552593.823634: WPA: EAPOL-Key TX status for STA 44:6d:57:a6:6e:b7 ack=1
1402552593.823667: WPA: Increase initial EAPOL-Key 1/4 timeout by 1000 ms because of acknowledged frame
1402552593.825918: wlan0: Event EAPOL_RX (26) received
1402552593.825975: IEEE 802.1X: 121 bytes from 44:6d:57:a6:6e:b7
1402552593.826009:    IEEE 802.1X: version=1 type=3 length=117
1402552593.826039: WPA: Received EAPOL-Key from 44:6d:57:a6:6e:b7 key_info=0x10a type=2 key_data_length=22
1402552593.826075: WPA: Received Key Nonce - hexdump(len=32): 64 98 37 12 db cd 98 d0 ab dd b0 de 52 fe c6 69 26 fc 91 b7 f6 c1 ac 83 5c 10 30 1f b1 f4 96 1d
1402552593.826543: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01
1402552593.826609: Add randomness: count=123 entropy=7
1402552593.826640: random pool - hexdump(len=128): [REMOVED]
1402552593.826670: random_mix_pool - hexdump(len=16): [REMOVED]
1402552593.826702: random_mix_pool - hexdump(len=32): [REMOVED]
1402552593.826734: random pool - hexdump(len=128): [REMOVED]
1402552593.826852: 1402552593.826856: wlan0: STA 44:6d:57:a6:6e:b7 WPA: received EAPOL-Key frame (2/4 Pairwise)
1402552593.826922: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state PTKCALCNEGOTIATING
1402552593.826956: Searching a PSK for 44:6d:57:a6:6e:b7 p2p_dev_addr=00:00:00:00:00:00 prev_psk=(nil)
1402552593.827045: WPA: PTK derivation - A1=00:15:af:ed:a9:6c A2=44:6d:57:a6:6e:b7
1402552593.827082: WPA: Nonce1 - hexdump(len=32): a1 2a 27 78 9b c6 85 d8 49 31 e9 c2 96 ec f8 65 6d 71 e6 99 16 0d 04 26 91 76 d8 b9 4e 2f 35 d2
1402552593.827139: WPA: Nonce2 - hexdump(len=32): 64 98 37 12 db cd 98 d0 ab dd b0 de 52 fe c6 69 26 fc 91 b7 f6 c1 ac 83 5c 10 30 1f b1 f4 96 1d
1402552593.827192: WPA: PMK - hexdump(len=32): [REMOVED]
1402552593.827222: WPA: PTK - hexdump(len=48): [REMOVED]
1402552593.827270: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state PTKCALCNEGOTIATING2
1402552593.827305: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state PTKINITNEGOTIATING
1402552593.827518: 1402552593.827522: wlan0: STA 44:6d:57:a6:6e:b7 WPA: sending 3/4 msg of 4-Way Handshake
1402552593.827568: WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=1 pairwise=1 kde_len=46 keyidx=1 encr=1)
1402552593.827655: Plaintext EAPOL-Key Key Data - hexdump(len=56): [REMOVED]
1402552593.827899: WPA: Use EAPOL-Key timeout of 100 ms (retry counter 1)
1402552593.828347: wlan0: Event EAPOL_TX_STATUS (40) received
1402552593.828399: IEEE 802.1X: 44:6d:57:a6:6e:b7 TX status - version=2 type=3 length=151 - ack=1
1402552593.828435: WPA: EAPOL-Key TX status for STA 44:6d:57:a6:6e:b7 ack=1
1402552593.832059: wlan0: Event EAPOL_RX (26) received
1402552593.832110: IEEE 802.1X: 99 bytes from 44:6d:57:a6:6e:b7
1402552593.832143:    IEEE 802.1X: version=1 type=3 length=95
1402552593.832173: WPA: Received EAPOL-Key from 44:6d:57:a6:6e:b7 key_info=0x30a type=2 key_data_length=0
1402552593.832209: WPA: Received Key Nonce - hexdump(len=32): 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
1402552593.832266: WPA: Received Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 02
1402552593.832320: 1402552593.832324: wlan0: STA 44:6d:57:a6:6e:b7 WPA: received EAPOL-Key frame (4/4 Pairwise)
1402552593.832385: WPA: 44:6d:57:a6:6e:b7 WPA_PTK entering state PTKINITDONE
1402552593.832483: wpa_driver_nl80211_set_key: ifindex=7 (wlan0) alg=3 addr=0xfc3ed0 key_idx=0 set_tx=1 seq_len=0 key_len=16
1402552593.832532:    addr=44:6d:57:a6:6e:b7
1402552593.832759: wlan0: AP-STA-CONNECTED 44:6d:57:a6:6e:b7
1402552593.832936: 1402552593.832940: wlan0: STA 44:6d:57:a6:6e:b7 IEEE 802.1X: authorizing port
1402552593.832992: 1402552593.832995: wlan0: STA 44:6d:57:a6:6e:b7 RADIUS: starting accounting session 539940FB-00000000
1402552593.833116: 1402552593.833120: wlan0: STA 44:6d:57:a6:6e:b7 WPA: pairwise key handshake completed (RSN)
1402552593.965207: nl80211: Beacon event message available
--------------- EOF



More information about the users mailing list