wpasupplicant and WEP (more info)

Erich Titl erich.titl at think.ch
Wed Dec 31 08:50:02 EST 2014


Hi Folks

I started wpa_supplicant with debugging, to me it looks like the
association worked.

Thanks for pointers

>>>>

wpa_supplicant v2.2
random: Trying to read entropy from /dev/random
Successfully initialized wpa_supplicant
Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver
'wext' ctrl_interface 'N/A' bridge 'N/A'
Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.conf'
Reading configuration file '/etc/wpa_supplicant.conf'
ctrl_interface='/var/run/wpa_supplicant'
update_config=1
eapol_version=1
ap_scan=1
fast_reauth=1
Line: 36 - start of a new network block
ssid - hexdump_ascii(len=7):
     73 63 6f 6f 62 6c 79                              scoobly
key_mgmt: 0x4
wep_key0 - hexdump(len=13): [REMOVED]
wep_tx_keyidx=0 (0x0)
Priority group 0
   id=0 ssid='scoobly'
WEXT: cfg80211-based driver detected
wext: interface wlan0 phy: phy0
rfkill: initial event: idx=0 type=1 op=0 soft=0 hard=0
SIOCGIWRANGE: WE(compiled)=22 WE(source)=21 enc_capa=0xf
  capabilities: key_mgmt 0xf enc 0x1f flags 0x0
netlink: Operstate: ifindex=4 linkmode=1 (userspace-control),
operstate=5 (IF_OPER_DORMANT)
Add interface wlan0 to a new radio phy0
wlan0: Own MAC address: 00:1a:2b:5f:61:11
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_countermeasures
wlan0: RSN: flushing PMKID list in the driver
wlan0: Setting scan request: 0.100000 sec
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
wlan0: Added interface wlan0
wlan0: State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
random: Got 20/20 bytes from /dev/random
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b1a len=8
wlan0: State: DISCONNECTED -> SCANNING
wlan0: Starting AP scan for wildcard SSID
wlan0: Add radio work 'scan'@0x8f02648
wlan0: First radio work item in the queue - schedule start immediately
wlan0: Starting radio work 'scan'@0x8f02648 after 0.000193 second wait
Scan requested (ret=0) - scan timeout 10 seconds
EAPOL: disable timer tick
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b19 len=8
wlan0: Event SCAN_RESULTS (3) received
Scan results did not fit - trying larger buffer (8192 bytes)
Received 7901 bytes of scan results (17 BSSes)
wlan0: BSS: Start scan result update 1
wlan0: BSS: Add new id 0 BSSID 00:0b:6b:36:bc:c9 SSID 'SALT'
wlan0: BSS: Add new id 1 BSSID dc:71:44:ae:fd:88 SSID 'UPC248834387'
wlan0: BSS: Add new id 2 BSSID dc:71:44:ae:fd:89 SSID 'UPC Wi-Free'
wlan0: BSS: Add new id 3 BSSID 5c:a3:9d:f0:b9:88 SSID 'UPC246501678'
wlan0: BSS: Add new id 4 BSSID 5c:a3:9d:88:3b:88 SSID 'UPC249534882'
wlan0: BSS: Add new id 5 BSSID 5c:a3:9d:f0:b9:89 SSID 'UPC Wi-Free'
wlan0: BSS: Add new id 6 BSSID 5c:a3:9d:e4:d8:b8 SSID 'UPC247695636'
wlan0: BSS: Add new id 7 BSSID 10:0d:7f:6d:90:62 SSID 'alinnet'
wlan0: BSS: Add new id 8 BSSID 28:be:9b:05:e0:01 SSID 'UPC0038059'
wlan0: BSS: Add new id 9 BSSID dc:71:44:af:9d:b8 SSID 'UPC249806521'
wlan0: BSS: Add new id 10 BSSID 88:1f:a1:35:7c:62 SSID 'Welcome'
wlan0: BSS: Add new id 11 BSSID f0:84:2f:d5:79:79 SSID 'cgz-98799'
wlan0: BSS: Add new id 12 BSSID 8e:04:ff:02:0e:8f SSID
'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'ioctl[SIOCSIWFREQ]:
Device or resource busy

wlan0: BSS: Add new id 13 BSSID 2a:be:9b:05:e0:03 SSID 'UPC Wi-Free'
wlan0: BSS: Add new id 14 BSSID 8c:04:ff:02:0e:8e SSID 'UPC0046489'
wlan0: BSS: Add new id 15 BSSID 8e:04:ff:02:0e:80 SSID 'UPC Wi-Free'
wlan0: BSS: Add new id 16 BSSID f8:1a:67:56:42:96 SSID 'scoobly'
BSS: last_scan_res_used=17/32
wlan0: New scan results available (own=0 ext=0)
wlan0: Radio work 'scan'@0x8f02648 done in 1.013447 seconds
wlan0: Selecting BSS from priority group 0
wlan0: 0: 00:0b:6b:36:bc:c9 ssid='SALT' wpa_ie_len=0 rsn_ie_len=24
caps=0x11 level=-57
wlan0:    skip - SSID mismatch
wlan0: 1: dc:71:44:ae:fd:88 ssid='UPC248834387' wpa_ie_len=0
rsn_ie_len=24 caps=0x11 level=-70 wps
wlan0:    skip - SSID mismatch
wlan0: 2: dc:71:44:ae:fd:89 ssid='UPC Wi-Free' wpa_ie_len=0
rsn_ie_len=20 caps=0x11 level=-71
wlan0:    skip - SSID mismatch
wlan0: 3: 5c:a3:9d:f0:b9:88 ssid='UPC246501678' wpa_ie_len=0
rsn_ie_len=24 caps=0x11 level=-78 wps
wlan0:    skip - SSID mismatch
wlan0: 4: 5c:a3:9d:88:3b:88 ssid='UPC249534882' wpa_ie_len=26
rsn_ie_len=0 caps=0x11 level=-81 wps
wlan0:    skip - SSID mismatch
wlan0: 5: 5c:a3:9d:f0:b9:89 ssid='UPC Wi-Free' wpa_ie_len=0
rsn_ie_len=20 caps=0x11 level=-85
wlan0:    skip - SSID mismatch
wlan0: 6: 5c:a3:9d:e4:d8:b8 ssid='UPC247695636' wpa_ie_len=0
rsn_ie_len=24 caps=0x11 level=-85 wps
wlan0:    skip - SSID mismatch
wlan0: 7: 10:0d:7f:6d:90:62 ssid='alinnet' wpa_ie_len=0 rsn_ie_len=24
caps=0x11 level=-85
wlan0:    skip - SSID mismatch
wlan0: 8: 28:be:9b:05:e0:01 ssid='UPC0038059' wpa_ie_len=28
rsn_ie_len=24 caps=0x11 level=-86
wlan0:    skip - SSID mismatch
wlan0: 9: dc:71:44:af:9d:b8 ssid='UPC249806521' wpa_ie_len=0
rsn_ie_len=24 caps=0x11 level=-86 wps
wlan0:    skip - SSID mismatch
wlan0: 10: 88:1f:a1:35:7c:62 ssid='Welcome' wpa_ie_len=0 rsn_ie_len=20
caps=0x11 level=-86
wlan0:    skip - SSID mismatch
wlan0: 11: f0:84:2f:d5:79:79 ssid='cgz-98799' wpa_ie_len=28
rsn_ie_len=24 caps=0x11 level=-86 wps
wlan0:    skip - SSID mismatch
wlan0: 12: 8e:04:ff:02:0e:8f
ssid='\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
wpa_ie_len=28 rsn_ie_len=24 caps=0x11 level=-88
wlan0:    skip - SSID mismatch
wlan0: 13: 2a:be:9b:05:e0:03 ssid='UPC Wi-Free' wpa_ie_len=0
rsn_ie_len=24 caps=0x11 level=-88
wlan0:    skip - SSID mismatch
wlan0: 14: 8c:04:ff:02:0e:8e ssid='UPC0046489' wpa_ie_len=28
rsn_ie_len=24 caps=0x11 level=-89
wlan0:    skip - SSID mismatch
wlan0: 15: 8e:04:ff:02:0e:80 ssid='UPC Wi-Free' wpa_ie_len=0
rsn_ie_len=24 caps=0x11 level=-90
wlan0:    skip - SSID mismatch
wlan0: 16: f8:1a:67:56:42:96 ssid='scoobly' wpa_ie_len=0 rsn_ie_len=0
caps=0x11 level=-70 wps
wlan0:    allow in non-WPA/WPA2
wlan0:    selected BSS f8:1a:67:56:42:96 ssid='scoobly'
wlan0: Considering connect request: reassociate: 0  selected:
f8:1a:67:56:42:96  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00
 wpa_state: SCANNING  ssid=0x8ef4a40  current_ssid=(nil)
wlan0: Request association with f8:1a:67:56:42:96
wlan0: Add radio work 'connect'@0x8f02648
wlan0: First radio work item in the queue - schedule start immediately
wlan0: Starting radio work 'connect'@0x8f02648 after 0.000358 second wait
wlan0: Trying to associate with f8:1a:67:56:42:96 (SSID='scoobly'
freq=2427 MHz)
wlan0: Cancelling scan request
wlan0: WPA: clearing own WPA/RSN IE
wlan0: Automatic auth_alg selection: 0x1
wlan0: WPA: clearing AP WPA IE
wlan0: WPA: clearing AP RSN IE
wlan0: WPA: clearing own WPA/RSN IE
wpa_driver_wext_set_key: alg=1 key_idx=0 set_tx=1 seq_len=0 key_len=13
wlan0: State: SCANNING -> ASSOCIATING
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
Limit connection to BSSID f8:1a:67:56:42:96 freq=2427 MHz based on scan
results (bssid_set=0)
wpa_driver_wext_associate
wpa_driver_wext_set_drop_unencrypted
wpa_driver_wext_set_psk
wlan0: Association request to the driver failed
wlan0: Setting authentication timeout: 5 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=ForceAuthorized
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b1a len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b1a len=15
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8c08 len=73
AssocResp IE wireless event - hexdump(len=65): 01 08 82 84 8b 0c 12 96
18 24 32 04 30 48 60 6c dd 18 00 50 f2 02 01 01 85 00 03 a4 00 00 27 a4
00 00 42 43 5e 00 62 32 2f 00 dd 09 00 03 7f 01 01 00 00 ff 7f dd 0a 00
03 7f 04 01 00 06 00 40 00
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: f8:1a:67:56:42:96
wlan0: Event ASSOCINFO (4) received
wlan0: Association info event
resp_ies - hexdump(len=65): 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60
6c dd 18 00 50 f2 02 01 01 85 00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62
32 2f 00 dd 09 00 03 7f 01 01 00 00 ff 7f dd 0a 00 03 7f 04 01 00 06 00
40 00
wlan0: Event ASSOC (0) received
wlan0: State: ASSOCIATING -> ASSOCIATED

>>> Looks OK

wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
wlan0: Associated to a new BSS: BSSID=f8:1a:67:56:42:96
wlan0: Associated with f8:1a:67:56:42:96
wlan0: WPA: Association event - clear replay counter
wlan0: WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state S_FORCE_AUTH
EAPOL: Supplicant port status: Authorized
EAPOL: SUPP_BE entering state IDLE
wlan0: Cancelling authentication timeout
wlan0: State: ASSOCIATED -> COMPLETED

>>> This is what is shown in `status`

wlan0: Radio work 'connect'@0x8f02648 done in 0.036686 seconds
wlan0: CTRL-EVENT-CONNECTED - Connection to f8:1a:67:56:42:96 completed
[id=0 id_str=]
wpa_driver_wext_set_operstate: operstate 0->1 (UP)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=6
(IF_OPER_UP)
wlan0: Cancelling scan request
RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
wlan0: Control interface command 'STATUS'

>>> This is probably because wpasupplicant was killed

wlan0: Removing interface wlan0
wlan0: Request to deauthenticate - bssid=f8:1a:67:56:42:96
pending_bssid=00:00:00:00:00:00 reason=3 state=COMPLETED
wpa_driver_wext_deauthenticate
wlan0: Event DEAUTH (12) received
wlan0: Deauthentication notification
wlan0:  * reason 3 (locally generated)
Deauthentication frame IE(s) - hexdump(len=0): [NULL]
wlan0: CTRL-EVENT-DISCONNECTED bssid=f8:1a:67:56:42:96 reason=3
locally_generated=1
wlan0: Auto connect disabled: do not try to re-connect
wlan0: Ignore connection failure indication since interface has been put
into disconnected state
wlan0: State: COMPLETED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 1->0 (DORMANT)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
EAPOL: SUPP_BE entering state INITIALIZE
EAPOL: External notification - portValid=0
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wlan0: State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5
(IF_OPER_DORMANT)
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
wpa_driver_wext_set_countermeasures
wlan0: BSS: Remove id 0 BSSID 00:0b:6b:36:bc:c9 SSID 'SALT' due to
wpa_bss_flush
wlan0: BSS: Remove id 1 BSSID dc:71:44:ae:fd:88 SSID 'UPC248834387' due
to wpa_bss_flush
wlan0: BSS: Remove id 2 BSSID dc:71:44:ae:fd:89 SSID 'UPC Wi-Free' due
to wpa_bss_flush
wlan0: BSS: Remove id 3 BSSID 5c:a3:9d:f0:b9:88 SSID 'UPC246501678' due
to wpa_bss_flush
wlan0: BSS: Remove id 4 BSSID 5c:a3:9d:88:3b:88 SSID 'UPC249534882' due
to wpa_bss_flush
wlan0: BSS: Remove id 5 BSSID 5c:a3:9d:f0:b9:89 SSID 'UPC Wi-Free' due
to wpa_bss_flush
wlan0: BSS: Remove id 6 BSSID 5c:a3:9d:e4:d8:b8 SSID 'UPC247695636' due
to wpa_bss_flush
wlan0: BSS: Remove id 7 BSSID 10:0d:7f:6d:90:62 SSID 'alinnet' due to
wpa_bss_flush
wlan0: BSS: Remove id 8 BSSID 28:be:9b:05:e0:01 SSID 'UPC0038059' due to
wpa_bss_flush
wlan0: BSS: Remove id 9 BSSID dc:71:44:af:9d:b8 SSID 'UPC249806521' due
to wpa_bss_flush
wlan0: BSS: Remove id 10 BSSID 88:1f:a1:35:7c:62 SSID 'Welcome' due to
wpa_bss_flush
wlan0: BSS: Remove id 11 BSSID f0:84:2f:d5:79:79 SSID 'cgz-98799' due to
wpa_bss_flush
wlan0: BSS: Remove id 12 BSSID 8e:04:ff:02:0e:8f SSID
'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00' due to
wpa_bss_flush
wlan0: BSS: Remove id 13 BSSID 2a:be:9b:05:e0:03 SSID 'UPC Wi-Free' due
to wpa_bss_flush
wlan0: BSS: Remove id 14 BSSID 8c:04:ff:02:0e:8e SSID 'UPC0046489' due
to wpa_bss_flush
wlan0: BSS: Remove id 15 BSSID 8e:04:ff:02:0e:80 SSID 'UPC Wi-Free' due
to wpa_bss_flush
wlan0: BSS: Remove id 16 BSSID f8:1a:67:56:42:96 SSID 'scoobly' due to
wpa_bss_flush
wlan0: Cancelling scan request
wlan0: Cancelling authentication timeout
Remove interface wlan0 from radio phy0
Remove radio phy0
netlink: Operstate: ifindex=4 linkmode=0 (kernel-control), operstate=6
(IF_OPER_UP)
wlan0: CTRL-EVENT-TERMINATING


-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 1908 bytes
Desc: S/MIME Cryptographic Signature
URL: <http://lists.shmoo.com/pipermail/hostap/attachments/20141231/d077474a/attachment-0001.bin>


More information about the HostAP mailing list