[pkg-wpa-devel] Bug#564568: many retries before connection succeeds

Daniel Pocock daniel at pocock.com.au
Sun Jan 10 10:50:37 UTC 2010 

Package: wpasupplicant

I notice that wpa_supplicant sometimes tries many times before the 
connection eventually succeeds for a particular AP.

When running under network-manager, it sometimes gives up, and I have to 
go to the menu and select the AP to make it try again.  I am repeatedly 
prompted for the WPA password, even though it has the key already.

When running it from the command line with a simple config file, it 
keeps retrying and eventually succeeds.

The system is a Thinkpad X61, lenny amd64.  I am currently trying it 
with firmware-iwlwifi 0.22, I have also tried 0.18 (from testing) and 
the 0.14+lenny2 package from lenny.

network={
    ssid="SpeedTouch1"
    key_mgmt=WPA-PSK
    proto=WPA
    pairwise=TKIP
    group=TKIP
    psk=<hidden>
}


lspci -v:

03:00.0 Network controller: Intel Corporation PRO/Wireless 4965 AG or 
AGN [Kedron] Network Connection (rev 61)
    Subsystem: Intel Corporation Lenovo ThinkPad T51
    Flags: fast devsel, IRQ 17
    Memory at f7f00000 (64-bit, non-prefetchable) [size=8K]
    Capabilities: [c8] Power Management version 3
    Capabilities: [d0] Message Signalled Interrupts: Mask- 64bit+ 
Queue=0/0 Enable-
    Capabilities: [e0] Express Endpoint, MSI 00
    Capabilities: [100] Advanced Error Reporting <?>
    Capabilities: [140] Device Serial Number a9-56-af-ff-ff-e8-13-00
    Kernel driver in use: iwl4965
    Kernel modules: iwl4965

# uname -a
Linux lt2 2.6.26-2-amd64 #1 SMP Fri Aug 14 07:12:04 UTC 2009 x86_64 
GNU/Linux


 From dmesg:


[ 4727.877159] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 4730.152521] wlan0: Initial auth_alg=0
[ 4730.152521] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4730.154099] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4730.154109] wlan0: authenticated
[ 4730.154115] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4730.156859] wlan0: RX AssocResp from 00:90:d0:db:ad:19 (capab=0x411 
status=0 aid=1)
[ 4730.156859] wlan0: associated
[ 4730.156859] wlan0: switched to short barker preamble 
(BSSID=00:90:d0:db:ad:19)
[ 4730.176019] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 4741.144458] wlan0: no IPv6 routers present
[ 4741.192917] wlan0: disassociate(reason=3)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.815095] wlan0: Initial auth_alg=0
[ 4743.815095] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4743.815095] wlan0: association frame received from 00:90:d0:db:ad:19, 
but not in associate state - ignored
[ 4743.821222] wlan0: Initial auth_alg=0
[ 4743.821222] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4743.821222] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4743.821222] wlan0: authenticated
[ 4743.821222] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4743.825142] wlan0: authentication frame received from 
00:90:d0:db:ad:19, but not in authenticate state - ignored
[ 4743.827965] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 
status=0 aid=1)
[ 4743.827977] wlan0: associated
[ 4743.828000] wlan0: switched to short barker preamble 
(BSSID=00:90:d0:db:ad:19)
[ 4754.841820] wlan0: disassociate(reason=3)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4757.456924] wlan0: Initial auth_alg=0
[ 4757.456924] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4757.456924] wlan0: association frame received from 00:90:d0:db:ad:19, 
but not in associate state - ignored
[ 4757.462968] wlan0: Initial auth_alg=0
[ 4757.462968] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4757.462968] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4757.462968] wlan0: authenticated
[ 4757.462968] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4757.466115] wlan0: authentication frame received from 
00:90:d0:db:ad:19, but not in authenticate state - ignored
[ 4757.470892] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 
status=0 aid=1)
[ 4757.470892] wlan0: associated
[ 4757.470892] wlan0: switched to short barker preamble 
(BSSID=00:90:d0:db:ad:19)
[ 4768.487853] wlan0: disassociate(reason=3)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.177560] wlan0: Initial auth_alg=0
[ 4771.177560] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4771.177560] wlan0: association frame received from 00:90:d0:db:ad:19, 
but not in associate state - ignored
[ 4771.180076] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4771.180076] wlan0: authenticated
[ 4771.180076] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4771.181961] wlan0: Initial auth_alg=0
[ 4771.181961] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4771.185788] wlan0: association frame received from 00:90:d0:db:ad:19, 
but not in associate state - ignored
[ 4771.188749] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4771.188749] wlan0: authenticated
[ 4771.188749] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4771.201855] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 
status=0 aid=1)
[ 4771.201855] wlan0: associated
[ 4771.201855] wlan0: switched to short barker preamble 
(BSSID=00:90:d0:db:ad:19)
[ 4782.232515] wlan0: disassociate(reason=3)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.856414] wlan0: Initial auth_alg=0
[ 4784.856414] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4784.856414] wlan0: association frame received from 00:90:d0:db:ad:19, 
but not in associate state - ignored
[ 4784.862561] wlan0: Initial auth_alg=0
[ 4784.862561] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4784.862561] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4784.862561] wlan0: authenticated
[ 4784.862561] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4784.865141] wlan0: authentication frame received from 
00:90:d0:db:ad:19, but not in authenticate state - ignored
[ 4784.870828] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 
status=0 aid=1)
[ 4784.870828] wlan0: associated
[ 4784.870828] wlan0: switched to short barker preamble 
(BSSID=00:90:d0:db:ad:19)
[ 4795.933142] wlan0: disassociate(reason=3)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.656857] wlan0: Initial auth_alg=0
[ 4798.656857] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4798.656857] wlan0: association frame received from 00:90:d0:db:ad:19, 
but not in associate state - ignored
[ 4798.663745] wlan0: Initial auth_alg=0
[ 4798.663745] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4798.663745] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 
transaction=2 status=0)
[ 4798.663745] wlan0: authenticated
[ 4798.663745] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4798.668536] wlan0: authentication frame received from 
00:90:d0:db:ad:19, but not in authenticate state - ignored
[ 4798.668548] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 
status=0 aid=1)
[ 4798.668551] wlan0: associated
[ 4798.668566] wlan0: switched to short barker preamble 
(BSSID=00:90:d0:db:ad:19)


# wpa_supplicant -c /root/wpa-speedtouch1.conf -i wlan0 -d -t

.......
1263119480.783866: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119480.783871: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119480.783881: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119480.783889: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119480.783897: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119480.783905: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119480.783934: Driver did not support SIOCSIWENCODEEXT
1263119480.783941: State: DISCONNECTED -> DISCONNECTED
1263119480.783946: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119480.783952: WEXT: Operstate: linkmode=-1, operstate=5
1263119480.783961: EAPOL: External notification - portEnabled=0
1263119480.783967: EAPOL: External notification - portValid=0
1263119480.783972: EAPOL: External notification - EAP success=0
1263119480.783980: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.783986: Wireless event: cmd=0x8b06 len=12
1263119480.783993: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.783999: Wireless event: cmd=0x8b04 len=16
1263119480.784005: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.784011: Wireless event: cmd=0x8b1a len=27
1263119480.784018: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.784023: Wireless event: cmd=0x8c02 len=128
1263119480.791248: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
1263119480.791261: Wireless event: cmd=0x8b15 len=24
1263119480.791266: Wireless event: new AP: 00:90:d0:db:ad:19
1263119480.791273: State: DISCONNECTED -> ASSOCIATED
1263119480.791279: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119480.791285: WEXT: Operstate: linkmode=-1, operstate=5
1263119480.791301: Associated to a new BSS: BSSID=00:90:d0:db:ad:19
1263119480.791308: No keys have been configured - skip key clearing
1263119480.791315: Associated with 00:90:d0:db:ad:19
1263119480.791320: WPA: Association event - clear replay counter
1263119480.791325: WPA: Clear old PTK
1263119480.791330: EAPOL: External notification - portEnabled=0
1263119480.798511: EAPOL: External notification - portValid=0
1263119480.798518: EAPOL: External notification - EAP success=0
1263119480.798523: EAPOL: External notification - portEnabled=1
1263119480.798528: EAPOL: SUPP_PAE entering state CONNECTING
1263119480.798533: EAPOL: SUPP_BE entering state IDLE
1263119480.798541: Setting authentication timeout: 10 sec 0 usec
1263119480.798549: Cancelling scan request
1263119480.798557: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
1263119480.798564: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119481.782445: RX EAPOL from 00:90:d0:db:ad:19
1263119481.782491: Setting authentication timeout: 10 sec 0 usec
1263119481.782509: IEEE 802.1X RX: version=1 type=3 length=95
1263119481.782520:   EAPOL-Key type=254
1263119481.782529:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
1263119481.782541:   key_length=32 key_data_length=0
1263119481.782549:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 
00 01
1263119481.782560:   key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 
76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e6
1263119481.782582:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119481.782597:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119481.782608:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 
00 00 00
1263119481.782619:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119481.782656: State: ASSOCIATED -> 4WAY_HANDSHAKE
1263119481.782668: WPA: RX message 1 of 4-Way Handshake from 
00:90:d0:db:ad:19 (ver=1)
1263119481.784817: WPA: Renewed SNonce - hexdump(len=32): 0f 9b 0f ee 6e 
e8 25 c5 9c 6a b5 ce 78 f8 8a 89 a6 92 9d 6e 1c 93 11 74 8c 14 dc 5e c4 
85 36 bd
1263119481.784875: WPA: PTK derivation - A1=00:13:e8:af:56:a9 
A2=00:90:d0:db:ad:19
1263119481.784888: WPA: PMK - hexdump(len=32): [REMOVED]
1263119481.784899: WPA: PTK - hexdump(len=64): [REMOVED]
1263119481.784908: WPA: WPA IE for msg 2/4 - hexdump(len=24): dd 16 00 
50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
1263119481.784931: WPA: Sending EAPOL-Key 2/4
1263119483.330463: EAPOL: startWhen --> 0
1263119483.330496: EAPOL: disable timer tick
1263119483.330508: EAPOL: SUPP_PAE entering state CONNECTING
1263119483.330516: EAPOL: enable timer tick
1263119483.330528: EAPOL: txStart
1263119483.330536: WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
1263119491.786482: Authentication with 00:90:d0:db:ad:19 timed out.
1263119491.786514: BSSID 00:90:d0:db:ad:19 blacklist count incremented to 2
1263119491.786529: wpa_driver_wext_disassociate
1263119491.786641: No keys have been configured - skip key clearing
1263119491.786654: State: 4WAY_HANDSHAKE -> DISCONNECTED
1263119491.786664: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119491.786674: WEXT: Operstate: linkmode=-1, operstate=5
1263119491.786697: EAPOL: External notification - portEnabled=0
1263119491.786706: EAPOL: SUPP_PAE entering state DISCONNECTED
1263119491.786713: EAPOL: SUPP_BE entering state INITIALIZE
1263119491.786724: EAPOL: External notification - portValid=0
1263119491.786734: EAPOL: External notification - EAP success=0
1263119491.786748: Setting scan request: 0 sec 0 usec
1263119491.787106: State: DISCONNECTED -> SCANNING
1263119491.787123: Starting AP scan (broadcast SSID)
1263119491.799096: Scan requested (ret=0) - scan timeout 30 seconds
1263119491.799134: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119491.799148: Wireless event: cmd=0x8b15 len=24
1263119491.799157: Wireless event: new AP: 00:00:00:00:00:00
1263119491.799169: BSSID 00:00:00:00:00:00 blacklist count incremented to 10
1263119491.799186: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119491.799195: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119491.799212: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119491.799224: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119491.799236: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119491.799248: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119491.799339: Driver did not support SIOCSIWENCODEEXT
1263119491.799348: State: SCANNING -> DISCONNECTED
1263119491.799357: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119491.799366: WEXT: Operstate: linkmode=-1, operstate=5
1263119491.799382: EAPOL: External notification - portEnabled=0
1263119491.799391: EAPOL: External notification - portValid=0
1263119491.799399: EAPOL: External notification - EAP success=0
1263119491.799409: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119491.799419: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119494.475672: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.475839: Wireless event: cmd=0x8b19 len=16
1263119494.475934: Received 1485 bytes of scan results (5 BSSes)
1263119494.475950: CTRL-EVENT-SCAN-RESULTS
1263119494.475955: Selecting BSS from priority group 0
1263119494.475960: Try to find WPA-enabled AP
1263119494.475964: 0: 00:90:d0:db:ad:19 ssid='SpeedTouch1' wpa_ie_len=24 
rsn_ie_len=0 caps=0x11
1263119494.475971:    skip - blacklisted
1263119494.475975: 1: 00:22:3f:93:e8:f5 ssid='Vermilion' wpa_ie_len=0 
rsn_ie_len=20 caps=0x11
1263119494.475981:    skip - SSID mismatch
1263119494.475984: 2: 00:26:f2:0f:04:2c ssid='NETGEARSA' wpa_ie_len=26 
rsn_ie_len=24 caps=0x11
1263119494.475991:    skip - SSID mismatch
1263119494.475995: 3: 00:1f:9f:ce:fa:6f ssid='O2wirelessD2D249' 
wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476001:    skip - no WPA/RSN IE
1263119494.476004: 4: 00:1d:68:4f:19:5b ssid='BTHomeHub-4B93' 
wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476010:    skip - no WPA/RSN IE
1263119494.476015: Try to find non-WPA AP
1263119494.476018: 0: 00:90:d0:db:ad:19 ssid='SpeedTouch1' wpa_ie_len=24 
rsn_ie_len=0 caps=0x11
1263119494.476024:    skip - blacklisted
1263119494.476028: 1: 00:22:3f:93:e8:f5 ssid='Vermilion' wpa_ie_len=0 
rsn_ie_len=20 caps=0x11
1263119494.476034:    skip - SSID mismatch
1263119494.476038: 2: 00:26:f2:0f:04:2c ssid='NETGEARSA' wpa_ie_len=26 
rsn_ie_len=24 caps=0x11
1263119494.476043:    skip - SSID mismatch
1263119494.476047: 3: 00:1f:9f:ce:fa:6f ssid='O2wirelessD2D249' 
wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476053:    skip - SSID mismatch
1263119494.476056: 4: 00:1d:68:4f:19:5b ssid='BTHomeHub-4B93' 
wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476062:    skip - SSID mismatch
1263119494.476065: No APs found - clear blacklist and try again
1263119494.476070: Removed BSSID 00:00:00:00:00:00 from blacklist (clear)
1263119494.476075: Removed BSSID 00:90:d0:db:ad:19 from blacklist (clear)
1263119494.476079: Selecting BSS from priority group 0
1263119494.476084: Try to find WPA-enabled AP
1263119494.476088: 0: 00:90:d0:db:ad:19 ssid='SpeedTouch1' wpa_ie_len=24 
rsn_ie_len=0 caps=0x11
1263119494.476095:    selected based on WPA IE
1263119494.476098:    selected WPA AP 00:90:d0:db:ad:19 ssid='SpeedTouch1'
1263119494.476103: Try to find non-WPA AP
1263119494.476110: Trying to associate with 00:90:d0:db:ad:19 
(SSID='SpeedTouch1' freq=2462 MHz)
1263119494.476115: Cancelling scan request
1263119494.476120: WPA: clearing own WPA/RSN IE
1263119494.476124: Automatic auth_alg selection: 0x1
1263119494.476133: WPA: using IEEE 802.11i/D3.0
1263119494.476137: WPA: Selected cipher suites: group 8 pairwise 8 
key_mgmt 2 proto 1
1263119494.476143: WPA: set AP WPA IE - hexdump(len=26): dd 18 00 50 f2 
01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 0c 00
1263119494.476154: WPA: clearing AP RSN IE
1263119494.476158: WPA: using GTK TKIP
1263119494.476162: WPA: using PTK TKIP
1263119494.476167: WPA: using KEY_MGMT WPA-PSK
1263119494.476171: WPA: not using MGMT group cipher
1263119494.476176: WPA: Set own WPA IE default - hexdump(len=24): dd 16 
00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
1263119494.476186: No keys have been configured - skip key clearing
1263119494.476190: wpa_driver_wext_set_drop_unencrypted
1263119494.476196: State: DISCONNECTED -> ASSOCIATING
1263119494.476201: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.476205: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.476217: wpa_driver_wext_associate
1263119494.476234: wpa_driver_wext_set_psk
1263119494.476659: Setting authentication timeout: 10 sec 0 usec
1263119494.476670: EAPOL: External notification - EAP success=0
1263119494.476678: EAPOL: External notification - EAP fail=0
1263119494.476683: EAPOL: External notification - portControl=Auto
1263119494.476691: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.476697: Wireless event: cmd=0x8b15 len=24
1263119494.476701: Wireless event: new AP: 00:00:00:00:00:00
1263119494.476707: Added BSSID 00:90:d0:db:ad:19 into blacklist
1263119494.476713: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.476717: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.476726: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.476732: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.476738: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.476744: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.476796: Driver did not support SIOCSIWENCODEEXT
1263119494.476801: State: ASSOCIATING -> DISCONNECTED
1263119494.476805: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.476809: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.476816: EAPOL: External notification - portEnabled=0
1263119494.476821: EAPOL: External notification - portValid=0
1263119494.476825: EAPOL: External notification - EAP success=0
1263119494.476830: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.476834: Wireless event: cmd=0x8b15 len=24
1263119494.476838: Wireless event: new AP: 00:00:00:00:00:00
1263119494.476843: Added BSSID 00:00:00:00:00:00 into blacklist
1263119494.476855: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.476858: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.476865: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.476870: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.476875: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.476880: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.476899: Driver did not support SIOCSIWENCODEEXT
1263119494.476904: State: DISCONNECTED -> DISCONNECTED
1263119494.476907: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.476911: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.476917: EAPOL: External notification - portEnabled=0
1263119494.476921: EAPOL: External notification - portValid=0
1263119494.476925: EAPOL: External notification - EAP success=0
1263119494.476929: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.476934: Wireless event: cmd=0x8b15 len=24
1263119494.476937: Wireless event: new AP: 00:00:00:00:00:00
1263119494.476941: BSSID 00:00:00:00:00:00 blacklist count incremented to 2
1263119494.476947: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.476950: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.476956: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.476962: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.476967: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.476972: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.476990: Driver did not support SIOCSIWENCODEEXT
1263119494.476994: State: DISCONNECTED -> DISCONNECTED
1263119494.476998: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.477002: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.477008: EAPOL: External notification - portEnabled=0
1263119494.477011: EAPOL: External notification - portValid=0
1263119494.477015: EAPOL: External notification - EAP success=0
1263119494.477020: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.477024: Wireless event: cmd=0x8b15 len=24
1263119494.477027: Wireless event: new AP: 00:00:00:00:00:00
1263119494.477032: BSSID 00:00:00:00:00:00 blacklist count incremented to 3
1263119494.477037: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.477041: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.477047: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.490786: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.490802: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.490808: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.490859: Driver did not support SIOCSIWENCODEEXT
1263119494.490865: State: DISCONNECTED -> DISCONNECTED
1263119494.490869: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.490874: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.490887: EAPOL: External notification - portEnabled=0
1263119494.490894: EAPOL: External notification - portValid=0
1263119494.490898: EAPOL: External notification - EAP success=0
1263119494.490906: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.490912: Wireless event: cmd=0x8b15 len=24
1263119494.490916: Wireless event: new AP: 00:00:00:00:00:00
1263119494.490922: BSSID 00:00:00:00:00:00 blacklist count incremented to 4
1263119494.490930: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.490934: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.490941: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.490947: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.490953: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.490958: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.490979: Driver did not support SIOCSIWENCODEEXT
1263119494.490984: State: DISCONNECTED -> DISCONNECTED
1263119494.490988: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.490993: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.490999: EAPOL: External notification - portEnabled=0
1263119494.491003: EAPOL: External notification - portValid=0
1263119494.491007: EAPOL: External notification - EAP success=0
1263119494.491013: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.491018: Wireless event: cmd=0x8b15 len=24
1263119494.491022: Wireless event: new AP: 00:00:00:00:00:00
1263119494.491026: BSSID 00:00:00:00:00:00 blacklist count incremented to 5
1263119494.491032: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.491036: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.491043: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.491049: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.491054: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.491060: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.491080: Driver did not support SIOCSIWENCODEEXT
1263119494.491085: State: DISCONNECTED -> DISCONNECTED
1263119494.491089: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.491093: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.491099: EAPOL: External notification - portEnabled=0
1263119494.491103: EAPOL: External notification - portValid=0
1263119494.491107: EAPOL: External notification - EAP success=0
1263119494.491113: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.491117: Wireless event: cmd=0x8b15 len=24
1263119494.491121: Wireless event: new AP: 00:00:00:00:00:00
1263119494.491126: BSSID 00:00:00:00:00:00 blacklist count incremented to 6
1263119494.491131: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.491135: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.491141: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.491147: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.491153: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.526639: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.526694: Driver did not support SIOCSIWENCODEEXT
1263119494.526699: State: DISCONNECTED -> DISCONNECTED
1263119494.526704: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.526708: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.526721: EAPOL: External notification - portEnabled=0
1263119494.526727: EAPOL: External notification - portValid=0
1263119494.526731: EAPOL: External notification - EAP success=0
1263119494.526739: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.526744: Wireless event: cmd=0x8b15 len=24
1263119494.526748: Wireless event: new AP: 00:00:00:00:00:00
1263119494.526825: BSSID 00:00:00:00:00:00 blacklist count incremented to 7
1263119494.526833: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.526837: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.526843: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.526849: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.526854: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.526860: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.526879: Driver did not support SIOCSIWENCODEEXT
1263119494.526883: State: DISCONNECTED -> DISCONNECTED
1263119494.526887: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.526891: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.526897: EAPOL: External notification - portEnabled=0
1263119494.526901: EAPOL: External notification - portValid=0
1263119494.526905: EAPOL: External notification - EAP success=0
1263119494.526910: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.526914: Wireless event: cmd=0x8b15 len=24
1263119494.526918: Wireless event: new AP: 00:00:00:00:00:00
1263119494.526922: BSSID 00:00:00:00:00:00 blacklist count incremented to 8
1263119494.526927: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.526931: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.526943: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.526949: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.526955: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.526961: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.526981: Driver did not support SIOCSIWENCODEEXT
1263119494.526987: State: DISCONNECTED -> DISCONNECTED
1263119494.526991: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.526995: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.527002: EAPOL: External notification - portEnabled=0
1263119494.527006: EAPOL: External notification - portValid=0
1263119494.527010: EAPOL: External notification - EAP success=0
1263119494.527024: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.527030: Wireless event: cmd=0x8b15 len=24
1263119494.527035: Wireless event: new AP: 00:00:00:00:00:00
1263119494.527039: BSSID 00:00:00:00:00:00 blacklist count incremented to 9
1263119494.527045: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.527049: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.527056: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.527062: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.527067: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.527073: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.527093: Driver did not support SIOCSIWENCODEEXT
1263119494.532915: State: DISCONNECTED -> DISCONNECTED
1263119494.532928: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.532934: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.532947: EAPOL: External notification - portEnabled=0
1263119494.532954: EAPOL: External notification - portValid=0
1263119494.532959: EAPOL: External notification - EAP success=0
1263119494.532968: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.532974: Wireless event: cmd=0x8b06 len=12
1263119494.532980: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.532985: Wireless event: cmd=0x8b15 len=24
1263119494.532989: Wireless event: new AP: 00:00:00:00:00:00
1263119494.532995: BSSID 00:00:00:00:00:00 blacklist count incremented to 10
1263119494.533010: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.533014: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
1263119494.533026: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
1263119494.533033: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
1263119494.533038: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
1263119494.533044: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.533087: Driver did not support SIOCSIWENCODEEXT
1263119494.533092: State: DISCONNECTED -> DISCONNECTED
1263119494.533096: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.533101: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.533107: EAPOL: External notification - portEnabled=0
1263119494.533112: EAPOL: External notification - portValid=0
1263119494.533116: EAPOL: External notification - EAP success=0
1263119494.533121: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.533126: Wireless event: cmd=0x8b04 len=16
1263119494.533131: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.533135: Wireless event: cmd=0x8b1a len=27
1263119494.533141: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.533145: Wireless event: cmd=0x8c02 len=128
1263119494.533151: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
1263119494.533156: Wireless event: cmd=0x8b15 len=24
1263119494.533160: Wireless event: new AP: 00:90:d0:db:ad:19
1263119494.533165: State: DISCONNECTED -> ASSOCIATED
1263119494.533169: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.533173: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.533182: Associated to a new BSS: BSSID=00:90:d0:db:ad:19
1263119494.533187: No keys have been configured - skip key clearing
1263119494.533192: Associated with 00:90:d0:db:ad:19
1263119494.533196: WPA: Association event - clear replay counter
1263119494.533200: WPA: Clear old PTK
1263119494.533204: EAPOL: External notification - portEnabled=0
1263119494.533208: EAPOL: External notification - portValid=0
1263119494.533212: EAPOL: External notification - EAP success=0
1263119494.533215: EAPOL: External notification - portEnabled=1
1263119494.533219: EAPOL: SUPP_PAE entering state CONNECTING
1263119494.533223: EAPOL: SUPP_BE entering state IDLE
1263119494.533229: Setting authentication timeout: 10 sec 0 usec
1263119494.533235: Cancelling scan request
1263119494.533240: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
1263119494.533245: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119494.533255: RX EAPOL from 00:90:d0:db:ad:19
1263119494.533261: Setting authentication timeout: 10 sec 0 usec
1263119494.533267: IEEE 802.1X RX: version=1 type=3 length=95
1263119494.533272:   EAPOL-Key type=254
1263119494.533276:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
1263119494.533281:   key_length=32 key_data_length=0
1263119494.533285:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 
00 00
1263119494.533291:   key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 
76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e8
1263119494.533300:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119494.533417:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119494.533423:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 
00 00 00
1263119494.533428:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119494.533440: State: ASSOCIATED -> 4WAY_HANDSHAKE
1263119494.533444: WPA: RX message 1 of 4-Way Handshake from 
00:90:d0:db:ad:19 (ver=1)
1263119494.534290: WPA: Renewed SNonce - hexdump(len=32): bd ea 07 bd 57 
06 ce 5d 92 92 c4 93 92 4d 07 2b ec 05 a3 37 40 d5 89 b7 6d f9 83 51 d2 
7f e8 a9
1263119494.534314: WPA: PTK derivation - A1=00:13:e8:af:56:a9 
A2=00:90:d0:db:ad:19
1263119494.534319: WPA: PMK - hexdump(len=32): [REMOVED]
1263119494.534323: WPA: PTK - hexdump(len=64): [REMOVED]
1263119494.534326: WPA: WPA IE for msg 2/4 - hexdump(len=24): dd 16 00 
50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
1263119494.534336: WPA: Sending EAPOL-Key 2/4
1263119495.502077: RX EAPOL from 00:90:d0:db:ad:19
1263119495.502120: IEEE 802.1X RX: version=1 type=3 length=95
1263119495.502132:   EAPOL-Key type=254
1263119495.502140:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
1263119495.502154:   key_length=32 key_data_length=0
1263119495.502163:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 
00 01
1263119495.502176:   key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 
76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e8
1263119495.502198:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119495.502213:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119495.502225:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 
00 00 00
1263119495.502237:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119495.502273: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
1263119495.502283: WPA: RX message 1 of 4-Way Handshake from 
00:90:d0:db:ad:19 (ver=1)
1263119495.502326: WPA: PTK derivation - A1=00:13:e8:af:56:a9 
A2=00:90:d0:db:ad:19
1263119495.502338: WPA: PMK - hexdump(len=32): [REMOVED]
1263119495.502372: WPA: PTK - hexdump(len=64): [REMOVED]
1263119495.502383: WPA: WPA IE for msg 2/4 - hexdump(len=24): dd 16 00 
50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
1263119495.502409: WPA: Sending EAPOL-Key 2/4
1263119495.505083: RX EAPOL from 00:90:d0:db:ad:19
1263119495.505107: IEEE 802.1X RX: version=1 type=3 length=121
1263119495.505118:   EAPOL-Key type=254
1263119495.505126:   key_info 0x1c9 (ver=1 keyidx=0 rsvd=0 Pairwise 
Install Ack MIC)
1263119495.505139:   key_length=32 key_data_length=26
1263119495.505148:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 
00 02
1263119495.505160:   key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 
76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e8
1263119495.505183:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 
00 00 00 00 00 00 00 00
1263119495.505198:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119495.505210:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 
00 00 00
1263119495.505222:   key_mic - hexdump(len=16): 1d c1 f4 8d d3 85 3e 3d 
9e d4 e0 82 16 53 3c 45
1263119495.505246: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
1263119495.505256: WPA: RX message 3 of 4-Way Handshake from 
00:90:d0:db:ad:19 (ver=1)
1263119495.505267: WPA: IE KeyData - hexdump(len=26): dd 18 00 50 f2 01 
01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 0c 00
1263119495.505291: WPA: Sending EAPOL-Key 4/4
1263119495.505323: WPA: Installing PTK to the driver.
1263119495.505333: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
1263119495.505345: wpa_driver_wext_set_key: alg=2 key_idx=0 set_tx=1 
seq_len=6 key_len=32
1263119495.505375: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
1263119495.508271: RX EAPOL from 00:90:d0:db:ad:19
1263119495.508292: IEEE 802.1X RX: version=1 type=3 length=127
1263119495.508302:   EAPOL-Key type=254
1263119495.508310:   key_info 0x391 (ver=1 keyidx=1 rsvd=0 Group Ack MIC 
Secure)
1263119495.508322:   key_length=32 key_data_length=32
1263119495.508330:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 
00 03
1263119495.508343:   key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 
76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f 82
1263119495.508364:   key_iv - hexdump(len=16): ae ff 62 e1 9d 56 b8 53 
d4 2e ff e6 18 4a 0f e9
1263119495.508379:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119495.508391:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 
00 00 00
1263119495.508403:   key_mic - hexdump(len=16): ab dd ce a5 86 37 71 ce 
8e fa d1 d3 9e 5f 0f a4
1263119495.508426: WPA: RX message 1 of Group Key Handshake from 
00:90:d0:db:ad:19 (ver=1)
1263119495.508446: State: GROUP_HANDSHAKE -> GROUP_HANDSHAKE
1263119495.508456: WPA: Group Key - hexdump(len=32): [REMOVED]
1263119495.508465: WPA: Installing GTK to the driver (keyidx=1 tx=0 len=32).
1263119495.508474: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
1263119495.508485: wpa_driver_wext_set_key: alg=2 key_idx=1 set_tx=0 
seq_len=6 key_len=32
1263119495.508505: WPA: Sending EAPOL-Key 2/2
1263119495.508561: WPA: Key negotiation completed with 00:90:d0:db:ad:19 
[PTK=TKIP GTK=TKIP]
1263119495.508574: Cancelling authentication timeout
1263119495.508583: Removed BSSID 00:90:d0:db:ad:19 from blacklist
1263119495.508594: State: GROUP_HANDSHAKE -> COMPLETED
1263119495.508608: CTRL-EVENT-CONNECTED - Connection to 
00:90:d0:db:ad:19 completed (auth) [id=0 id_str=]
1263119495.508617: wpa_driver_wext_set_operstate: operstate 0->1 (UP)
1263119495.508628: WEXT: Operstate: linkmode=-1, operstate=6
1263119495.509391: EAPOL: External notification - portValid=1
1263119495.509405: EAPOL: External notification - EAP success=1
1263119495.509416: EAPOL: SUPP_PAE entering state AUTHENTICATING
1263119495.509424: EAPOL: SUPP_BE entering state SUCCESS
1263119495.509431: EAP: EAP entering state DISABLED
1263119495.509438: EAPOL: SUPP_PAE entering state AUTHENTICATED
1263119495.509445: EAPOL: SUPP_BE entering state IDLE
1263119495.509453: EAPOL authentication completed successfully
1263119495.509478: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 
([UP][RUNNING][LOWER_UP])
1263119495.509489: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119497.363490: EAPOL: startWhen --> 0
1263119497.363523: EAPOL: disable timer tick

More information about the Pkg-wpa-devel mailing list