Attachment 222686 Details for Bug 383083

WPA Log

wpa_log.txt (text/plain), 16.45 KB, created by Quentin Jackson on 2008-06-18 08:48:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Quentin Jackson

Created: 2008-06-18 08:48:12 UTC

Size: 16.45 KB

patch

obsolete

># wpa_supplicant -Dwext -iwlan0 -c/home/quentinj/wpafile.c
>onfig -dddt
>1213777682.907696: Initializing interface 'wlan0' conf '/home/quentinj/wpafile.c
>onfig' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
>1213777682.907800: Configuration file '/home/quentinj/wpafile.config' -> '/home/
>quentinj/wpafile.config'
>1213777682.907815: Reading configuration file '/home/quentinj/wpafile.config'
>1213777682.907914: ctrl_interface='/var/run/wpa_supplicant'
>1213777682.907928: ctrl_interface_group='0' (DEPRECATED)
>1213777682.907940: eapol_version=1
>1213777682.907948: ap_scan=1
>1213777682.907956: fast_reauth=1
>1213777682.907962: Line: 7 - start of a new network block
>1213777682.907983: ssid - hexdump_ascii(len=8):
>     47 65 74 61 6c 69 66 65                           Getalife
>1213777682.908013: PSK (ASCII passphrase) - hexdump_ascii(len=9): [REMOVED]
>1213777682.908029: key_mgmt: 0x2
>1213777682.908045: priority=5 (0x5)
>1213777682.908055: proto: 0x1
>1213777682.935625: PSK (from passphrase) - hexdump(len=32): [REMOVED]
>1213777682.935673: Priority group 5
>1213777682.935685:    id=0 ssid='Getalife'
>1213777682.935697: Initializing interface (2) 'wlan0'
>1213777682.939338: EAPOL: SUPP_PAE entering state DISCONNECTED
>1213777682.939358: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
>1213777682.939370: EAPOL: SUPP_BE entering state INITIALIZE
>1213777682.939386: EAP: EAP entering state DISABLED
>1213777682.939397: EAPOL: External notification - portEnabled=0
>1213777682.939405: EAPOL: External notification - portValid=0
>1213777683.151179: SIOCGIWRANGE: WE(compiled)=22 WE(source)=21 enc_capa=0xf
>1213777683.151217:   capabilities: key_mgmt 0xf enc 0xf
>1213777683.172486: WEXT: Operstate: linkmode=1, operstate=5
>1213777683.191889: Own MAC address: 00:1f:3b:70:4d:77
>1213777683.191912: wpa_driver_wext_set_wpa
>1213777683.191926: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 k
>ey_len=0
>1213777683.191944: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 k
>ey_len=0
>1213777683.191958: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 k
>ey_len=0
>1213777683.191971: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 k
>ey_len=0
>1213777683.191985: wpa_driver_wext_set_countermeasures
>WEXT auth param 4 value 0x0 - ioctl[SIOCSIWAUTH]: Operation not supported
>1213777683.192053: wpa_driver_wext_set_drop_unencrypted
>1213777683.192077: Setting scan request: 0 sec 100000 usec
>1213777683.192112: Using existing control interface directory.
>1213777683.193121: ctrl_interface_group=0
>1213777683.193246: Added interface wlan0
>1213777683.193292: RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
>1213777683.193306: Wireless event: cmd=0x8b06 len=12
>1213777683.193318: Ignore event for foreign ifindex 3
>1213777683.193329: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1213777683.193339: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1213777683.295928: State: DISCONNECTED -> SCANNING
>1213777683.295954: Starting AP scan (broadcast SSID)
>1213777683.295964: Trying to get current scan results first without requesting a
> new scan to speed up initial association
>ioctl[SIOCGIWSCAN]: Resource temporarily unavailable
>1213777683.296035: Scan results: -1
>1213777683.296045: Failed to get scan results
>1213777683.296053: Failed to get scan results - try scanning again
>1213777683.296064: Setting scan request: 0 sec 0 usec
>1213777683.296082: Starting AP scan (broadcast SSID)
>1213777683.296095: Scan requested (ret=0) - scan timeout 5 seconds
>1213777684.720740: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1213777684.720776: Wireless event: cmd=0x8b19 len=16
>1213777684.720840: Received 650 bytes of scan results (2 BSSes)
>1213777684.720853: Scan results: 2
>1213777684.720863: Selecting BSS from priority group 5
>1213777684.720871: Try to find WPA-enabled AP
>1213777684.720879: 0: 00:1d:7e:aa:1d:4a ssid='Getalife' wpa_ie_len=28 rsn_ie_len
>=0 caps=0x11
>1213777684.720897:    selected based on WPA IE
>1213777684.720905:    selected WPA AP 00:1d:7e:aa:1d:4a ssid='Getalife'
>1213777684.720916: Try to find non-WPA AP
>1213777684.720931: Trying to associate with 00:1d:7e:aa:1d:4a (SSID='Getalife' f
>req=2442 MHz)
>1213777684.720942: Cancelling scan request
>1213777684.720951: WPA: clearing own WPA/RSN IE
>1213777684.720959: Automatic auth_alg selection: 0x1
>1213777684.720975: WPA: using IEEE 802.11i/D3.0
>1213777684.720984: WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 2 p
>roto 1
>1213777684.721005: WPA: set AP WPA IE - hexdump(len=28): dd 1a 00 50 f2 01 01 00
> 00 50 f2 02 02 00 00 50 f2 04 00 50 f2 02 01 00 00 50 f2 02
>1213777684.721029: WPA: clearing AP RSN IE
>1213777684.721039: WPA: using GTK TKIP
>1213777684.721048: WPA: using PTK CCMP
>1213777684.721056: WPA: using KEY_MGMT WPA-PSK
>1213777684.721065: 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 04 01 00 00 50 f2 02
>1213777684.721086: No keys have been configured - skip key clearing
>1213777684.721095: wpa_driver_wext_set_drop_unencrypted
>1213777684.721105: State: SCANNING -> ASSOCIATING
>1213777684.721115: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1213777684.721125: WEXT: Operstate: linkmode=-1, operstate=5
>1213777684.721145: wpa_driver_wext_associate
>1213777684.727328: Setting authentication timeout: 15 sec 0 usec
>1213777684.727350: EAPOL: External notification - EAP success=0
>1213777684.727363: EAPOL: External notification - EAP fail=0
>1213777684.727371: EAPOL: External notification - portControl=Auto
>1213777684.727393: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1213777684.727405: Wireless event: cmd=0x8b06 len=12
>1213777684.727416: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1213777684.727426: Wireless event: cmd=0x8b04 len=16
>1213777684.727437: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1213777684.727446: Wireless event: cmd=0x8b1a len=24
>1213777684.738774: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1213777684.738799: Wireless event: cmd=0x8b15 len=24
>1213777684.738809: Wireless event: new AP: 00:1d:7e:aa:1d:4a
>1213777684.738832: State: ASSOCIATING -> ASSOCIATED
>1213777684.738840: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1213777684.738854: WEXT: Operstate: linkmode=-1, operstate=5
>1213777684.738880: Associated to a new BSS: BSSID=00:1d:7e:aa:1d:4a
>1213777684.738890: No keys have been configured - skip key clearing
>1213777684.738900: Associated with 00:1d:7e:aa:1d:4a
>1213777684.738908: WPA: Association event - clear replay counter
>1213777684.738915: EAPOL: External notification - portEnabled=0
>1213777684.738924: EAPOL: External notification - portValid=0
>1213777684.738936: EAPOL: External notification - EAP success=0
>1213777684.738944: EAPOL: External notification - portEnabled=1
>1213777684.738951: EAPOL: SUPP_PAE entering state CONNECTING
>1213777684.738958: EAPOL: SUPP_BE entering state IDLE
>1213777684.738968: Setting authentication timeout: 10 sec 0 usec
>1213777684.738979: Cancelling scan request
>1213777684.738990: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1213777684.739000: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1213777684.753939: RX EAPOL from 00:1d:7e:aa:1d:4a
>1213777684.753970: RX EAPOL - hexdump(len=99): 02 03 00 5f fe 00 8a 00 10 00 00
>00 00 00 00 00 01 65 ac bf c1 cb e1 88 39 42 c7 f0 6e 3c 45 7e 4e 21 3b 3b 96 53
> 1f d5 1f 95 a6 f5 09 5f 49 90 4c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0
>0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00 00 00 00
>1213777684.754030: Setting authentication timeout: 10 sec 0 usec
>1213777684.754050: IEEE 802.1X RX: version=2 type=3 length=95
>1213777684.754065:   EAPOL-Key type=254
>1213777684.754075:   key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
>1213777684.754087:   key_length=16 key_data_length=0
>1213777684.754097:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
>1213777684.754110:   key_nonce - hexdump(len=32): 65 ac bf c1 cb e1 88 39 42 c7
>f0 6e 3c 45 7e 4e 21 3b 3b 96 53 1f d5 1f 95 a6 f5 09 5f 49 90 4c
>1213777684.754133:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00
>1213777684.754149:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1213777684.754161:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1213777684.754178:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00
> 00 00 00 00 00
>1213777684.754194: WPA: RX EAPOL-Key - hexdump(len=99): 02 03 00 5f fe 00 8a 00
>10 00 00 00 00 00 00 00 01 65 ac bf c1 cb e1 88 39 42 c7 f0 6e 3c 45 7e 4e 21 3b
> 3b 96 53 1f d5 1f 95 a6 f5 09 5f 49 90 4c 00 00 00 00 00 00 00 00 00 00 00 00 0
>0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00 00 00 00 00 00 00
>1213777684.754264: State: ASSOCIATED -> 4WAY_HANDSHAKE
>1213777684.754274: WPA: RX message 1 of 4-Way Handshake from 00:1d:7e:aa:1d:4a (
>ver=2)
>1213777684.756273: WPA: Renewed SNonce - hexdump(len=32): 89 af af b1 2b ea 7c 1
>6 a8 07 1b a7 63 62 63 5a 3e a4 c7 1f c6 83 aa 55 f6 5e 2f 43 1a 75 4d 68
>1213777684.756330: WPA: PMK - hexdump(len=32): [REMOVED]
>1213777684.756339: WPA: PTK - hexdump(len=64): [REMOVED]
>1213777684.756348: 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 04 01 00 00 50 f2 02
>1213777684.756371: WPA: Sending EAPOL-Key 2/4
>1213777684.756384: WPA: TX EAPOL-Key - hexdump(len=123): 01 03 00 77 fe 01 0a 00
> 10 00 00 00 00 00 00 00 01 89 af af b1 2b ea 7c 16 a8 07 1b a7 63 62 63 5a 3e a
>4 c7 1f c6 83 aa 55 f6 5e 2f 43 1a 75 4d 68 00 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ac 3d c9 b5 67 5d 47
> 5a 3f 9f 2f ed 61 69 20 81 00 18 dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 5
>0 f2 04 01 00 00 50 f2 02
>1213777684.790453: RX EAPOL from 00:1d:7e:aa:1d:4a
>1213777684.790476: RX EAPOL - hexdump(len=127): 02 03 00 7b fe 01 ca 00 10 00 00
> 00 00 00 00 00 02 65 ac bf c1 cb e1 88 39 42 c7 f0 6e 3c 45 7e 4e 21 3b 3b 96 5
>3 1f d5 1f 95 a6 f5 09 5f 49 90 4c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 a4 78 3b e2 94 dd 79 b8 67 d4
> be 4e 96 6f 07 a3 00 1c dd 1a 00 50 f2 01 01 00 00 50 f2 02 02 00 00 50 f2 04 0
>0 50 f2 02 01 00 00 50 f2 02
>1213777684.790544: IEEE 802.1X RX: version=2 type=3 length=123
>1213777684.790554:   EAPOL-Key type=254
>1213777684.790562:   key_info 0x1ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack
>MIC)
>1213777684.790574:   key_length=16 key_data_length=28
>1213777684.790582:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
>1213777684.790595:   key_nonce - hexdump(len=32): 65 ac bf c1 cb e1 88 39 42 c7
>f0 6e 3c 45 7e 4e 21 3b 3b 96 53 1f d5 1f 95 a6 f5 09 5f 49 90 4c
>1213777684.790618:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00
>1213777684.790634:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1213777684.790646:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1213777684.790658:   key_mic - hexdump(len=16): a4 78 3b e2 94 dd 79 b8 67 d4 be
> 4e 96 6f 07 a3
>1213777684.790674: WPA: RX EAPOL-Key - hexdump(len=127): 02 03 00 7b fe 01 ca 00
> 10 00 00 00 00 00 00 00 02 65 ac bf c1 cb e1 88 39 42 c7 f0 6e 3c 45 7e 4e 21 3
>b 3b 96 53 1f d5 1f 95 a6 f5 09 5f 49 90 4c 00 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 a4 78 3b e2 94 dd 79
> b8 67 d4 be 4e 96 6f 07 a3 00 1c dd 1a 00 50 f2 01 01 00 00 50 f2 02 02 00 00 5
>0 f2 04 00 50 f2 02 01 00 00 50 f2 02
>1213777684.790750: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
>1213777684.790760: WPA: RX message 3 of 4-Way Handshake from 00:1d:7e:aa:1d:4a (
>ver=2)
>1213777684.790771: WPA: IE KeyData - hexdump(len=28): dd 1a 00 50 f2 01 01 00 00
> 50 f2 02 02 00 00 50 f2 04 00 50 f2 02 01 00 00 50 f2 02
>1213777684.790796: WPA: Sending EAPOL-Key 4/4
>1213777684.790810: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 01 0a 00
>10 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0
>0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 69 2f f5 fd 3c eb b1
>68 bf cd a9 18 74 e6 59 71 00 00
>1213777684.790906: WPA: Installing PTK to the driver.
>1213777684.790917: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1213777684.790929: wpa_driver_wext_set_key: alg=3 key_idx=0 set_tx=1 seq_len=6 k
>ey_len=16
>1213777684.847462: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
>1213777685.833651: RX EAPOL from 00:1d:7e:aa:1d:4a
>1213777685.833686: RX EAPOL - hexdump(len=139): 02 03 00 87 fe 03 a2 00 20 00 00
> 00 00 00 00 00 04 65 ac bf c1 cb e1 88 39 42 c7 f0 6e 3c 45 7e 4e 21 3b 3b 96 5
>3 1f d5 1f 95 a6 f5 09 5f 49 90 4b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>00 4e 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 26 d0 8b 7a e6 fd e1 ab 72 f4
> 4b 13 8c 0c 4e 92 00 28 ca a3 03 87 2f 98 f8 72 a9 7d f2 3d 00 17 eb b7 8a a9 5
>7 e8 d7 ed 31 0b 58 fc 2c 39 22 35 8d 3f 07 dc 15 da 8c fb da 1f
>1213777685.833762: IEEE 802.1X RX: version=2 type=3 length=135
>1213777685.833772:   EAPOL-Key type=254
>1213777685.833780:   key_info 0x3a2 (ver=2 keyidx=2 rsvd=0 Group Ack MIC Secure)
>1213777685.833792:   key_length=32 key_data_length=40
>1213777685.833801:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 04
>1213777685.833813:   key_nonce - hexdump(len=32): 65 ac bf c1 cb e1 88 39 42 c7
>f0 6e 3c 45 7e 4e 21 3b 3b 96 53 1f d5 1f 95 a6 f5 09 5f 49 90 4b
>1213777685.833835:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 00
>1213777685.833851:   key_rsc - hexdump(len=8): 4e 01 00 00 00 00 00 00
>1213777685.833864:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1213777685.833876:   key_mic - hexdump(len=16): 26 d0 8b 7a e6 fd e1 ab 72 f4 4b
> 13 8c 0c 4e 92
>1213777685.833893: WPA: RX EAPOL-Key - hexdump(len=139): 02 03 00 87 fe 03 a2 00
> 20 00 00 00 00 00 00 00 04 65 ac bf c1 cb e1 88 39 42 c7 f0 6e 3c 45 7e 4e 21 3
>b 3b 96 53 1f d5 1f 95 a6 f5 09 5f 49 90 4b 00 00 00 00 00 00 00 00 00 00 00 00
>00 00 00 00 4e 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 26 d0 8b 7a e6 fd e1
> ab 72 f4 4b 13 8c 0c 4e 92 00 28 ca a3 03 87 2f 98 f8 72 a9 7d f2 3d 00 17 eb b
>7 8a a9 57 e8 d7 ed 31 0b 58 fc 2c 39 22 35 8d 3f 07 dc 15 da 8c fb da 1f
>1213777685.833979: WPA: RX message 1 of Group Key Handshake from 00:1d:7e:aa:1d:
>4a (ver=2)
>1213777685.834046: State: GROUP_HANDSHAKE -> GROUP_HANDSHAKE
>1213777685.834058: WPA: Group Key - hexdump(len=32): [REMOVED]
>1213777685.834067: WPA: Installing GTK to the driver (keyidx=2 tx=0).
>1213777685.834076: WPA: RSC - hexdump(len=6): 4e 01 00 00 00 00
>1213777685.834089: wpa_driver_wext_set_key: alg=2 key_idx=2 set_tx=0 seq_len=6 k
>ey_len=32
>1213777685.834176: WPA: Sending EAPOL-Key 2/2
>1213777685.834195: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 03 22 00                                                                                                        20 00 00 00 00 00 00 00 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00                                                                                                        00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0                                                                                                       0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 b7 e8 52 d5 20 9f 22                                                                                                        52 39 bc 86 0b cc 2d d2 0f 00 00
>1213777685.834308: WPA: Key negotiation completed with 00:1d:7e:aa:1d:4a [PTK=CC                                                                                                       MP GTK=TKIP]
>1213777685.834323: Cancelling authentication timeout
>1213777685.834331: State: GROUP_HANDSHAKE -> COMPLETED
>1213777685.834345: CTRL-EVENT-CONNECTED - Connection to 00:1d:7e:aa:1d:4a comple                                                                                                       ted (auth) [id=0 id_str=]
>1213777685.834355: wpa_driver_wext_set_operstate: operstate 0->1 (UP)
>1213777685.834366: WEXT: Operstate: linkmode=-1, operstate=6
>1213777685.835909: EAPOL: External notification - portValid=1
>1213777685.835926: EAPOL: External notification - EAP success=1
>1213777685.835934: EAPOL: SUPP_PAE entering state AUTHENTICATING
>1213777685.835942: EAPOL: SUPP_BE entering state SUCCESS
>1213777685.835949: EAP: EAP entering state DISABLED
>1213777685.835956: EAPOL: SUPP_PAE entering state AUTHENTICATED
>1213777685.835963: EAPOL: SUPP_BE entering state IDLE
>1213777685.835986: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOW                                                                                                       ER_UP])
>1213777685.835999: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1213777686.951924: EAPOL: startWhen --> 0

Actions: View

Attachments on bug 383083: 212534 | 215827 | 215829 | 222686 | 243723 | 243724 | 243725