EAPOL Windows: Debug Logs with more Verbose Debug

Surendranath.C. surendranath.c
Thu Jan 1 21:53:22 PST 2009 

> Message: 4
> Date: Wed, 31 Dec 2008 15:13:15 +0200
> From: Jouni Malinen <j at w1.fi>
> Subject: Re: Logs of Win WPA Supplicant
> To: hostap at lists.shmoo.com
> Message-ID: <20081231131315.GB20701 at jm.kir.nu>
> Content-Type: text/plain; charset=us-ascii
> On Wed, Dec 31, 2008 at 04:01:57PM +0530, Surendranath.C. wrote:
> > When I fire using the WPA supplicant windows client I am getting an
error.
> The debug log did not seem to indicate any clear errors.. However, the
driver did not seem to associate. Could you
> please send me a debug log with more verbose debug (-ddK on the command
line)? Please note that the 'K' on the 
> command line will add passwords etc. to the log, so only do this with a
test setup without any secret passwords/keys.
> > 	scan_ssid=1	
> Windows driver interface does not support scan_ssid=1 so you will either
need to enable SSID broadcast in the AP 
> (which seemed to be already
> done) or use ap_scan=2.
> > 	ca_cert="/usr/local/etc/raddb/certs/ca.der"
> This is unlikely to be a correct path on Windows.
-- 
> Jouni Malinen

Dear Jouni Malinen,

The logs as requested by you are provided below.

E:\Free-Radius\WPA-Supplicant\wpa_supplicant-windows-bin-0.5.11\wpa_supplica
nt-w
indows-bin-0.5.11>wpa_supplicant.exe -iIntel(R) -c wpa.conf -ddK
CTRL: Open pipe 003F4030
CTRL: ConnectNamedPipe: connection in progress
Initializing interface 'Intel(R)' conf 'wpa.conf' driver 'default'
ctrl_interfac
e 'N/A' bridge 'N/A'
Reading configuration file 'wpa.conf'
Line: 1 - start of a new network block
ssid - hexdump_ascii(len=18):
     54 61 74 61 20 49 6e 64 69 63 6f 6d 20 57 69 2d   Tata Indicom Wi-
     46 69                                             Fi
key_mgmt: 0x8
eap methods - hexdump(len=16): 00 00 00 00 15 00 00 00 00 00 00 00 00 00 00
00 
identity - hexdump_ascii(len=3):
     62 6f 62                                          bob
password - hexdump_ascii(len=5):
     68 65 6c 6c 6f                                    hello
phase2 - hexdump_ascii(len=11):
     61 75 74 68 65 61 70 3d 4d 44 35                  autheap=MD5
Priority group 0 id=0 ssid='Tata Indicom Wi-Fi'
Initializing interface (2) 'Intel(R)'
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
NDIS: Packet.dll version: 4.0.0.1040
NDIS: 3 adapter names found
NDIS: 3 adapter descriptions found
NDIS: 0 - \Device\NPF_GenericDialupAdapter - Adapter for generic dialup and
VPN capture
NDIS: 1 - \Device\NPF_{50DB3A8A-3662-48F6-99A4-978BF26B2127} - Intel(R)
PRO/Wireless LAN 2100 3B Mini PCI Adapter (Microsoft's Packet Scheduler)
NDIS: 2 - \Device\NPF_{4B9A8702-AEF0-4A17-B4AF-C1A64DA008BC} - Intel(R)
PRO/1000 MT Mobile Connection (Microsoft's Packet Scheduler)
NDIS: Matched interface '\Device\NPF_{50DB3A8A-3662-48F6-99A4-978BF26B2127}'
based on description 'Intel(R) PRO/Wireless LAN 2100 3B Mini PCI Adapter
(Microsoft's Packet Scheduler) '
NDIS: Adapter description prefix 'Intel(R) PRO/Wireless LAN 2100 3B Mini PCI
Adapter'
NDIS: WZCEnumInterfaces: 1 interfaces
NDIS: intfs 0 GUID '{50DB3A8A-3662-48F6-99A4-978BF26B2127}'
NDIS: Current interface found from WZC
NDIS: WZC interface flags 0x8000 dwCtlFlags 0x8000
NDIS: Disabling WZC for this interface
NDIS: Disabled WZC temporarily for this interface
ndis_get_oid: oid=0xd010122 len (512) failed
NDIS: verifying driver WPA capability
NDIS: Set OID 0d010118 - hexdump(len=4): 03 00 00 00
NDIS: WPA key management supported
NDIS: Set OID 0d010118 - hexdump(len=4): 04 00 00 00
NDIS: WPA-PSK key management supported
NDIS: Set OID 0d01011b - hexdump(len=4): 06 00 00 00
ndis_set_oid: oid=0xd01011b len (4) failed
NDIS: Failed to set OID_802_11_ENCRYPTION_STATUS (6)
NDIS: Set OID 0d01011b - hexdump(len=4): 04 00 00 00
NDIS: Set OID 0d01011b - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d010118 - hexdump(len=4): 01 00 00 00
ndis_set_oid: oid=0xd010118 len (4) failed
NDIS: Failed to set OID_802_11_AUTHENTICATION_MODE (1)
NDIS: Set OID 0d010118 - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d01011b - hexdump(len=4): 01 00 00 00
NDIS: no WPA support found
NDIS: driver capabilities: key_mgmt 0x5 enc 0x0 auth 0x1
ndis_events: Connected to ROOT\CIMV2.
ndis_events: WMI: SELECT Index FROM Win32_NetworkAdapterConfiguration WHERE
SettingID='{50DB3A8A-3662-48F6-99A4-978BF26B2127}'
ndis_events: WMI: SELECT Name,PNPDeviceID FROM Win32_NetworkAdapter WHERE
Index=13
ndis_events: Win32_NetworkAdapter::Name='Intel(R) PRO/Wireless LAN 2100 3B
Mini PCI Adapter'
ndis_events:
Win32_NetworkAdapter::PNPDeviceID='PCI\VEN_8086&DEV_1043&SUBSYS_255
18086&REV_04\4&39A85202&0&10F0'
ndis_events: WMI: SELECT Name FROM Win32_PnPEntity WHERE
DeviceID='PCI\\VEN_8086
&DEV_1043&SUBSYS_25518086&REV_04\\4&39A85202&0&10F0'
ndis_events: Win32_PnPEntity::Name='Intel(R) PRO/Wireless LAN 2100 3B Mini
PCI Adapter'
ndis_events: use adapter descriptor 'Intel(R) PRO/Wireless LAN 2100 3B Mini
PCI Adapter'
Connected to ROOT\WMI.
ndis_events: WMI: SELECT * FROM MSNdis_StatusMediaConnect
ndis_events: WMI: SELECT * FROM MSNdis_StatusMediaDisconnect
ndis_events: WMI: SELECT * FROM MSNdis_StatusMediaSpecificIndication
ndis_events: WMI: SELECT * FROM MSNdis_NotifyAdapterArrival
ndis_events: WMI: SELECT * FROM MSNdis_NotifyAdapterRemoval
NDIS: Set OID 0d010108 - hexdump(len=4): 01 00 00 00
Driver interface replaced interface name with
'{50DB3A8A-3662-48F6-99A4-978BF26B2127}'
Own MAC address: 00:0c:f1:4e:71:e9
wpa_driver_ndis_set_wpa: enabled=1
ndis_get_oid: oid=0xd010101 len (6) failed
NDIS: Set OID 0d01011e - hexdump(len=16): 10 00 00 00 00 00 00 00 ff ff ff
ff ff ff 00 00
NDIS: Set OID 0d010114 - hexdump(len=4): 00 00 00 00
ndis_get_oid: oid=0xd010101 len (6) failed
NDIS: Set OID 0d01011e - hexdump(len=16): 10 00 00 00 01 00 00 00 ff ff ff
ff ff ff 00 00
NDIS: Set OID 0d010114 - hexdump(len=4): 01 00 00 00
ndis_get_oid: oid=0xd010101 len (6) failed
NDIS: Set OID 0d01011e - hexdump(len=16): 10 00 00 00 02 00 00 00 ff ff ff
ff ff ff 00 00
NDIS: Set OID 0d010114 - hexdump(len=4): 02 00 00 00
ndis_get_oid: oid=0xd010101 len (6) failed
NDIS: Set OID 0d01011e - hexdump(len=16): 10 00 00 00 03 00 00 00 ff ff ff
ff ff ff 00 00
NDIS: Set OID 0d010114 - hexdump(len=4): 03 00 00 00
Setting scan request: 0 sec 100000 usec
Added interface {50DB3A8A-3662-48F6-99A4-978BF26B2127}
ndis_get_oid: oid=0xd010101 len (6) failed
State: DISCONNECTED -> SCANNING
Starting AP scan (broadcast SSID)
Trying to get current scan results first without requesting a new scan to
speedup initial association
Scan results: 8
Selecting BSS from priority group 0
Try to find WPA-enabled AP
0: 00:21:29:da:59:c1 ssid='linksys' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
   skip - SSID mismatch
1: 00:90:cc:0f:8e:f1 ssid='UAT' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
2: 00:15:62:8d:5e:20 ssid='Tata Indicom Wi-Fi' wpa_ie_len=0 rsn_ie_len=0
caps=0x11
skip - no WPA/RSN IE
3: 00:13:49:3d:45:55 ssid='DGP Wi-Fi Zone' wpa_ie_len=24 rsn_ie_len=0
caps=0x11
   skip - SSID mismatch
4: 00:14:bf:de:d5:c0 ssid='New Router' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
   skip - no WPA/RSN IE
5: 00:50:e8:02:31:02 ssid='Nomadix' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
6: 02:19:d2:00:0b:0e ssid='Free Public WiFi' wpa_ie_len=0 rsn_ie_len=0
caps=0x2
   skip - no WPA/RSN IE
7: 00:1e:e5:25:d2:54 ssid='bajajmumbai_2' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
Try to find non-WPA AP
0: 00:21:29:da:59:c1 ssid='linksys' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
   skip - SSID mismatch
1: 00:90:cc:0f:8e:f1 ssid='UAT' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - SSID mismatch
2: 00:15:62:8d:5e:20 ssid='Tata Indicom Wi-Fi' wpa_ie_len=0 rsn_ie_len=0
caps=0x11
selected non-WPA AP 00:15:62:8d:5e:20 ssid='Tata Indicom Wi-Fi'
Trying to associate with 00:15:62:8d:5e:20 (SSID='Tata Indicom Wi-Fi'
freq=2422MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
WPA: clearing AP WPA IE
WPA: clearing AP RSN IE
WPA: clearing own WPA/RSN IE
No keys have been configured - skip key clearing
State: SCANNING -> ASSOCIATING
NDIS: Set OID 0d010108 - hexdump(len=4): 01 00 00 00
NDIS: Set OID 0d010119 - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d010118 - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d01011b - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d010101 - hexdump(len=6): 00 15 62 8d 5e 20
NDIS: Set OID 0d010102 - hexdump(len=36): 12 00 00 00 54 61 74 61 20 49 6e
64 69 63 6f 6d 20 57 69 2d 46 69 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - portControl=Auto
Authentication with 00:15:62:8d:5e:20 timed out.
Added BSSID 00:15:62:8d:5e:20 into blacklist
No keys have been configured - skip key clearing
State: ASSOCIATING -> DISCONNECTED
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
Setting scan request: 0 sec 0 usec
State: DISCONNECTED -> SCANNING
Starting AP scan (broadcast SSID)
NDIS: Set OID 0d01011a - hexdump(len=4): 20 20 20 20
Scan timeout - try to get results
Scan results: 9
Selecting BSS from priority group 0
Try to find WPA-enabled AP
0: 00:21:29:da:59:c1 ssid='linksys' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
   skip - SSID mismatch
1: 00:90:cc:0f:8e:f1 ssid='UAT' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
2: 00:15:62:8d:5e:20 ssid='Tata Indicom Wi-Fi' wpa_ie_len=0 rsn_ie_len=0
caps=0x11
skip - no WPA/RSN IE
3: 00:13:49:3d:45:55 ssid='DGP Wi-Fi Zone' wpa_ie_len=24 rsn_ie_len=0
caps=0x11
   skip - SSID mismatch
4: 00:14:bf:de:d5:c0 ssid='New Router' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
   skip - no WPA/RSN IE
5: 00:50:e8:02:31:02 ssid='Nomadix' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
6: 02:19:d2:00:0b:0e ssid='Free Public WiFi' wpa_ie_len=0 rsn_ie_len=0
caps=0x2
   skip - no WPA/RSN IE
7: 00:1e:e5:25:d2:54 ssid='bajajmumbai_2' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
8: 00:17:5a:b7:b9:80 ssid='DGP 1st Flr' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
Try to find non-WPA AP
0: 00:21:29:da:59:c1 ssid='linksys' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
   skip - SSID mismatch
1: 00:90:cc:0f:8e:f1 ssid='UAT' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - SSID mismatch
2: 00:15:62:8d:5e:20 ssid='Tata Indicom Wi-Fi' wpa_ie_len=0 rsn_ie_len=0
caps=0x11
selected non-WPA AP 00:15:62:8d:5e:20 ssid='Tata Indicom Wi-Fi'
Trying to associate with 00:15:62:8d:5e:20 (SSID='Tata Indicom Wi-Fi'
freq=2422 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
WPA: clearing AP WPA IE
WPA: clearing AP RSN IE
WPA: clearing own WPA/RSN IE
No keys have been configured - skip key clearing
State: SCANNING -> ASSOCIATING
NDIS: Set OID 0d010108 - hexdump(len=4): 01 00 00 00
NDIS: Set OID 0d010119 - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d010118 - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d01011b - hexdump(len=4): 00 00 00 00
NDIS: Set OID 0d010101 - hexdump(len=6): 00 15 62 8d 5e 20
NDIS: Set OID 0d010102 - hexdump(len=36): 12 00 00 00 54 61 74 61 20 49 6e
64 69
 63 6f 6d 20 57 69 2d 46 69 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - portControl=Auto
CTRL-EVENT-TERMINATING - signal 0 received
Removing interface {50DB3A8A-3662-48F6-99A4-978BF26B2127}
State: ASSOCIATING -> DISCONNECTED
No keys have been configured - skip key clearing
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
wpa_driver_ndis_set_wpa: enabled=0
No keys have been configured - skip key clearing
Removed BSSID 00:15:62:8d:5e:20 from blacklist (clear)
Cancelling scan request
Cancelling authentication timeout
NDIS: Set OID 0d010102 - hexdump(len=36): 20 00 00 00 29 23 be 84 e1 6c d6
ae 52
 90 49 f1 f1 bb e9 eb b3 a6 db 3c 87 0c 3e 99 24 5e 0d 1c 06 b7 47 de
ndis_events: terminated
NDIS: Set OID 0d010115 - hexdump(len=4): 20 20 20 20
NDIS: WZCEnumInterfaces: 1 interfaces
NDIS: intfs 0 GUID '{50DB3A8A-3662-48F6-99A4-978BF26B2127}'
NDIS: Current interface found from WZC
NDIS: WZC interface flags 0x8000 dwCtlFlags 0x0
NDIS: Enabling WZC for this interface
NDIS: Re-enabled WZC for this interface
CTRL: close pipe 003F4030

--
Surendranath. C.

More information about the Hostap mailing list