Attachment 298102 Details for Bug 437576 – Log file (using -dd option) during a network restart followed by wpa_supplicant restart

Log file (using -dd option) during a network restart followed by wpa_supplicant restart

wpa_supplicant.log (text/plain), 16.27 KB, created by Ian Chapman on 2008-03-14 22:59:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ian Chapman

Created: 2008-03-14 22:59:12 UTC

Size: 16.27 KB

patch

obsolete

>1205535421.594029: Initializing interface 'wlan0' conf '/etc/wpa_supplicant/wpa_supplicant.conf' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
>1205535421.594247: Configuration file '/etc/wpa_supplicant/wpa_supplicant.conf' -> '/etc/wpa_supplicant/wpa_supplicant.conf'
>1205535421.594282: Reading configuration file '/etc/wpa_supplicant/wpa_supplicant.conf'
>1205535421.594389: ctrl_interface='/var/run/wpa_supplicant'
>1205535421.594423: ctrl_interface_group='wheel' (DEPRECATED)
>1205535421.594449: Line: 4 - start of a new network block
>1205535421.594490: ssid - hexdump_ascii(len=10):
>     61 74 6d 6f 73 70 68 65 72 65                     atmosphere      
>1205535421.594549: PSK (ASCII passphrase) - hexdump_ascii(len=21): [REMOVED]
>1205535421.594585: key_mgmt: 0x2
>1205535421.594614: proto: 0x1
>1205535421.640860: PSK (from passphrase) - hexdump(len=32): [REMOVED]
>1205535421.640989: Priority group 0
>1205535421.641019:    id=0 ssid='atmosphere'
>1205535421.641050: Initializing interface (2) 'wlan0'
>1205535421.650389: EAPOL: SUPP_PAE entering state DISCONNECTED
>1205535421.650456: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
>1205535421.650479: EAPOL: SUPP_BE entering state INITIALIZE
>1205535421.650511: EAP: EAP entering state DISABLED
>1205535421.650542: EAPOL: External notification - portEnabled=0
>1205535421.650568: EAPOL: External notification - portValid=0
>1205535423.125148: SIOCGIWRANGE: WE(compiled)=22 WE(source)=21 enc_capa=0xf
>1205535423.125253:   capabilities: key_mgmt 0xf enc 0xf
>1205535423.218129: WEXT: Operstate: linkmode=1, operstate=5
>1205535423.222208: Own MAC address: 00:0d:93:ea:fd:eb
>1205535423.222236: wpa_driver_wext_set_wpa
>1205535423.222257: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
>1205535423.222287: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
>1205535423.222304: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
>1205535423.222320: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
>1205535423.222337: wpa_driver_wext_set_countermeasures
>1205535423.223644: wpa_driver_wext_set_drop_unencrypted
>1205535423.223695: Setting scan request: 0 sec 100000 usec
>1205535423.224418: ctrl_interface_group=10 (from group name 'wheel')
>1205535423.224570: Added interface wlan0
>1205535423.224592: Daemonize..
>1205535423.225399: RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
>1205535423.225511: Wireless event: cmd=0x8b06 len=8
>1205535423.225528: Ignore event for foreign ifindex 3
>1205535423.225546: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1205535423.225564: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1205535423.324227: State: DISCONNECTED -> SCANNING
>1205535423.324316: Starting AP scan (broadcast SSID)
>1205535423.324330: Trying to get current scan results first without requesting a new scan to speed up initial association
>1205535423.324509: Received 0 bytes of scan results (0 BSSes)
>1205535423.324536: Scan results: 0
>1205535423.324550: Selecting BSS from priority group 0
>1205535423.324563: No suitable AP found.
>1205535423.324585: Setting scan request: 0 sec 0 usec
>1205535423.324610: Starting AP scan (broadcast SSID)
>1205535424.113276: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1205535424.113364: Wireless event: cmd=0x8b19 len=8
>1205535424.113518: Scan results: -1
>1205535424.113541: Failed to get scan results
>1205535424.113554: Failed to get scan results - try scanning again
>1205535424.113572: Setting scan request: 1 sec 0 usec
>1205535424.902270: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1205535424.902356: Wireless event: cmd=0x8b19 len=8
>1205535424.902470: Received 684 bytes of scan results (3 BSSes)
>1205535424.902496: Scan results: 3
>1205535424.902513: Selecting BSS from priority group 0
>1205535424.902526: 0: 00:11:50:34:8d:ef ssid='atmosphere' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
>1205535424.902554:    selected based on WPA IE
>1205535424.902579: Trying to associate with 00:11:50:34:8d:ef (SSID='atmosphere' freq=2462 MHz)
>1205535424.902594: Cancelling scan request
>1205535424.902609: WPA: clearing own WPA/RSN IE
>1205535424.902625: Automatic auth_alg selection: 0x1
>1205535424.902718: WPA: using IEEE 802.11i/D3.0
>1205535424.902735: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto 1
>1205535424.902752: WPA: set AP WPA IE - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.902781: WPA: clearing AP RSN IE
>1205535424.902796: WPA: using GTK CCMP
>1205535424.902809: WPA: using PTK CCMP
>1205535424.902822: WPA: using KEY_MGMT WPA-PSK
>1205535424.902835: WPA: Set own WPA IE default - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.902863: No keys have been configured - skip key clearing
>1205535424.902874: wpa_driver_wext_set_drop_unencrypted
>1205535424.902889: State: SCANNING -> ASSOCIATING
>1205535424.902902: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1205535424.902915: WEXT: Operstate: linkmode=-1, operstate=5
>1205535424.902947: wpa_driver_wext_associate
>1205535424.903031: Setting authentication timeout: 15 sec 0 usec
>1205535424.903053: EAPOL: External notification - EAP success=0
>1205535424.903071: EAPOL: External notification - EAP fail=0
>1205535424.903084: EAPOL: External notification - portControl=Auto
>1205535424.903433: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1205535424.903470: Wireless event: cmd=0x8b06 len=8
>1205535424.903486: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1205535424.903498: Wireless event: cmd=0x8b04 len=12
>1205535424.903512: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
>1205535424.903525: Wireless event: cmd=0x8b1a len=18
>1205535424.909132: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1205535424.909214: Wireless event: cmd=0x8c02 len=187
>1205535424.909235: WEXT: Custom wireless event: 'ASSOCINFO(ReqIEs=000a61746d6f737068657265010802040b160c12182432043048606cdd160050f20101000050f20401000050f20401000050f202 RespIEs=010882848b962430486c32040c121860dd06001018010000)'
>1205535424.909334: Association info event
>1205535424.909352: req_ies - hexdump(len=52): 00 0a 61 74 6d 6f 73 70 68 65 72 65 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 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.909411: resp_ies - hexdump(len=24): 01 08 82 84 8b 96 24 30 48 6c 32 04 0c 12 18 60 dd 06 00 10 18 01 00 00
>1205535424.909440: WPA: set own WPA/RSN IE - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.909477: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1205535424.909492: Wireless event: cmd=0x8b15 len=20
>1205535424.909504: Wireless event: new AP: 00:11:50:34:8d:ef
>1205535424.909518: State: ASSOCIATING -> ASSOCIATED
>1205535424.909531: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
>1205535424.909543: WEXT: Operstate: linkmode=-1, operstate=5
>1205535424.909586: Associated to a new BSS: BSSID=00:11:50:34:8d:ef
>1205535424.909604: No keys have been configured - skip key clearing
>1205535424.909618: Associated with 00:11:50:34:8d:ef
>1205535424.909630: WPA: Association event - clear replay counter
>1205535424.909648: EAPOL: External notification - portEnabled=0
>1205535424.909666: EAPOL: External notification - portValid=0
>1205535424.909679: EAPOL: External notification - EAP success=0
>1205535424.909691: EAPOL: External notification - portEnabled=1
>1205535424.909704: EAPOL: SUPP_PAE entering state CONNECTING
>1205535424.909715: EAPOL: SUPP_BE entering state IDLE
>1205535424.909729: Setting authentication timeout: 10 sec 0 usec
>1205535424.909745: Cancelling scan request
>1205535424.909761: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
>1205535424.909776: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1205535424.909796: RX EAPOL from 00:11:50:34:8d:ef
>1205535424.909811: RX EAPOL - hexdump(len=99): 01 03 00 5f fe 00 8a 00 10 00 00 00 00 00 00 00 00 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1205535424.909945: Setting authentication timeout: 10 sec 0 usec
>1205535424.909968: IEEE 802.1X RX: version=1 type=3 length=95
>1205535424.909983:   EAPOL-Key type=254
>1205535424.909995:   key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
>1205535424.910008:   key_length=16 key_data_length=0
>1205535424.910019:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 00
>1205535424.910036:   key_nonce - hexdump(len=32): 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4d
>1205535424.910064:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1205535424.910085:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1205535424.910101:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1205535424.910118:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1205535424.910139: WPA: RX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 00 8a 00 10 00 00 00 00 00 00 00 00 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1205535424.910287: State: ASSOCIATED -> 4WAY_HANDSHAKE
>1205535424.910306: WPA: RX message 1 of 4-Way Handshake from 00:11:50:34:8d:ef (ver=2)
>1205535424.911945: WPA: Renewed SNonce - hexdump(len=32): 68 cb 11 6a 0f 6c 84 7d 08 4c 73 9f 09 d2 6f b4 31 63 a6 b2 26 70 26 40 aa e0 c4 3f 41 f0 5d 9a
>1205535424.912034: WPA: PMK - hexdump(len=32): [REMOVED]
>1205535424.912050: WPA: PTK - hexdump(len=64): [REMOVED]
>1205535424.912062: WPA: WPA IE for msg 2/4 - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.912091: WPA: Sending EAPOL-Key 2/4
>1205535424.912110: WPA: TX EAPOL-Key - hexdump(len=123): 01 03 00 77 fe 01 0a 00 10 00 00 00 00 00 00 00 00 68 cb 11 6a 0f 6c 84 7d 08 4c 73 9f 09 d2 6f b4 31 63 a6 b2 26 70 26 40 aa e0 c4 3f 41 f0 5d 9a 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 79 d9 bd c4 f9 ec b5 d0 51 c6 26 57 e1 3c 9e 91 00 18 dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.914247: RX EAPOL from 00:11:50:34:8d:ef
>1205535424.914284: RX EAPOL - hexdump(len=123): 01 03 00 77 fe 01 ca 00 10 00 00 00 00 00 00 00 01 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4d 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 79 ff 79 b7 6a be bb 39 41 6c 09 ab 8a 0d c6 18 00 18 dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.914368: IEEE 802.1X RX: version=1 type=3 length=119
>1205535424.914381:   EAPOL-Key type=254
>1205535424.914392:   key_info 0x1ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack MIC)
>1205535424.914406:   key_length=16 key_data_length=24
>1205535424.914418:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
>1205535424.914434:   key_nonce - hexdump(len=32): 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4d
>1205535424.914462:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>1205535424.914483:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
>1205535424.914499:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1205535424.914515:   key_mic - hexdump(len=16): 79 ff 79 b7 6a be bb 39 41 6c 09 ab 8a 0d c6 18
>1205535424.914536: WPA: RX EAPOL-Key - hexdump(len=123): 01 03 00 77 fe 01 ca 00 10 00 00 00 00 00 00 00 01 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4d 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 79 ff 79 b7 6a be bb 39 41 6c 09 ab 8a 0d c6 18 00 18 dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.914629: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
>1205535424.914644: WPA: RX message 3 of 4-Way Handshake from 00:11:50:34:8d:ef (ver=2)
>1205535424.914657: WPA: IE KeyData - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 04 01 00 00 50 f2 04 01 00 00 50 f2 02
>1205535424.914715: WPA: Sending EAPOL-Key 4/4
>1205535424.914732: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 01 0a 00 10 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 92 bd 60 27 0c f1 4b 45 03 36 85 af 74 6c 1f 78 00 00
>1205535424.914830: WPA: Installing PTK to the driver.
>1205535424.914848: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
>1205535424.914864: wpa_driver_wext_set_key: alg=3 key_idx=0 set_tx=1 seq_len=6 key_len=16
>1205535424.914958: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
>1205535424.917472: RX EAPOL from 00:11:50:34:8d:ef
>1205535424.917495: RX EAPOL - hexdump(len=123): 01 03 00 77 fe 03 a2 00 10 00 00 00 00 00 00 00 02 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4c e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4e 06 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 2e 67 ba 5a 0e fe 81 05 80 ef a1 03 b6 23 eb 00 18 fc 36 92 1e f0 10 91 cd e7 60 32 6c 1e d8 03 60 e3 a0 40 4f 77 21 5e f8
>1205535424.917575: IEEE 802.1X RX: version=1 type=3 length=119
>1205535424.917588:   EAPOL-Key type=254
>1205535424.917599:   key_info 0x3a2 (ver=2 keyidx=2 rsvd=0 Group Ack MIC Secure)
>1205535424.917613:   key_length=16 key_data_length=24
>1205535424.917624:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
>1205535424.917640:   key_nonce - hexdump(len=32): 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4c
>1205535424.917668:   key_iv - hexdump(len=16): e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4e
>1205535424.917688:   key_rsc - hexdump(len=8): 06 00 00 00 00 00 00 00
>1205535424.917704:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
>1205535424.917719:   key_mic - hexdump(len=16): 05 2e 67 ba 5a 0e fe 81 05 80 ef a1 03 b6 23 eb
>1205535424.917740: WPA: RX EAPOL-Key - hexdump(len=123): 01 03 00 77 fe 03 a2 00 10 00 00 00 00 00 00 00 02 3c 85 59 d9 3f a6 1d 94 81 eb 54 68 10 66 bd 39 e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4c e3 99 85 ee 1d e0 ee 99 d7 17 5f 63 86 48 19 4e 06 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 2e 67 ba 5a 0e fe 81 05 80 ef a1 03 b6 23 eb 00 18 fc 36 92 1e f0 10 91 cd e7 60 32 6c 1e d8 03 60 e3 a0 40 4f 77 21 5e f8
>1205535424.917830: WPA: RX message 1 of Group Key Handshake from 00:11:50:34:8d:ef (ver=2)
>1205535424.917905: State: GROUP_HANDSHAKE -> GROUP_HANDSHAKE
>1205535424.917922: WPA: Group Key - hexdump(len=16): [REMOVED]
>1205535424.917935: WPA: Installing GTK to the driver (keyidx=2 tx=0).
>1205535424.917946: WPA: RSC - hexdump(len=6): 06 00 00 00 00 00
>1205535424.917963: wpa_driver_wext_set_key: alg=3 key_idx=2 set_tx=0 seq_len=6 key_len=16
>1205535424.918028: WPA: Sending EAPOL-Key 2/2
>1205535424.918054: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 03 22 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 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 87 87 9f 22 e3 6c 8c 33 56 d7 ff 6e b0 21 15 00 00
>1205535424.918171: WPA: Key negotiation completed with 00:11:50:34:8d:ef [PTK=CCMP GTK=CCMP]
>1205535424.918191: Cancelling authentication timeout
>1205535424.918304: State: GROUP_HANDSHAKE -> COMPLETED
>1205535424.918438: CTRL-EVENT-CONNECTED - Connection to 00:11:50:34:8d:ef completed (auth) [id=0 id_str=]
>1205535424.918452: wpa_driver_wext_set_operstate: operstate 0->1 (UP)
>1205535424.918464: WEXT: Operstate: linkmode=-1, operstate=6
>1205535424.919477: EAPOL: External notification - portValid=1
>1205535424.919516: EAPOL: External notification - EAP success=1
>1205535424.919528: EAPOL: SUPP_PAE entering state AUTHENTICATING
>1205535424.919540: EAPOL: SUPP_BE entering state SUCCESS
>1205535424.919552: EAP: EAP entering state DISABLED
>1205535424.919574: EAPOL: SUPP_PAE entering state AUTHENTICATED
>1205535424.919617: EAPOL: SUPP_BE entering state IDLE
>1205535424.919652: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
>1205535424.919672: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
>1205535427.227205: EAPOL: startWhen --> 0

Actions: View

Attachments on bug 437576: 298100 | 298101 | 298102