Attachment 292153 Details for Bug 428591 – wpa log for working attempt

wpa log for working attempt

wpa.log.ok (text/plain), 60.00 KB, created by Rui Matos on 2008-01-18 14:59:09 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Rui Matos

Created: 2008-01-18 14:59:09 UTC

Size: 60.00 KB

patch

obsolete

>1200667338.991020: Initializing interface 'wlan0' conf '/home/jman/eduroam.conf' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
>1200667338.991036: Configuration file '/home/jman/eduroam.conf' -> '/home/jman/eduroam.conf'
>1200667338.991039: Reading configuration file '/home/jman/eduroam.conf'
>1200667338.991087: Priority group 0
>1200667338.991090:    id=0 ssid='eduroam'
>1200667338.991092:    id=1 ssid='myfriendgoo'
>1200667338.991094:    id=2 ssid='dropyourweapons'
>1200667338.991096:    id=3 ssid=''
>1200667338.991098: Initializing interface (2) 'wlan0'
>1200667338.992681: EAPOL: SUPP_PAE entering state DISCONNECTED
>1200667338.992685: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
>1200667338.992687: EAPOL: SUPP_BE entering state INITIALIZE
>1200667338.992692: EAP: EAP entering state DISABLED
>1200667338.992695: EAPOL: External notification - portEnabled=0
>1200667338.992698: EAPOL: External notification - portValid=0
>1200667339.009317: SIOCGIWRANGE: WE(compiled)=22 WE(source)=21 enc_capa=0xf
>1200667339.009333:   capabilities: key_mgmt 0xf enc 0xf
>1200667339.026035: WEXT: Operstate: linkmode=1, operstate=5
>1200667339.032606: Own MAC address: 00:1b:77:de:f9:26
>1200667339.032613: wpa_driver_wext_set_wpa
>1200667339.032622: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
>1200667339.032632: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
>1200667339.032638: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
>1200667339.032644: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
>1200667339.032650: wpa_driver_wext_set_countermeasures
>1200667339.032731: wpa_driver_wext_set_drop_unencrypted
>1200667339.032745: Setting scan request: 0 sec 100000 usec
>1200667339.032757: Added interface wlan0
>1200667339.032785: RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
>1200667339.032789: Wireless event: cmd=0x8b06 len=12
>1200667339.032796: Ignore event for foreign ifindex 3
>1200667339.032805: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667339.032808: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667339.133651: State: DISCONNECTED -> SCANNING
>1200667339.133677: Starting AP scan (broadcast SSID)
>1200667339.133682: Trying to get current scan results first without requesting a new scan to speed up initial association
>1200667339.133760: Received 0 bytes of scan results (0 BSSes)
>1200667339.133765: Scan results: 0
>1200667339.133771: Selecting BSS from priority group 0
>1200667339.133775: No suitable AP found.
>1200667339.133783: Setting scan request: 0 sec 0 usec
>1200667339.133796: Starting AP scan (broadcast SSID)
>1200667341.756098: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667341.756117: Wireless event: cmd=0x8b19 len=16
>1200667341.756190: Received 1235 bytes of scan results (5 BSSes)
>1200667341.756195: Scan results: 5
>1200667341.756203: Selecting BSS from priority group 0
>1200667341.756207: 0: 00:0f:24:d6:96:f0 ssid='eduroam' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
>1200667341.756230:    selected based on WPA IE
>1200667341.756241: Trying to associate with 00:0f:24:d6:96:f0 (SSID='eduroam' freq=2462 MHz)
>1200667341.756249: Cancelling scan request
>1200667341.756257: WPA: clearing own WPA/RSN IE
>1200667341.756265: Automatic auth_alg selection: 0x1
>1200667341.756280: WPA: using IEEE 802.11i/D3.0
>1200667341.756284: WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 1 proto 1
>1200667341.756290: 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 01 28 00
>1200667341.756308: WPA: clearing AP RSN IE
>1200667341.756312: WPA: using GTK TKIP
>1200667341.756316: WPA: using PTK TKIP
>1200667341.756320: WPA: using KEY_MGMT 802.1X
>1200667341.756324: 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 01
>1200667341.756341: No keys have been configured - skip key clearing
>1200667341.756345: wpa_driver_wext_set_drop_unencrypted
>1200667341.756351: State: SCANNING -> ASSOCIATING
>1200667341.756356: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667341.756361: WEXT: Operstate: linkmode=-1, operstate=5
>1200667341.756511: wpa_driver_wext_associate
>1200667341.759477: Setting authentication timeout: 15 sec 0 usec
>1200667341.759488: EAPOL: External notification - portControl=Auto
>1200667341.759506: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667341.759512: Wireless event: cmd=0x8b06 len=12
>1200667341.759521: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667341.759526: Wireless event: cmd=0x8b04 len=16
>1200667341.759533: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667341.759537: Wireless event: cmd=0x8b1a len=23
>1200667341.765938: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667341.765952: Wireless event: cmd=0x8c02 len=229
>1200667341.765967: Association info event
>1200667341.765971: req_ies - hexdump(len=58): 00 07 65 64 75 72 6f 61 6d 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 01 dd 07 00 50 f2 02 00 01 00
>1200667341.766001: resp_ies - hexdump(len=35): 01 07 96 18 24 30 48 60 6c dd 18 00 50 f2 02 01 01 8b 00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62 32 2f 00
>1200667341.766020: WPA: set own WPA/RSN IE - 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 01
>1200667341.766042: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667341.766048: Wireless event: cmd=0x8b15 len=24
>1200667341.766051: Wireless event: new AP: 00:0f:24:d6:96:f0
>1200667341.766057: State: ASSOCIATING -> ASSOCIATED
>1200667341.766063: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667341.766068: WEXT: Operstate: linkmode=-1, operstate=5
>1200667341.771708: Associated to a new BSS: BSSID=00:0f:24:d6:96:f0
>1200667341.771712: No keys have been configured - skip key clearing
>1200667341.771716: Associated with 00:0f:24:d6:96:f0
>1200667341.771724: WPA: Association event - clear replay counter
>1200667341.771726: EAPOL: External notification - portEnabled=0
>1200667341.771731: EAPOL: External notification - portValid=0
>1200667341.771734: EAPOL: External notification - portEnabled=1
>1200667341.771736: EAPOL: SUPP_PAE entering state CONNECTING
>1200667341.771738: EAPOL: SUPP_BE entering state IDLE
>1200667341.771740: EAP: EAP entering state INITIALIZE
>1200667341.771743: EAP: EAP entering state IDLE
>1200667341.771747: Setting authentication timeout: 10 sec 0 usec
>1200667341.771751: Cancelling scan request
>1200667341.771755: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667341.771759: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667341.774529: RX EAPOL from 00:0f:24:d6:96:f0
>1200667341.774594: Setting authentication timeout: 70 sec 0 usec
>1200667341.774601: EAPOL: Received EAP-Packet frame
>1200667341.774604: EAPOL: SUPP_PAE entering state RESTART
>1200667341.774607: EAP: EAP entering state INITIALIZE
>1200667341.774610: EAP: EAP entering state IDLE
>1200667341.774613: EAPOL: SUPP_PAE entering state AUTHENTICATING
>1200667341.774615: EAPOL: SUPP_BE entering state REQUEST
>1200667341.774617: EAPOL: getSuppRsp
>1200667341.774620: EAP: EAP entering state RECEIVED
>1200667341.774642: EAP: Received EAP-Request id=1 method=1 vendor=0 vendorMethod=0
>1200667341.774645: EAP: EAP entering state IDENTITY
>1200667341.774648: CTRL-EVENT-EAP-STARTED EAP authentication started
>1200667341.774651: EAP: EAP-Request Identity data - hexdump_ascii(len=42):
>     00 6e 65 74 77 6f 72 6b 69 64 3d 65 64 75 72 6f   _networkid=eduro
>     61 6d 2c 6e 61 73 69 64 3d 64 6f 63 2d 61 70 30   am,nasid=doc-ap0
>     36 2c 70 6f 72 74 69 64 3d 30                     6,portid=0      
>1200667341.774668: EAP: using real identity - hexdump_ascii(len=15):
>     72 75 69 2e 6d 61 74 6f 73 40 75 61 2e 70 74      rui.matos@ua.pt 
>1200667341.774675: EAP: EAP entering state SEND_RESPONSE
>1200667341.774682: EAP: EAP entering state IDLE
>1200667341.774690: EAPOL: SUPP_BE entering state RESPONSE
>1200667341.774692: EAPOL: txSuppRsp
>1200667341.774707: EAPOL: SUPP_BE entering state RECEIVE
>1200667341.843191: RX EAPOL from 00:0f:24:d6:96:f0
>1200667341.843196: EAPOL: Received EAP-Packet frame
>1200667341.843232: EAPOL: SUPP_BE entering state REQUEST
>1200667341.843234: EAPOL: getSuppRsp
>1200667341.843237: EAP: EAP entering state RECEIVED
>1200667341.843240: EAP: Received EAP-Request id=2 method=25 vendor=0 vendorMethod=0
>1200667341.843244: EAP: EAP entering state GET_METHOD
>1200667341.843247: EAP: Initialize selected EAP method: vendor 0 method 25 (PEAP)
>1200667341.843255: EAP-PEAP: Phase2 EAP types - hexdump(len=8): 00 00 00 00 1a 00 00 00
>1200667341.843311: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
>1200667341.843314: EAP: EAP entering state METHOD
>1200667341.843317: SSL: Received packet(len=6) - Flags 0x20
>1200667341.843320: EAP-PEAP: Start (server ver=0, own ver=1)
>1200667341.843323: EAP-PEAP: Using PEAP version 0
>1200667341.843354: SSL: (where=0x10 ret=0x1)
>1200667341.843389: SSL: (where=0x1001 ret=0x1)
>1200667341.843393: SSL: SSL_connect:before/connect initialization
>1200667341.843831: SSL: (where=0x1001 ret=0x1)
>1200667341.843837: SSL: SSL_connect:SSLv3 write client hello A
>1200667341.843847: SSL: (where=0x1002 ret=0xffffffff)
>1200667341.843851: SSL: SSL_connect:error in SSLv3 read server hello A
>1200667341.843857: SSL: SSL_connect - want more data
>1200667341.843861: SSL: 97 bytes pending from ssl_out
>1200667341.843866: SSL: 97 bytes left to be sent out (of total 97 bytes)
>1200667341.843871: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667341.843876: EAP: EAP entering state SEND_RESPONSE
>1200667341.843880: EAP: EAP entering state IDLE
>1200667341.843884: EAPOL: SUPP_BE entering state RESPONSE
>1200667341.843887: EAPOL: txSuppRsp
>1200667341.843914: EAPOL: SUPP_BE entering state RECEIVE
>1200667341.859978: RX EAPOL from 00:0f:24:d6:96:f0
>1200667341.860000: EAPOL: Received EAP-Packet frame
>1200667341.860007: EAPOL: SUPP_BE entering state REQUEST
>1200667341.860011: EAPOL: getSuppRsp
>1200667341.860015: EAP: EAP entering state RECEIVED
>1200667341.860033: EAP: Received EAP-Request id=3 method=25 vendor=0 vendorMethod=0
>1200667341.860039: EAP: EAP entering state METHOD
>1200667341.860050: SSL: Received packet(len=1396) - Flags 0xc0
>1200667341.860054: SSL: TLS Message Length: 14789
>1200667341.860077: SSL: Need 13403 bytes more input data
>1200667341.860082: SSL: Building ACK
>1200667341.860085: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667341.860091: EAP: EAP entering state SEND_RESPONSE
>1200667341.860096: EAP: EAP entering state IDLE
>1200667341.860099: EAPOL: SUPP_BE entering state RESPONSE
>1200667341.860102: EAPOL: txSuppRsp
>1200667341.860152: EAPOL: SUPP_BE entering state RECEIVE
>1200667341.898791: RX EAPOL from 00:0f:24:d6:96:f0
>1200667341.898810: EAPOL: Received EAP-Packet frame
>1200667341.898816: EAPOL: SUPP_BE entering state REQUEST
>1200667341.898819: EAPOL: getSuppRsp
>1200667341.898824: EAP: EAP entering state RECEIVED
>1200667341.898840: EAP: Received EAP-Request id=4 method=25 vendor=0 vendorMethod=0
>1200667341.898845: EAP: EAP entering state METHOD
>1200667341.898854: SSL: Received packet(len=1396) - Flags 0x40
>1200667341.898863: SSL: Need 12013 bytes more input data
>1200667341.898867: SSL: Building ACK
>1200667341.898870: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667341.898875: EAP: EAP entering state SEND_RESPONSE
>1200667341.898879: EAP: EAP entering state IDLE
>1200667341.898882: EAPOL: SUPP_BE entering state RESPONSE
>1200667341.898885: EAPOL: txSuppRsp
>1200667341.898927: EAPOL: SUPP_BE entering state RECEIVE
>1200667344.682548: EAPOL: startWhen --> 0
>1200667371.779535: EAPOL: authWhile --> 0
>1200667371.779555: EAPOL: SUPP_BE entering state TIMEOUT
>1200667371.779561: EAPOL: SUPP_PAE entering state CONNECTING
>1200667371.779564: EAPOL: SUPP_BE entering state IDLE
>1200667374.781541: EAPOL: startWhen --> 0
>1200667374.781561: EAPOL: SUPP_PAE entering state CONNECTING
>1200667374.781565: EAPOL: txStart
>1200667374.782916: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667374.782931: Wireless event: cmd=0x8b15 len=24
>1200667374.782936: Wireless event: new AP: 00:00:00:00:00:00
>1200667374.782967: Setting scan request: 0 sec 100000 usec
>1200667374.783069: Added BSSID 00:0f:24:d6:96:f0 into blacklist
>1200667374.783081: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
>1200667374.783087: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
>1200667374.797945: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
>1200667374.797972: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
>1200667374.797981: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
>1200667374.797989: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
>1200667374.798000: State: ASSOCIATED -> DISCONNECTED
>1200667374.798006: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667374.798011: WEXT: Operstate: linkmode=-1, operstate=5
>1200667374.798032: EAPOL: External notification - portEnabled=0
>1200667374.798037: EAPOL: SUPP_PAE entering state DISCONNECTED
>1200667374.798040: EAPOL: SUPP_BE entering state INITIALIZE
>1200667374.798046: EAP: EAP entering state DISABLED
>1200667374.798050: EAPOL: External notification - portValid=0
>1200667374.798060: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667374.798067: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667374.883537: State: DISCONNECTED -> SCANNING
>1200667374.883555: Starting AP scan (broadcast SSID)
>1200667375.786544: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667375.786557: Wireless event: cmd=0x8c02 len=229
>1200667375.786574: Association info event
>1200667375.786582: req_ies - hexdump(len=58): 00 07 65 64 75 72 6f 61 6d 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 01 dd 07 00 50 f2 02 00 01 00
>1200667375.786612: resp_ies - hexdump(len=35): 01 07 96 18 24 30 48 60 6c dd 18 00 50 f2 02 01 01 8b 00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62 32 2f 00
>1200667375.786632: WPA: set own WPA/RSN IE - 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 01
>1200667375.786654: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667375.786660: Wireless event: cmd=0x8b15 len=24
>1200667375.786664: Wireless event: new AP: 00:0f:24:d6:96:f0
>1200667375.786671: State: SCANNING -> ASSOCIATED
>1200667375.786676: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667375.786681: WEXT: Operstate: linkmode=-1, operstate=5
>1200667375.786792: Associated to a new BSS: BSSID=00:0f:24:d6:96:f0
>1200667375.786799: No keys have been configured - skip key clearing
>1200667375.786807: Associated with 00:0f:24:d6:96:f0
>1200667375.786815: WPA: Association event - clear replay counter
>1200667375.786819: EAPOL: External notification - portEnabled=0
>1200667375.786825: EAPOL: External notification - portValid=0
>1200667375.786829: EAPOL: External notification - portEnabled=1
>1200667375.786833: EAPOL: SUPP_PAE entering state CONNECTING
>1200667375.786836: EAPOL: SUPP_BE entering state IDLE
>1200667375.786839: EAP: EAP entering state INITIALIZE
>1200667375.786844: EAP: deinitialize previously used EAP method (25, PEAP) at INITIALIZE
>1200667375.786917: ENGINE: engine deinit
>1200667375.786925: EAP: EAP entering state IDLE
>1200667375.786934: Setting authentication timeout: 10 sec 0 usec
>1200667375.786940: Cancelling scan request
>1200667375.792660: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667375.792669: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667375.792677: RX EAPOL from 00:0f:24:d6:96:f0
>1200667375.792683: Setting authentication timeout: 70 sec 0 usec
>1200667375.792688: EAPOL: Received EAP-Packet frame
>1200667375.792691: EAPOL: SUPP_PAE entering state RESTART
>1200667375.792694: EAP: EAP entering state INITIALIZE
>1200667375.792696: EAP: EAP entering state IDLE
>1200667375.792698: EAPOL: SUPP_PAE entering state AUTHENTICATING
>1200667375.792700: EAPOL: SUPP_BE entering state REQUEST
>1200667375.792702: EAPOL: getSuppRsp
>1200667375.792704: EAP: EAP entering state RECEIVED
>1200667375.792709: EAP: Received EAP-Request id=1 method=1 vendor=0 vendorMethod=0
>1200667375.792712: EAP: EAP entering state IDENTITY
>1200667375.792753: CTRL-EVENT-EAP-STARTED EAP authentication started
>1200667375.792756: EAP: EAP-Request Identity data - hexdump_ascii(len=42):
>     00 6e 65 74 77 6f 72 6b 69 64 3d 65 64 75 72 6f   _networkid=eduro
>     61 6d 2c 6e 61 73 69 64 3d 64 6f 63 2d 61 70 30   am,nasid=doc-ap0
>     36 2c 70 6f 72 74 69 64 3d 30                     6,portid=0      
>1200667375.792768: EAP: using real identity - hexdump_ascii(len=15):
>     72 75 69 2e 6d 61 74 6f 73 40 75 61 2e 70 74      rui.matos@ua.pt 
>1200667375.792773: EAP: EAP entering state SEND_RESPONSE
>1200667375.792776: EAP: EAP entering state IDLE
>1200667375.792778: EAPOL: SUPP_BE entering state RESPONSE
>1200667375.792780: EAPOL: txSuppRsp
>1200667375.792803: EAPOL: SUPP_BE entering state RECEIVE
>1200667375.868315: RX EAPOL from 00:0f:24:d6:96:f0
>1200667375.868335: EAPOL: Received EAP-Packet frame
>1200667375.868342: EAPOL: SUPP_BE entering state REQUEST
>1200667375.868345: EAPOL: getSuppRsp
>1200667375.868350: EAP: EAP entering state RECEIVED
>1200667375.868358: EAP: Received EAP-Request id=2 method=25 vendor=0 vendorMethod=0
>1200667375.868363: EAP: EAP entering state GET_METHOD
>1200667375.868369: EAP: Initialize selected EAP method: vendor 0 method 25 (PEAP)
>1200667375.868383: EAP-PEAP: Phase2 EAP types - hexdump(len=8): 00 00 00 00 1a 00 00 00
>1200667375.868455: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
>1200667375.868461: EAP: EAP entering state METHOD
>1200667375.868466: SSL: Received packet(len=6) - Flags 0x20
>1200667375.868470: EAP-PEAP: Start (server ver=0, own ver=1)
>1200667375.868474: EAP-PEAP: Using PEAP version 0
>1200667375.868502: SSL: (where=0x10 ret=0x1)
>1200667375.868526: SSL: (where=0x1001 ret=0x1)
>1200667375.868531: SSL: SSL_connect:before/connect initialization
>1200667375.868568: SSL: (where=0x1001 ret=0x1)
>1200667375.868573: SSL: SSL_connect:SSLv3 write client hello A
>1200667375.868580: SSL: (where=0x1002 ret=0xffffffff)
>1200667375.868584: SSL: SSL_connect:error in SSLv3 read server hello A
>1200667375.868590: SSL: SSL_connect - want more data
>1200667375.868594: SSL: 97 bytes pending from ssl_out
>1200667375.868599: SSL: 97 bytes left to be sent out (of total 97 bytes)
>1200667375.868605: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667375.868611: EAP: EAP entering state SEND_RESPONSE
>1200667375.868615: EAP: EAP entering state IDLE
>1200667375.868620: EAPOL: SUPP_BE entering state RESPONSE
>1200667375.868623: EAPOL: txSuppRsp
>1200667375.868680: EAPOL: SUPP_BE entering state RECEIVE
>1200667375.885010: RX EAPOL from 00:0f:24:d6:96:f0
>1200667375.885034: EAPOL: Received EAP-Packet frame
>1200667375.885042: EAPOL: SUPP_BE entering state REQUEST
>1200667375.885045: EAPOL: getSuppRsp
>1200667375.885050: EAP: EAP entering state RECEIVED
>1200667375.885068: EAP: Received EAP-Request id=3 method=25 vendor=0 vendorMethod=0
>1200667375.885074: EAP: EAP entering state METHOD
>1200667375.885085: SSL: Received packet(len=1396) - Flags 0xc0
>1200667375.885089: SSL: TLS Message Length: 14789
>1200667375.885097: SSL: Need 13403 bytes more input data
>1200667375.885102: SSL: Building ACK
>1200667375.885105: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667375.885111: EAP: EAP entering state SEND_RESPONSE
>1200667375.885115: EAP: EAP entering state IDLE
>1200667375.885119: EAPOL: SUPP_BE entering state RESPONSE
>1200667375.885122: EAPOL: txSuppRsp
>1200667375.885172: EAPOL: SUPP_BE entering state RECEIVE
>1200667377.884546: Scan timeout - try to get results
>1200667377.884632: Received 0 bytes of scan results (0 BSSes)
>1200667377.884638: Scan results: 0
>1200667377.884645: Selecting BSS from priority group 0
>1200667377.884649: No APs found - clear blacklist and try again
>1200667377.884653: Removed BSSID 00:0f:24:d6:96:f0 from blacklist (clear)
>1200667377.884659: Selecting BSS from priority group 0
>1200667377.884662: No suitable AP found.
>1200667377.884671: Setting scan request: 5 sec 0 usec
>1200667378.783546: EAPOL: startWhen --> 0
>1200667382.885551: Starting AP scan (broadcast SSID)
>1200667385.886552: Scan timeout - try to get results
>1200667385.886643: Scan results: -1
>1200667385.886650: Failed to get scan results
>1200667385.886655: Failed to get scan results - try scanning again
>1200667385.886664: Setting scan request: 1 sec 0 usec
>1200667386.887637: Starting AP scan (broadcast SSID)
>1200667387.341104: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667387.341128: Wireless event: cmd=0x8b19 len=16
>1200667387.341197: Received 974 bytes of scan results (4 BSSes)
>1200667387.341203: Scan results: 4
>1200667387.341211: Selecting BSS from priority group 0
>1200667387.341215: 0: 00:0f:24:d6:a3:f0 ssid='eduroam' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
>1200667387.341227:    selected based on WPA IE
>1200667387.341239: Trying to associate with 00:0f:24:d6:a3:f0 (SSID='eduroam' freq=2437 MHz)
>1200667387.341244: Cancelling scan request
>1200667387.341248: WPA: clearing own WPA/RSN IE
>1200667387.341251: Automatic auth_alg selection: 0x1
>1200667387.341265: WPA: using IEEE 802.11i/D3.0
>1200667387.341269: WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 1 proto 1
>1200667387.341275: 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 01 28 00
>1200667387.341292: WPA: clearing AP RSN IE
>1200667387.341297: WPA: using GTK TKIP
>1200667387.341301: WPA: using PTK TKIP
>1200667387.341304: WPA: using KEY_MGMT 802.1X
>1200667387.341308: 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 01
>1200667387.341324: No keys have been configured - skip key clearing
>1200667387.341328: wpa_driver_wext_set_drop_unencrypted
>1200667387.341335: State: ASSOCIATED -> ASSOCIATING
>1200667387.341339: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667387.341344: WEXT: Operstate: linkmode=-1, operstate=5
>1200667387.341364: wpa_driver_wext_associate
>1200667387.362713: Setting authentication timeout: 15 sec 0 usec
>1200667387.362723: EAPOL: External notification - portControl=Auto
>1200667387.362736: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667387.362741: Wireless event: cmd=0x8b06 len=12
>1200667387.362745: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667387.362747: Wireless event: cmd=0x8b15 len=24
>1200667387.362749: Wireless event: new AP: 00:00:00:00:00:00
>1200667387.362754: Added BSSID 00:0f:24:d6:a3:f0 into blacklist
>1200667387.362759: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
>1200667387.362762: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
>1200667387.362770: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
>1200667387.362775: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
>1200667387.362779: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
>1200667387.362783: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
>1200667387.362814: Driver did not support SIOCSIWENCODEEXT
>1200667387.362817: State: ASSOCIATING -> DISCONNECTED
>1200667387.362821: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667387.362823: WEXT: Operstate: linkmode=-1, operstate=5
>1200667387.362834: EAPOL: External notification - portEnabled=0
>1200667387.362886: EAPOL: SUPP_PAE entering state DISCONNECTED
>1200667387.362888: EAPOL: SUPP_BE entering state INITIALIZE
>1200667387.362891: EAP: EAP entering state DISABLED
>1200667387.362893: EAPOL: External notification - portValid=0
>1200667387.362897: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667387.362900: Wireless event: cmd=0x8b04 len=16
>1200667387.362903: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667387.362906: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667387.362910: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1200667387.362913: Wireless event: cmd=0x8b1a len=23
>1200667387.368808: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667387.368815: Wireless event: cmd=0x8c02 len=229
>1200667387.368823: Association info event
>1200667387.368825: req_ies - hexdump(len=58): 00 07 65 64 75 72 6f 61 6d 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 01 dd 07 00 50 f2 02 00 01 00
>1200667387.368883: resp_ies - hexdump(len=35): 01 07 96 18 24 30 48 60 6c dd 18 00 50 f2 02 01 01 8b 00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62 32 2f 00
>1200667387.368893: WPA: set own WPA/RSN IE - 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 01
>1200667387.368903: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667387.368906: Wireless event: cmd=0x8b15 len=24
>1200667387.368908: Wireless event: new AP: 00:0f:24:d6:a3:f0
>1200667387.368912: State: DISCONNECTED -> ASSOCIATED
>1200667387.368915: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1200667387.368918: WEXT: Operstate: linkmode=-1, operstate=5
>1200667387.368933: Associated to a new BSS: BSSID=00:0f:24:d6:a3:f0
>1200667387.368936: No keys have been configured - skip key clearing
>1200667387.368939: Associated with 00:0f:24:d6:a3:f0
>1200667387.368941: WPA: Association event - clear replay counter
>1200667387.368944: EAPOL: External notification - portEnabled=0
>1200667387.368947: EAPOL: External notification - portValid=0
>1200667387.368949: EAPOL: External notification - portEnabled=1
>1200667387.368951: EAPOL: SUPP_PAE entering state CONNECTING
>1200667387.368953: EAPOL: SUPP_BE entering state IDLE
>1200667387.368955: EAP: EAP entering state INITIALIZE
>1200667387.368958: EAP: deinitialize previously used EAP method (25, PEAP) at INITIALIZE
>1200667387.368995: ENGINE: engine deinit
>1200667387.368998: EAP: EAP entering state IDLE
>1200667387.369002: Setting authentication timeout: 10 sec 0 usec
>1200667387.369006: Cancelling scan request
>1200667387.373961: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1200667387.373969: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667387.377020: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.377035: Setting authentication timeout: 70 sec 0 usec
>1200667387.377040: EAPOL: Received EAP-Packet frame
>1200667387.377043: EAPOL: SUPP_PAE entering state RESTART
>1200667387.377045: EAP: EAP entering state INITIALIZE
>1200667387.377048: EAP: EAP entering state IDLE
>1200667387.377051: EAPOL: SUPP_PAE entering state AUTHENTICATING
>1200667387.377053: EAPOL: SUPP_BE entering state REQUEST
>1200667387.377055: EAPOL: getSuppRsp
>1200667387.377060: EAP: EAP entering state RECEIVED
>1200667387.377065: EAP: Received EAP-Request id=1 method=1 vendor=0 vendorMethod=0
>1200667387.377068: EAP: EAP entering state IDENTITY
>1200667387.377072: CTRL-EVENT-EAP-STARTED EAP authentication started
>1200667387.377075: EAP: EAP-Request Identity data - hexdump_ascii(len=42):
>     00 6e 65 74 77 6f 72 6b 69 64 3d 65 64 75 72 6f   _networkid=eduro
>     61 6d 2c 6e 61 73 69 64 3d 64 6f 63 2d 61 70 30   am,nasid=doc-ap0
>     37 2c 70 6f 72 74 69 64 3d 30                     7,portid=0      
>1200667387.377087: EAP: using real identity - hexdump_ascii(len=15):
>     72 75 69 2e 6d 61 74 6f 73 40 75 61 2e 70 74      rui.matos@ua.pt 
>1200667387.377093: EAP: EAP entering state SEND_RESPONSE
>1200667387.377096: EAP: EAP entering state IDLE
>1200667387.377098: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.377100: EAPOL: txSuppRsp
>1200667387.377122: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.446760: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.446774: EAPOL: Received EAP-Packet frame
>1200667387.446778: EAPOL: SUPP_BE entering state REQUEST
>1200667387.446780: EAPOL: getSuppRsp
>1200667387.446783: EAP: EAP entering state RECEIVED
>1200667387.446789: EAP: Received EAP-Request id=2 method=25 vendor=0 vendorMethod=0
>1200667387.446794: EAP: EAP entering state GET_METHOD
>1200667387.446798: EAP: Initialize selected EAP method: vendor 0 method 25 (PEAP)
>1200667387.446807: EAP-PEAP: Phase2 EAP types - hexdump(len=8): 00 00 00 00 1a 00 00 00
>1200667387.446842: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
>1200667387.446846: EAP: EAP entering state METHOD
>1200667387.446849: SSL: Received packet(len=6) - Flags 0x20
>1200667387.446853: EAP-PEAP: Start (server ver=0, own ver=1)
>1200667387.446898: EAP-PEAP: Using PEAP version 0
>1200667387.446920: SSL: (where=0x10 ret=0x1)
>1200667387.446933: SSL: (where=0x1001 ret=0x1)
>1200667387.446936: SSL: SSL_connect:before/connect initialization
>1200667387.446955: SSL: (where=0x1001 ret=0x1)
>1200667387.446957: SSL: SSL_connect:SSLv3 write client hello A
>1200667387.446961: SSL: (where=0x1002 ret=0xffffffff)
>1200667387.446963: SSL: SSL_connect:error in SSLv3 read server hello A
>1200667387.446966: SSL: SSL_connect - want more data
>1200667387.446968: SSL: 97 bytes pending from ssl_out
>1200667387.446970: SSL: 97 bytes left to be sent out (of total 97 bytes)
>1200667387.446973: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.446976: EAP: EAP entering state SEND_RESPONSE
>1200667387.446978: EAP: EAP entering state IDLE
>1200667387.446980: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.446981: EAPOL: txSuppRsp
>1200667387.447003: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.454264: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.454270: EAPOL: Received EAP-Packet frame
>1200667387.454274: EAPOL: SUPP_BE entering state REQUEST
>1200667387.454276: EAPOL: getSuppRsp
>1200667387.454278: EAP: EAP entering state RECEIVED
>1200667387.454288: EAP: Received EAP-Request id=3 method=25 vendor=0 vendorMethod=0
>1200667387.454292: EAP: EAP entering state METHOD
>1200667387.454295: SSL: Received packet(len=1396) - Flags 0xc0
>1200667387.454298: SSL: TLS Message Length: 14789
>1200667387.454302: SSL: Need 13403 bytes more input data
>1200667387.454305: SSL: Building ACK
>1200667387.454307: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.454310: EAP: EAP entering state SEND_RESPONSE
>1200667387.454313: EAP: EAP entering state IDLE
>1200667387.454316: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.454318: EAPOL: txSuppRsp
>1200667387.454333: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.461180: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.461186: EAPOL: Received EAP-Packet frame
>1200667387.461189: EAPOL: SUPP_BE entering state REQUEST
>1200667387.461191: EAPOL: getSuppRsp
>1200667387.461193: EAP: EAP entering state RECEIVED
>1200667387.461202: EAP: Received EAP-Request id=4 method=25 vendor=0 vendorMethod=0
>1200667387.461206: EAP: EAP entering state METHOD
>1200667387.461209: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.461215: SSL: Need 12013 bytes more input data
>1200667387.461218: SSL: Building ACK
>1200667387.461220: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.461223: EAP: EAP entering state SEND_RESPONSE
>1200667387.461226: EAP: EAP entering state IDLE
>1200667387.461228: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.461230: EAPOL: txSuppRsp
>1200667387.461245: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.467460: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.467467: EAPOL: Received EAP-Packet frame
>1200667387.467470: EAPOL: SUPP_BE entering state REQUEST
>1200667387.467472: EAPOL: getSuppRsp
>1200667387.467474: EAP: EAP entering state RECEIVED
>1200667387.467481: EAP: Received EAP-Request id=5 method=25 vendor=0 vendorMethod=0
>1200667387.467483: EAP: EAP entering state METHOD
>1200667387.467487: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.467491: SSL: Need 10623 bytes more input data
>1200667387.467493: SSL: Building ACK
>1200667387.467495: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.467498: EAP: EAP entering state SEND_RESPONSE
>1200667387.467500: EAP: EAP entering state IDLE
>1200667387.467502: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.467503: EAPOL: txSuppRsp
>1200667387.467516: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.474450: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.474456: EAPOL: Received EAP-Packet frame
>1200667387.474465: EAPOL: SUPP_BE entering state REQUEST
>1200667387.474467: EAPOL: getSuppRsp
>1200667387.474469: EAP: EAP entering state RECEIVED
>1200667387.474477: EAP: Received EAP-Request id=6 method=25 vendor=0 vendorMethod=0
>1200667387.474479: EAP: EAP entering state METHOD
>1200667387.474483: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.474509: SSL: Need 9233 bytes more input data
>1200667387.474512: SSL: Building ACK
>1200667387.474514: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.474517: EAP: EAP entering state SEND_RESPONSE
>1200667387.474519: EAP: EAP entering state IDLE
>1200667387.474521: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.474523: EAPOL: txSuppRsp
>1200667387.474535: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.480983: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.480990: EAPOL: Received EAP-Packet frame
>1200667387.480993: EAPOL: SUPP_BE entering state REQUEST
>1200667387.480996: EAPOL: getSuppRsp
>1200667387.480998: EAP: EAP entering state RECEIVED
>1200667387.481013: EAP: Received EAP-Request id=7 method=25 vendor=0 vendorMethod=0
>1200667387.481016: EAP: EAP entering state METHOD
>1200667387.481019: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.481023: SSL: Need 7843 bytes more input data
>1200667387.481025: SSL: Building ACK
>1200667387.481027: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.481030: EAP: EAP entering state SEND_RESPONSE
>1200667387.481033: EAP: EAP entering state IDLE
>1200667387.481035: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.481037: EAPOL: txSuppRsp
>1200667387.481049: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.487851: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.487858: EAPOL: Received EAP-Packet frame
>1200667387.487861: EAPOL: SUPP_BE entering state REQUEST
>1200667387.487869: EAPOL: getSuppRsp
>1200667387.487871: EAP: EAP entering state RECEIVED
>1200667387.487878: EAP: Received EAP-Request id=8 method=25 vendor=0 vendorMethod=0
>1200667387.487881: EAP: EAP entering state METHOD
>1200667387.487884: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.487888: SSL: Need 6453 bytes more input data
>1200667387.487890: SSL: Building ACK
>1200667387.487892: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.487894: EAP: EAP entering state SEND_RESPONSE
>1200667387.487897: EAP: EAP entering state IDLE
>1200667387.487899: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.487901: EAPOL: txSuppRsp
>1200667387.487912: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.497698: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.497715: EAPOL: Received EAP-Packet frame
>1200667387.497721: EAPOL: SUPP_BE entering state REQUEST
>1200667387.497725: EAPOL: getSuppRsp
>1200667387.497729: EAP: EAP entering state RECEIVED
>1200667387.497744: EAP: Received EAP-Request id=9 method=25 vendor=0 vendorMethod=0
>1200667387.497750: EAP: EAP entering state METHOD
>1200667387.497758: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.497766: SSL: Need 5063 bytes more input data
>1200667387.497770: SSL: Building ACK
>1200667387.497774: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.497779: EAP: EAP entering state SEND_RESPONSE
>1200667387.497783: EAP: EAP entering state IDLE
>1200667387.497787: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.497790: EAPOL: txSuppRsp
>1200667387.497826: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.506071: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.506081: EAPOL: Received EAP-Packet frame
>1200667387.506085: EAPOL: SUPP_BE entering state REQUEST
>1200667387.506088: EAPOL: getSuppRsp
>1200667387.506092: EAP: EAP entering state RECEIVED
>1200667387.506106: EAP: Received EAP-Request id=10 method=25 vendor=0 vendorMethod=0
>1200667387.506111: EAP: EAP entering state METHOD
>1200667387.506116: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.506121: SSL: Need 3673 bytes more input data
>1200667387.506125: SSL: Building ACK
>1200667387.506128: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.506133: EAP: EAP entering state SEND_RESPONSE
>1200667387.506136: EAP: EAP entering state IDLE
>1200667387.506139: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.506142: EAPOL: txSuppRsp
>1200667387.506167: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.512356: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.512361: EAPOL: Received EAP-Packet frame
>1200667387.512409: EAPOL: SUPP_BE entering state REQUEST
>1200667387.512411: EAPOL: getSuppRsp
>1200667387.512413: EAP: EAP entering state RECEIVED
>1200667387.512420: EAP: Received EAP-Request id=11 method=25 vendor=0 vendorMethod=0
>1200667387.512423: EAP: EAP entering state METHOD
>1200667387.512427: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.512431: SSL: Need 2283 bytes more input data
>1200667387.512433: SSL: Building ACK
>1200667387.512435: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.512439: EAP: EAP entering state SEND_RESPONSE
>1200667387.512441: EAP: EAP entering state IDLE
>1200667387.512444: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.512446: EAPOL: txSuppRsp
>1200667387.512458: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.519201: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.519206: EAPOL: Received EAP-Packet frame
>1200667387.519208: EAPOL: SUPP_BE entering state REQUEST
>1200667387.519211: EAPOL: getSuppRsp
>1200667387.519214: EAP: EAP entering state RECEIVED
>1200667387.519222: EAP: Received EAP-Request id=12 method=25 vendor=0 vendorMethod=0
>1200667387.519224: EAP: EAP entering state METHOD
>1200667387.519228: SSL: Received packet(len=1396) - Flags 0x40
>1200667387.519231: SSL: Need 893 bytes more input data
>1200667387.519233: SSL: Building ACK
>1200667387.519236: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.519238: EAP: EAP entering state SEND_RESPONSE
>1200667387.519240: EAP: EAP entering state IDLE
>1200667387.519243: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.519245: EAPOL: txSuppRsp
>1200667387.519256: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.524724: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.524730: EAPOL: Received EAP-Packet frame
>1200667387.524733: EAPOL: SUPP_BE entering state REQUEST
>1200667387.524735: EAPOL: getSuppRsp
>1200667387.524737: EAP: EAP entering state RECEIVED
>1200667387.524742: EAP: Received EAP-Request id=13 method=25 vendor=0 vendorMethod=0
>1200667387.524745: EAP: EAP entering state METHOD
>1200667387.524748: SSL: Received packet(len=899) - Flags 0x00
>1200667387.524839: SSL: (where=0x1001 ret=0x1)
>1200667387.524843: SSL: SSL_connect:SSLv3 read server hello A
>1200667387.525159: SSL: (where=0x1001 ret=0x1)
>1200667387.525163: SSL: SSL_connect:SSLv3 read server certificate A
>1200667387.525807: SSL: (where=0x1001 ret=0x1)
>1200667387.525810: SSL: SSL_connect:SSLv3 read server certificate request A
>1200667387.525813: SSL: (where=0x1001 ret=0x1)
>1200667387.525816: SSL: SSL_connect:SSLv3 read server done A
>1200667387.525822: SSL: (where=0x1001 ret=0x1)
>1200667387.525825: SSL: SSL_connect:SSLv3 write client certificate A
>1200667387.525978: SSL: (where=0x1001 ret=0x1)
>1200667387.525981: SSL: SSL_connect:SSLv3 write client key exchange A
>1200667387.526021: SSL: (where=0x1001 ret=0x1)
>1200667387.526023: SSL: SSL_connect:SSLv3 write change cipher spec A
>1200667387.526050: SSL: (where=0x1001 ret=0x1)
>1200667387.526053: SSL: SSL_connect:SSLv3 write finished A
>1200667387.526058: SSL: (where=0x1001 ret=0x1)
>1200667387.526059: SSL: SSL_connect:SSLv3 flush data
>1200667387.526062: SSL: (where=0x1002 ret=0xffffffff)
>1200667387.526064: SSL: SSL_connect:error in SSLv3 read finished A
>1200667387.526068: SSL: SSL_connect - want more data
>1200667387.526071: SSL: 194 bytes pending from ssl_out
>1200667387.526073: SSL: 194 bytes left to be sent out (of total 194 bytes)
>1200667387.526076: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.526079: EAP: EAP entering state SEND_RESPONSE
>1200667387.526081: EAP: EAP entering state IDLE
>1200667387.526083: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.526086: EAPOL: txSuppRsp
>1200667387.526103: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.535794: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.535801: EAPOL: Received EAP-Packet frame
>1200667387.535804: EAPOL: SUPP_BE entering state REQUEST
>1200667387.535806: EAPOL: getSuppRsp
>1200667387.535808: EAP: EAP entering state RECEIVED
>1200667387.535812: EAP: Received EAP-Request id=14 method=25 vendor=0 vendorMethod=0
>1200667387.535853: EAP: EAP entering state METHOD
>1200667387.535857: SSL: Received packet(len=53) - Flags 0x80
>1200667387.535859: SSL: TLS Message Length: 43
>1200667387.535924: SSL: (where=0x1001 ret=0x1)
>1200667387.535932: SSL: SSL_connect:SSLv3 read finished A
>1200667387.535941: SSL: (where=0x20 ret=0x1)
>1200667387.535943: SSL: (where=0x1002 ret=0x1)
>1200667387.535946: SSL: 0 bytes pending from ssl_out
>1200667387.535961: OpenSSL: tls_connection_handshake - Failed to read possible Application Data error:00000000:lib(0):func(0):reason(0)
>1200667387.535965: SSL: No data to be sent out
>1200667387.535967: EAP-PEAP: TLS done, proceed to Phase 2
>1200667387.535969: EAP-PEAP: using label 'client EAP encryption' in key derivation
>1200667387.536002: EAP-PEAP: Derived key - hexdump(len=64): [REMOVED]
>1200667387.536004: SSL: Building ACK
>1200667387.536006: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.536009: EAP: EAP entering state SEND_RESPONSE
>1200667387.536011: EAP: EAP entering state IDLE
>1200667387.536013: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.536014: EAPOL: txSuppRsp
>1200667387.536025: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.540278: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.540284: EAPOL: Received EAP-Packet frame
>1200667387.540287: EAPOL: SUPP_BE entering state REQUEST
>1200667387.540290: EAPOL: getSuppRsp
>1200667387.540292: EAP: EAP entering state RECEIVED
>1200667387.540296: EAP: Received EAP-Request id=15 method=25 vendor=0 vendorMethod=0
>1200667387.540299: EAP: EAP entering state METHOD
>1200667387.540304: SSL: Received packet(len=28) - Flags 0x00
>1200667387.540307: EAP-PEAP: received 22 bytes encrypted data for Phase 2
>1200667387.540323: EAP-PEAP: Decrypted Phase 2 EAP - hexdump(len=1): 01
>1200667387.540327: EAP-PEAP: received Phase 2: code=1 identifier=15 length=5
>1200667387.540331: EAP-PEAP: Phase 2 Request: type=1
>1200667387.540334: EAP: using real identity - hexdump_ascii(len=15):
>     72 75 69 2e 6d 61 74 6f 73 40 75 61 2e 70 74      rui.matos@ua.pt 
>1200667387.540343: EAP-PEAP: Encrypting Phase 2 data - hexdump(len=20): [REMOVED]
>1200667387.540361: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.540365: EAP: EAP entering state SEND_RESPONSE
>1200667387.540368: EAP: EAP entering state IDLE
>1200667387.540370: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.540373: EAPOL: txSuppRsp
>1200667387.540402: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.546414: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.546419: EAPOL: Received EAP-Packet frame
>1200667387.546423: EAPOL: SUPP_BE entering state REQUEST
>1200667387.546425: EAPOL: getSuppRsp
>1200667387.546427: EAP: EAP entering state RECEIVED
>1200667387.546432: EAP: Received EAP-Request id=16 method=25 vendor=0 vendorMethod=0
>1200667387.546435: EAP: EAP entering state METHOD
>1200667387.546439: SSL: Received packet(len=57) - Flags 0x00
>1200667387.546441: EAP-PEAP: received 51 bytes encrypted data for Phase 2
>1200667387.546456: EAP-PEAP: Decrypted Phase 2 EAP - hexdump(len=30): 1a 01 10 00 1d 10 a3 cd 1f 70 05 a6 df 98 51 fd 90 6c 7e a1 b8 bb 43 45 4c 45 42 4f 52 4e
>1200667387.546467: EAP-PEAP: received Phase 2: code=1 identifier=16 length=34
>1200667387.546470: EAP-PEAP: Phase 2 Request: type=26
>1200667387.546473: EAP-PEAP: Selected Phase 2 EAP vendor 0 method 26
>1200667387.546478: EAP-MSCHAPV2: RX identifier 16 mschapv2_id 16
>1200667387.546482: EAP-MSCHAPV2: Received challenge
>1200667387.546484: EAP-MSCHAPV2: Authentication Servername - hexdump_ascii(len=8):
>     43 45 4c 45 42 4f 52 4e                           CELEBORN        
>1200667387.546491: EAP-MSCHAPV2: Generating Challenge Response
>1200667387.547281: EAP-MSCHAPV2: auth_challenge - hexdump(len=16): a3 cd 1f 70 05 a6 df 98 51 fd 90 6c 7e a1 b8 bb
>1200667387.547287: EAP-MSCHAPV2: peer_challenge - hexdump(len=16): 51 02 26 54 14 98 42 92 db 02 c7 0e 33 f7 c2 e6
>1200667387.547291: EAP-MSCHAPV2: username - hexdump_ascii(len=15):
>     72 75 69 2e 6d 61 74 6f 73 40 75 61 2e 70 74      rui.matos@ua.pt 
>1200667387.547311: EAP-MSCHAPV2: password - hexdump_ascii(len=17): [REMOVED]
>1200667387.547329: EAP-MSCHAPV2: response - hexdump(len=24): 0f 1a f7 66 c9 f8 a3 9e ad 8b 5d 4f dc 67 d7 6b 42 db b5 6c 2d fd 3e ef
>1200667387.547339: EAP-MSCHAPV2: TX identifier 16 mschapv2_id 16 (response)
>1200667387.547341: EAP-PEAP: Encrypting Phase 2 data - hexdump(len=74): [REMOVED]
>1200667387.547353: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.547355: EAP: EAP entering state SEND_RESPONSE
>1200667387.547358: EAP: EAP entering state IDLE
>1200667387.547360: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.547361: EAPOL: txSuppRsp
>1200667387.547371: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.569098: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.569123: EAPOL: Received EAP-Packet frame
>1200667387.569131: EAPOL: SUPP_BE entering state REQUEST
>1200667387.569134: EAPOL: getSuppRsp
>1200667387.569139: EAP: EAP entering state RECEIVED
>1200667387.569147: EAP: Received EAP-Request id=17 method=25 vendor=0 vendorMethod=0
>1200667387.569153: EAP: EAP entering state METHOD
>1200667387.569164: SSL: Received packet(len=74) - Flags 0x00
>1200667387.569170: EAP-PEAP: received 68 bytes encrypted data for Phase 2
>1200667387.569209: EAP-PEAP: Decrypted Phase 2 EAP - hexdump(len=47): 1a 03 10 00 2e 53 3d 30 42 41 35 37 42 46 42 32 44 34 33 46 32 36 44 37 33 33 43 43 42 42 34 44 33 37 37 31 41 32 38 44 34 43 33 31 32 37 31
>1200667387.569233: EAP-PEAP: received Phase 2: code=1 identifier=17 length=51
>1200667387.569238: EAP-PEAP: Phase 2 Request: type=26
>1200667387.569243: EAP-MSCHAPV2: RX identifier 17 mschapv2_id 16
>1200667387.569247: EAP-MSCHAPV2: Received success
>1200667387.569252: EAP-MSCHAPV2: Success message - hexdump_ascii(len=0):
>1200667387.569256: EAP-MSCHAPV2: Authentication succeeded
>1200667387.569259: EAP-PEAP: Encrypting Phase 2 data - hexdump(len=6): [REMOVED]
>1200667387.569291: EAP: method process -> ignore=FALSE methodState=MAY_CONT decision=FAIL
>1200667387.569297: EAP: EAP entering state SEND_RESPONSE
>1200667387.569300: EAP: EAP entering state IDLE
>1200667387.569304: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.569307: EAPOL: txSuppRsp
>1200667387.569356: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.576439: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.576457: EAPOL: Received EAP-Packet frame
>1200667387.576462: EAPOL: SUPP_BE entering state REQUEST
>1200667387.576465: EAPOL: getSuppRsp
>1200667387.576469: EAP: EAP entering state RECEIVED
>1200667387.576476: EAP: Received EAP-Request id=18 method=25 vendor=0 vendorMethod=0
>1200667387.576481: EAP: EAP entering state METHOD
>1200667387.576491: SSL: Received packet(len=38) - Flags 0x00
>1200667387.576496: EAP-PEAP: received 32 bytes encrypted data for Phase 2
>1200667387.576532: EAP-PEAP: Decrypted Phase 2 EAP - hexdump(len=11): 01 12 00 0b 21 80 03 00 02 00 01
>1200667387.576543: EAP-PEAP: received Phase 2: code=1 identifier=18 length=11
>1200667387.576547: EAP-PEAP: Phase 2 Request: type=33
>1200667387.576551: EAP-TLV: Received TLVs - hexdump(len=6): 80 03 00 02 00 01
>1200667387.576558: EAP-TLV: Result TLV - hexdump(len=2): 00 01
>1200667387.576562: EAP-TLV: TLV Result - Success - EAP-TLV/Phase2 Completed
>1200667387.576566: EAP-PEAP: Encrypting Phase 2 data - hexdump(len=11): [REMOVED]
>1200667387.576595: EAP: method process -> ignore=FALSE methodState=DONE decision=UNCOND_SUCC
>1200667387.576601: EAP: EAP entering state SEND_RESPONSE
>1200667387.576604: EAP: EAP entering state IDLE
>1200667387.576608: EAPOL: SUPP_BE entering state RESPONSE
>1200667387.576611: EAPOL: txSuppRsp
>1200667387.576652: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.582745: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.582841: EAPOL: Received EAP-Packet frame
>1200667387.582848: EAPOL: SUPP_BE entering state REQUEST
>1200667387.582851: EAPOL: getSuppRsp
>1200667387.582856: EAP: EAP entering state RECEIVED
>1200667387.582863: EAP: Received EAP-Success
>1200667387.582867: EAP: Workaround for unexpected identifier field in EAP Success: reqId=19 lastId=18 (these are supposed to be same)
>1200667387.582872: EAP: EAP entering state SUCCESS
>1200667387.582956: CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully
>1200667387.582963: EAPOL: SUPP_BE entering state RECEIVE
>1200667387.582967: EAPOL: SUPP_BE entering state SUCCESS
>1200667387.582970: EAPOL: SUPP_BE entering state IDLE
>1200667387.582993: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.582999: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667387.583016: IEEE 802.1X RX: version=1 type=3 length=95
>1200667387.583020:   EAPOL-Key type=254
>1200667387.583024:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
>1200667387.583031:   key_length=32 key_data_length=0
>1200667387.583035:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
>1200667387.583043:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 42
>1200667387.583060:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1200667387.583071:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667387.583078:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667387.583085:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1200667387.583118: State: ASSOCIATED -> 4WAY_HANDSHAKE
>1200667387.583123: WPA: RX message 1 of 4-Way Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667387.583131: WPA: PMK from EAPOL state machines - hexdump(len=32): [REMOVED]
>1200667387.585255: WPA: Renewed SNonce - hexdump(len=32): fc 45 90 0f 9a f5 e3 53 8c f5 30 59 03 ea 84 59 3b 16 01 58 5e 0b 07 51 3a 34 6b aa e3 0e 6b 7a
>1200667387.585300: WPA: PMK - hexdump(len=32): [REMOVED]
>1200667387.585304: WPA: PTK - hexdump(len=64): [REMOVED]
>1200667387.585307: 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 01
>1200667387.585324: WPA: Sending EAPOL-Key 2/4
>1200667387.588175: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.588190: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667387.588197: IEEE 802.1X RX: version=1 type=3 length=121
>1200667387.588202:   EAPOL-Key type=254
>1200667387.588206:   key_info 0x1c9 (ver=1 keyidx=0 rsvd=0 Pairwise Install Ack MIC)
>1200667387.588213:   key_length=32 key_data_length=26
>1200667387.588217:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
>1200667387.588225:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 42
>1200667387.588240:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1200667387.588250:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667387.588257:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667387.588264:   key_mic - hexdump(len=16): 9a d0 1b e1 92 89 78 ad 57 91 7e 86 ba 9c a1 09
>1200667387.588285: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
>1200667387.588290: WPA: RX message 3 of 4-Way Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667387.588296: 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 01 28 00
>1200667387.588315: WPA: Sending EAPOL-Key 4/4
>1200667387.588360: WPA: Installing PTK to the driver.
>1200667387.588364: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1200667387.588371: wpa_driver_wext_set_key: alg=2 key_idx=0 set_tx=1 seq_len=6 key_len=32
>1200667387.588422: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
>1200667387.591544: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667387.591554: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667387.591560: IEEE 802.1X RX: version=1 type=3 length=127
>1200667387.591564:   EAPOL-Key type=254
>1200667387.591568:   key_info 0x3a1 (ver=1 keyidx=2 rsvd=0 Group Ack MIC Secure)
>1200667387.591575:   key_length=32 key_data_length=32
>1200667387.591579:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 03
>1200667387.591587:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 41
>1200667387.591603:   key_iv - hexdump(len=16): 57 cc 03 fe 9c b8 67 71 62 4a 1f 4e 25 78 2c 22
>1200667387.591694:   key_rsc - hexdump(len=8): cd 05 00 00 00 00 00 00
>1200667387.591702:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667387.591708:   key_mic - hexdump(len=16): a3 66 cb 96 ff 25 06 c3 7d a6 59 34 0c 6b 0c 04
>1200667387.591727: WPA: RX message 1 of Group Key Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667387.591742: State: GROUP_HANDSHAKE -> GROUP_HANDSHAKE
>1200667387.591745: WPA: Group Key - hexdump(len=32): [REMOVED]
>1200667387.591749: WPA: Installing GTK to the driver (keyidx=2 tx=0).
>1200667387.591753: WPA: RSC - hexdump(len=6): cd 05 00 00 00 00
>1200667387.591760: wpa_driver_wext_set_key: alg=2 key_idx=2 set_tx=0 seq_len=6 key_len=32
>1200667387.591784: WPA: Sending EAPOL-Key 2/2
>1200667387.591844: WPA: Key negotiation completed with 00:0f:24:d6:a3:f0 [PTK=TKIP GTK=TKIP]
>1200667387.591850: Cancelling authentication timeout
>1200667387.591853: Removed BSSID 00:0f:24:d6:a3:f0 from blacklist
>1200667387.591859: State: GROUP_HANDSHAKE -> COMPLETED
>1200667387.591868: CTRL-EVENT-CONNECTED - Connection to 00:0f:24:d6:a3:f0 completed (auth) [id=0 id_str=]
>1200667387.591872: wpa_driver_wext_set_operstate: operstate 0->1 (UP)
>1200667387.591877: WEXT: Operstate: linkmode=-1, operstate=6
>1200667387.604270: EAPOL: External notification - portValid=1
>1200667387.604279: EAPOL: SUPP_PAE entering state AUTHENTICATED
>1200667387.604308: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
>1200667387.604316: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1200667389.790419: EAPOL: startWhen --> 0
>1200667416.804669: EAPOL: authWhile --> 0
>1200667446.822626: EAPOL: idleWhile --> 0
>1200667477.972626: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667477.972637: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667477.972641: IEEE 802.1X RX: version=1 type=3 length=127
>1200667477.972644:   EAPOL-Key type=254
>1200667477.972646:   key_info 0x391 (ver=1 keyidx=1 rsvd=0 Group Ack MIC Secure)
>1200667477.972650:   key_length=32 key_data_length=32
>1200667477.972652:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 04
>1200667477.972656:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 44
>1200667477.972662:   key_iv - hexdump(len=16): 14 0c d5 ed f4 b6 1d 71 db 75 63 2e 03 32 92 60
>1200667477.972666:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667477.972670:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667477.972673:   key_mic - hexdump(len=16): 0a 03 58 e8 7d 8f 58 6c 9c cf 8c b5 64 4f 89 53
>1200667477.972683: WPA: RX message 1 of Group Key Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667477.972690: State: COMPLETED -> GROUP_HANDSHAKE
>1200667477.972693: WPA: Group Key - hexdump(len=32): [REMOVED]
>1200667477.972695: WPA: Installing GTK to the driver (keyidx=1 tx=0).
>1200667477.972697: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1200667477.972700: wpa_driver_wext_set_key: alg=2 key_idx=1 set_tx=0 seq_len=6 key_len=32
>1200667477.972716: WPA: Sending EAPOL-Key 2/2
>1200667477.972752: WPA: Group rekeying completed with 00:0f:24:d6:a3:f0 [GTK=TKIP]
>1200667477.972755: State: GROUP_HANDSHAKE -> COMPLETED
>1200667637.429713: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667637.429731: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667637.429740: IEEE 802.1X RX: version=1 type=3 length=127
>1200667637.429745:   EAPOL-Key type=254
>1200667637.429749:   key_info 0x3a1 (ver=1 keyidx=2 rsvd=0 Group Ack MIC Secure)
>1200667637.429757:   key_length=32 key_data_length=32
>1200667637.429761:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 05
>1200667637.429769:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 46
>1200667637.429786:   key_iv - hexdump(len=16): 0c 3b 0e de 43 2f bb 98 82 cd ac d9 5e 06 33 4d
>1200667637.429795:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667637.429803:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667637.429810:   key_mic - hexdump(len=16): 84 d7 0e aa bb b8 79 aa 73 4e a3 bb d0 66 24 22
>1200667637.429920: WPA: RX message 1 of Group Key Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667637.429935: State: COMPLETED -> GROUP_HANDSHAKE
>1200667637.429940: WPA: Group Key - hexdump(len=32): [REMOVED]
>1200667637.429944: WPA: Installing GTK to the driver (keyidx=2 tx=0).
>1200667637.429948: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1200667637.429955: wpa_driver_wext_set_key: alg=2 key_idx=2 set_tx=0 seq_len=6 key_len=32
>1200667637.432543: WPA: Sending EAPOL-Key 2/2
>1200667637.432654: WPA: Group rekeying completed with 00:0f:24:d6:a3:f0 [GTK=TKIP]
>1200667637.432660: State: GROUP_HANDSHAKE -> COMPLETED
>1200667717.522800: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667717.522818: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667717.522826: IEEE 802.1X RX: version=1 type=3 length=127
>1200667717.522831:   EAPOL-Key type=254
>1200667717.522835:   key_info 0x391 (ver=1 keyidx=1 rsvd=0 Group Ack MIC Secure)
>1200667717.522843:   key_length=32 key_data_length=32
>1200667717.522847:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 06
>1200667717.522856:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 48
>1200667717.522872:   key_iv - hexdump(len=16): d5 1d a7 0b bc 56 88 c4 13 2e 90 e2 7c 15 6f 03
>1200667717.522882:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667717.522889:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667717.522896:   key_mic - hexdump(len=16): 0e 44 3e 12 38 9a 5a 02 8f f8 94 f5 3a 8f a0 c9
>1200667717.522917: WPA: RX message 1 of Group Key Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667717.522932: State: COMPLETED -> GROUP_HANDSHAKE
>1200667717.522937: WPA: Group Key - hexdump(len=32): [REMOVED]
>1200667717.522941: WPA: Installing GTK to the driver (keyidx=1 tx=0).
>1200667717.522945: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1200667717.522952: wpa_driver_wext_set_key: alg=2 key_idx=1 set_tx=0 seq_len=6 key_len=32
>1200667717.525558: WPA: Sending EAPOL-Key 2/2
>1200667717.525671: WPA: Group rekeying completed with 00:0f:24:d6:a3:f0 [GTK=TKIP]
>1200667717.525677: State: GROUP_HANDSHAKE -> COMPLETED
>1200667751.675419: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667751.675439: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667751.675448: IEEE 802.1X RX: version=1 type=3 length=127
>1200667751.675453:   EAPOL-Key type=254
>1200667751.675457:   key_info 0x3a1 (ver=1 keyidx=2 rsvd=0 Group Ack MIC Secure)
>1200667751.675466:   key_length=32 key_data_length=32
>1200667751.675469:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 07
>1200667751.675478:   key_nonce - hexdump(len=32): c6 49 3e 9b ce e0 ae bb 2c 1f f5 34 e8 3b e1 9b c4 63 01 84 4a 51 ca 92 a6 46 74 7d f8 49 f7 49
>1200667751.675494:   key_iv - hexdump(len=16): b5 e5 9d ba ff 3a e2 d0 86 a2 ef 43 1b cd b8 48
>1200667751.675503:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667751.675511:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1200667751.675517:   key_mic - hexdump(len=16): f2 0d ab 27 e3 07 ca f6 8c f8 3d 19 fa fc 1d 06
>1200667751.675539: WPA: RX message 1 of Group Key Handshake from 00:0f:24:d6:a3:f0 (ver=1)
>1200667751.675554: State: COMPLETED -> GROUP_HANDSHAKE
>1200667751.675559: WPA: Group Key - hexdump(len=32): [REMOVED]
>1200667751.675563: WPA: Installing GTK to the driver (keyidx=2 tx=0).
>1200667751.675567: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1200667751.675575: wpa_driver_wext_set_key: alg=2 key_idx=2 set_tx=0 seq_len=6 key_len=32
>1200667751.678555: WPA: Sending EAPOL-Key 2/2
>1200667751.678667: WPA: Group rekeying completed with 00:0f:24:d6:a3:f0 [GTK=TKIP]
>1200667751.678673: State: GROUP_HANDSHAKE -> COMPLETED
>1200667776.558734: RX EAPOL from 00:0f:24:d6:a3:f0
>1200667776.558753: EAPOL: Ignoring WPA EAPOL-Key frame in EAPOL state machines
>1200667776.558761: IEEE 802.1X RX: version=1 type=3 length=127
>1200667776.558766:   EAPOL-Key type=254
>1200667776.558770:   key_info 0x391 (ver=1 keyidx=1 rsvd=0 Group Ack MIC Secure)
>1200667

Actions: View

Attachments on bug 428591: 292150 | 292153 | 292267 | 296082 | 296083