[ Re: eap-ttls failing]
Josh Hiner
josh at remc1.org
Tue Jan 27 19:53:06 CET 2009
tnt at kalik.net wrote:
>> Ok, made new client cert and now it shows valid and displays "Provides
>> your identity to a remote Computer" as the intended purpose and on the
>> Details tab displays the correct info etc... The Certification Path
>> displays valid. Still same problem though (exact same problem) of just
>> sitting there at "Attempting to authenticate".
>>
>>
>
> Have you deleted the old certificate from the certificate store? What
> does eapol.log say now.
>
> Ivan Kalik
> Kalik Informatika ISP
>
> -
> List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
>
>
Yep, I have deleted the old certificate =D.
Here is what epol.log says. I see the part where the card goes into
authenticate mode over and over again but I dont see a critical error.
Search for this line where authentication just starts over and over?
This appears on all laptops with different wireless cards but all use
windows xp to setup eap-tls:
[1712] 13:41:51:028: ProcessReceivedPacket: EAPOLSTATE_AUTHENTICATING
There is an error about updating a registry key or something but that
error should be normal as I've seen it on many machines that are able to
connect via peap and ttls. Notice at the end it reports a disconnect,
that is me finally clicking disconnect to stop the authentication loop.
<start of log>
[1208] 13:41:01:687: ElSessionChangeHandler: CONNECT for session = (0)
[1224] 13:41:01:687: ElUserLogonCallback: UserloggedOn = 0
[1224] 13:41:01:777: ElCheckUserModuleReady: No user logged on
[1224] 13:41:01:787: ElEapEnd entered
[1224] 13:41:01:787: FSMLogoff entered for port Intel(R) PRO/100 VE
Network Connection #2 - Packet Scheduler Miniport
[1224] 13:41:01:787: ElEapEnd entered
[1224] 13:41:01:787: Setting state LOGOFF for port Intel(R) PRO/100 VE
Network Connection #2 - Packet Scheduler Miniport
[1224] 13:41:01:787: FSMLogoff completed for port Intel(R) PRO/100 VE
Network Connection #2 - Packet Scheduler Miniport
[1224] 13:41:01:787: ElReStartPort: Entered: pPCB=<000DEBE0>,
pZCConnectInfo=<00000000>, Refcnt=<2>, EapFlags=<c0000000>, Notification=<a>
[1224] 13:41:01:787: ElRestartPortLocked: Entered: pPCB=<000DEBE0>,
pZCConnectInfo=<00000000>, Refcnt=<2>, EapFlags=<c0000000>, Notification=<a>
[1224] 13:41:01:787: ElGetInterfaceNdisStatistics:
pwszDeviceInterfaceName = (\Device\{3C90646A-0A9A-43B5-BFCC-7FE335265032})
[1224] 13:41:01:787: ElRestartPortLocked: Resetting Credentials for
Intel(R) PRO/100 VE Network Connection #2 - Packet Scheduler Miniport
[1224] 13:41:01:787: ElReadPerPortRegistryParams: Setting
dwEapFlags=<c0000000>
[1224] 13:41:01:797: ElReadPerPortRegistryParams:
dwTotalMaxAuthFailCount = (3)
[1224] 13:41:01:797: DisconnectedPCB: Media is already disconnected
<<IGNORE>>
[1224] 13:41:01:797: ElUserLogonCallback: completed with error 0
[1208] 13:41:02:699: ElSessionChangeHandler: LOGON for session = (0)
[1504] 13:41:02:709: ElUserLogonCallback: UserloggedOn = 1
[1504] 13:41:02:709: ElUserLogonCallback: User logon already detected,
returning without processing
[1504] 13:41:02:709: ElUserLogonCallback: completed with error 0
[3828] 13:41:25:892: EAPOLQueryGUIDNCSState: For Port Intel(R) PRO/100
VE Network Connection #2 - Packet Scheduler Miniport returning S_FALSE
[3828] 13:41:25:952: EAPOLQueryGUIDNCSState: 802.1X Port Inexistant for
{FD7F1225-FD60-480C-8857-1DF3ECBC9C18}. Returning S_OK
[3828] 13:41:28:926: EAPOLTrayIconReady: Advise username = PINKY\jdhiner
[1504] 13:41:28:937: EAPOLTrayIconReadyWorker: Advise username =
PINKY\jdhiner
[1504] 13:41:29:938: ElGetLoggedOnUserName: Got User Name PINKY\jdhiner
[1504] 13:41:29:938: EAPOLTrayIconReadyWorker: Tray icon ready for
username PINKY\jdhiner
[1180] 13:41:35:887: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1180] 13:41:35:887: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1180] 13:41:35:887: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1180] 13:41:35:887: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<25>,
Offset=<52/106>, dwAuthData=<0>
[1548] 13:41:35:897: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1180] 13:41:35:897: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1180] 13:41:35:897: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1180] 13:41:35:897: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<25>,
Offset=<52/106>, dwAuthData=<0>
[1180] 13:41:35:897: ElGetInterfaceParams: SsidLength=<13>, Found
EapTypeId=<13>, SSIDLen=<13>
[1548] 13:41:35:897: ElGetCustomAuthData: SSIDLen=<13>, EapTypeId=<13>,
Offset=<52/104>, dwAuthData=<40>
[1548] 13:41:35:897: ElGetCustomAuthData: SSIDLen=<13>, EapTypeId=<13>,
Offset=<52/104>, dwAuthData=<40>
[1548] 13:41:35:897: ElGetCustomAuthData: SSIDLen=<13>, EapTypeId=<25>,
Offset=<52/104>, dwAuthData=<0>
[1180] 13:41:43:598: ElSetCustomAuthData: EapTypeId=<13>,
SSIDLength=<11>, InfoLength=<42>
[1180] 13:41:43:598: ElSetCustomAuthData: Set value succeeded,
fFound=<1>, dwEapBlob=<106>, dwSizeOfSSID=<11>
[1180] 13:41:43:598: ElSetCustomAuthData: EapTypeId=<25>,
SSIDLength=<11>, InfoLength=<0>
[1180] 13:41:43:598: ElSetCustomAuthData: Set value succeeded,
fFound=<1>, dwEapBlob=<106>, dwSizeOfSSID=<11>
[1180] 13:41:43:598: ElSetInterfaceParams: EapTypeId=<13> SSIDLen=<11>
[1180] 13:41:43:598: Setting stuff in registry for
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1180] 13:41:43:598: ElSetInterfaceParams: Succeeded, EapTypeId=<13>
fFound=<1>, SSIDLen=<11>
[1180] 13:41:43:598: ElPostEapConfigChanged: SSIDLength=<11>
[1548] 13:41:43:598: ElWZCCfgUpdateSettings: Error in RegOpenKeyEx for
base key, 2
[1548] 13:41:43:598: ElWZCCfgChangeHandler: ElWZCCfgUpdateSettings HKCU
failed with error (2)
[1504] 13:41:43:608: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1504] 13:41:43:608: ElProcessEapConfigChange: PCB not started, enabled,
starting PCB
[1504] 13:41:43:608: ElZeroConfigNotify: Handle=(0), failcount=(0),
lastauthtype=(0)
[1504] 13:41:46:702: ElEnumAndOpenInterfaces: DeviceDesc = , GUID =
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:46:702: ElNdisuioEnumerateInterfaces: Opening handle
[1504] 13:41:46:702: NdisuioEnumerateInterfaces: NDISUIO bound to: (0)
\DEVICE\{3C90646A-0A9A-43B5-BFCC-7FE335265032}
- Intel(R) PRO/100 VE Network Connection #2 - Packet Scheduler Miniport
[1504] 13:41:46:702: NdisuioEnumerateInterfaces: NDISUIO bound to: (1)
\DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
- Wireless-G Notebook Adapter with RangeBooster - Packet Scheduler
Miniport
[1504] 13:41:46:702: ElNdisuioEnumerateInterfaces: DeviceIoControl
IOCTL_NDISUIO_QUERY_BINDING has no more entries
[1504] 13:41:46:702: Device: \DEVICE\{3C90646A-0A9A-43B5-BFCC-7FE335265032}
[1504] 13:41:46:702: Description: Intel(R) PRO/100 VE Network Connection
#2 - Packet Scheduler Miniport
[1504] 13:41:46:702: Device: \DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:46:702: Description: Wireless-G Notebook Adapter with
RangeBooster - Packet Scheduler Miniport
[1504] 13:41:46:702: ElEnumAndOpenInterfaces: Found interface after
enumeration \DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:46:702: ElEnumAndOpenInterfaces: Did NOT find PCB already
existing for interface
[1504] 13:41:46:702: ElOpenInterfaceHandle: Opening handle for
\DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:46:702: ElOpenInterfaceHandle: Trying to access NDIS
Device: \DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:46:702: ElOpenInterfaceHandle:
OpenIntfHandle(\DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}) = 1243504
[1504] 13:41:46:702: ElOpenInterfaceHandle: Opened handle 0012F970 with
dwRetCode 0
[1504] 13:41:46:702: ElCreatePort: Entered for Handle=(0012F970),
GUID=({B123A337-9DAB-45CD-B148-7A2E8A53AAED}), Name=(Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport), ZCId=(0),
UserData=(00000000) Notification=<3>
[1504] 13:41:46:702: ElCreatePort: No PCB found for
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:46:702: ElGetInterfaceNdisStatistics:
pwszDeviceInterfaceName = (\Device\{B123A337-9DAB-45CD-B148-7A2E8A53AAED})
[1504] 13:41:46:702: ElCreatePort: Ignoring WLAN indications for
pZCConnectInfo == NULL
[1504] 13:41:46:702: ElEnumAndOpenInterfaces: Error in CreatePort = 13
[1504] 13:41:46:702:
ElCloseInterfaceHandle(0x12f970,{B123A337-9DAB-45CD-B148-7A2E8A53AAED})
entered
[1504] 13:41:46:702: ElEnumAndOpenInterfaces: Completed with retcode = 0
[1504] 13:41:46:702: ElProcessEapConfigChange: Finished with error 0
[1712] 13:41:49:947: EAPOLQueryGUIDNCSState: 802.1X Port Inexistant for
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}. Returning S_OK
[1504] 13:41:49:957: ElMediaEventsHandler entered -- EventType=<7>
[1504] 13:41:49:957: ElMediaEventsHandler: Calling ElZeroConfigEvent
[1504] 13:41:49:957: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1504] 13:41:49:957: ElEnumAndOpenInterfaces: DeviceDesc = , GUID =
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:49:957: ElNdisuioEnumerateInterfaces: Opening handle
[1504] 13:41:49:957: NdisuioEnumerateInterfaces: NDISUIO bound to: (0)
\DEVICE\{3C90646A-0A9A-43B5-BFCC-7FE335265032}
- Intel(R) PRO/100 VE Network Connection #2 - Packet Scheduler Miniport
[1504] 13:41:49:957: NdisuioEnumerateInterfaces: NDISUIO bound to: (1)
\DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
- Wireless-G Notebook Adapter with RangeBooster - Packet Scheduler
Miniport
[1504] 13:41:49:957: ElNdisuioEnumerateInterfaces: DeviceIoControl
IOCTL_NDISUIO_QUERY_BINDING has no more entries
[1504] 13:41:49:957: Device: \DEVICE\{3C90646A-0A9A-43B5-BFCC-7FE335265032}
[1504] 13:41:49:957: Description: Intel(R) PRO/100 VE Network Connection
#2 - Packet Scheduler Miniport
[1504] 13:41:49:957: Device: \DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:49:957: Description: Wireless-G Notebook Adapter with
RangeBooster - Packet Scheduler Miniport
[1504] 13:41:49:957: ElEnumAndOpenInterfaces: Found interface after
enumeration \DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:49:957: ElEnumAndOpenInterfaces: Did NOT find PCB already
existing for interface
[1504] 13:41:49:957: ElOpenInterfaceHandle: Opening handle for
\DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:49:957: ElOpenInterfaceHandle: Trying to access NDIS
Device: \DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:49:957: ElOpenInterfaceHandle:
OpenIntfHandle(\DEVICE\{B123A337-9DAB-45CD-B148-7A2E8A53AAED}) = 1243504
[1504] 13:41:49:957: ElOpenInterfaceHandle: Opened handle 0012F970 with
dwRetCode 0
[1504] 13:41:49:957: ElCreatePort: Entered for Handle=(0012F970),
GUID=({B123A337-9DAB-45CD-B148-7A2E8A53AAED}), Name=(Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport), ZCId=(127),
UserData=(0299BB88) Notification=<4>
[1504] 13:41:49:957: ElCreatePort: No PCB found for
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:49:957: ElGetInterfaceNdisStatistics:
pwszDeviceInterfaceName = (\Device\{B123A337-9DAB-45CD-B148-7A2E8A53AAED})
[1504] 13:41:49:977: ElCreatePort: ElNdisuioQueryOIDValue for
OID_802_3_CURRENT_ADDRESS successful
[1504] 13:41:49:977: 00 16 B6 5C AC DD 00 00 00 00 00 00 00 00 00 00
|...\............|
[1504] 13:41:49:977: ElCreatePort: ElNdisuioQueryOIDValue for
OID_802_11_INFRASTRUCTURE_MODE successful, Mode = (1)
[1504] 13:41:49:977: ElCreatePort: ElNdisuioQueryOIDValue for
OID_802_11_BSSID successful
[1504] 13:41:49:977: 00 1F 41 3A 82 F9 00 00 00 00 00 00 00 00 00 00
|..A:............|
[1504] 13:41:49:977: ElCreatePort: prdUserData not valid
[1504] 13:41:49:977: ElCreatePort: Port Network Identifier:
[1504] 13:41:49:977: 43 43 49 53 44 2D 52 45 4D 43 31 00 00 00 00 00
|CCISD-REMC1.....|
[1504] 13:41:49:977: ElCreatePort: pPCB=<000E7E38>,
AuthenticationMode=<3>, EncyptionStatus=<6>, usKeyDescription=<0>
[1504] 13:41:49:977: TIMER: Create PCB Time: 2097148
[1504] 13:41:49:977: ElInitRegPortData: ElGetCustomAuthData returned
error 603
[1504] 13:41:49:977: ElInitRegPortData: completed with error 0
[1504] 13:41:49:977: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1504] 13:41:49:977: ElReadPerPortRegistryParams: Setting
dwEapFlags=<80000000>
[1504] 13:41:49:977: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1504] 13:41:49:977: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1504] 13:41:49:977: ElReadPerPortRegistryParams:
dwTotalMaxAuthFailCount = (3)
[1504] 13:41:49:977: TIMER: Create PMKCACHE Time: 2097148
[1504] 13:41:49:977: ElQueryBinaryOID(Wireless-G Notebook Adapter with
RangeBooster - Packet Scheduler Miniport, 0xd010122)
[1504] 13:41:49:977: Device Wireless-G Notebook Adapter with
RangeBooster - Packet Scheduler Miniport supports 4 PMKIDs in its cache
[1504] 13:41:49:977: TIMER: Create PREAUTH Time: 2097148
[1712] 13:41:49:977: EAPOLQueryGUIDNCSState: For Port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport returning
S_FALSE
[1504] 13:41:49:977: ElReadFromPort entered, pPCB = 000E7E38
[1504] 13:41:49:977: ElReadFromPort: pPCB = 000E7E38, RefCnt = 3
[1504] 13:41:49:977: FSMConnecting entered for port Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:027: Current Mode is NOT WPA2. PreAuth disabled
[1504] 13:41:50:047: TIMER: Restart PCB Time: 60
[1504] 13:41:50:047: Sending EAPOL-Start frame on the interface
Wireless-G Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:047: ElWriteToPort entered: Pkt Length = 7
[1504] 13:41:50:047: ElWriteToPort: pPCB = 000E7E38, RefCnt = 5
[1504] 13:41:50:047: ElWriteToInterface entered
[1504] 13:41:50:047: ElWriteToInterface completed, RetCode = 0
[1504] 13:41:50:047: Setting state CONNECTING for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:047: FSMConnecting completed for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:50:047: EAPOLQueryGUIDNCSState: For Port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport returning
S_FALSE
[1504] 13:41:50:047: ElCreatePort: Completed for GUID=
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}, Name = Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:047: ElEnumAndOpenInterfaces: CreatePort successful
[1504] 13:41:50:047: ElCreateInterfaceEntry: Added to hash table GUID=
{B123A337-9DAB-45CD-B148-7A2E8A53AAED} : Desc= Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport at Index=0
[1504] 13:41:50:047: ElEnumAndOpenInterfaces: Completed with retcode = 0
[1504] 13:41:50:047: ElWriteCompletionRoutine sent out 19 bytes with error 0
[1504] 13:41:50:047: ElWriteCompletionRoutine: pPCB= 000E7E38, RefCnt = 3
[1504] 13:41:50:047: EapolReceiveDataPacket: receive 55 bytes
[1504] 13:41:50:047: ProcessReceivedPacket entered, length = 51
[1504] 13:41:50:047: ProcessReceivedPacket: EAP_Packet
[1504] 13:41:50:047: ProcessReceivedPacket: EAPOLSTATE_CONNECTING
[1504] 13:41:50:047: TIMER: Restart PCB Time: 2097148
[1504] 13:41:50:047: FSMAcquired entered for port Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:047: TIMER: Restart PCB Time: 30
[1504] 13:41:50:047: ElEapEnd entered
[1504] 13:41:50:047: ElEapBegin entered
[1504] 13:41:50:047: ElEapBegin done
[1504] 13:41:50:047: ElEapWork: EapolPkt created at 000E3E40
[1504] 13:41:50:047: ElEapMakeMessage entered
[1504] 13:41:50:047: ElParseIdentityString: DisplayString = Please enter
your login name
[1504] 13:41:50:047: ElParseIdentityString: LocalIdString =
[1504] 13:41:50:047: ElParseIdentityString: LocalIdString Length = 28
[1504] 13:41:50:047: ElParseIdentityString: Identity string does not
contain tuples
[1504] 13:41:50:047: ElGetIdentity: Userlogged, Prev !Machine auth
[1504] 13:41:50:047: ElGetIdentity: Userlogged, <Maxauth, Prev !Machine
auth: !MD5
[1504] 13:41:50:047: ElGetUserIdentity entered
[1504] 13:41:50:057: ElGetEapUserInfo: Error in RegOpenKeyEx for base key, 2
[1504] 13:41:50:057: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1504] 13:41:50:057: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1504] 13:41:50:518: ElGetUserIdentityOptimized: Got identity = josh
[1504] 13:41:50:518: ElGetUserIdentity: ElGetUserIdentityOptimized got
identity without user module intervention
[1504] 13:41:50:518: ElGetUserIdentity completed with error 0
[1504] 13:41:50:518: ElGetIdentity: Userlogged, <Maxauth, Prev !Machine
auth: No Error: User Auth fine
[1504] 13:41:50:518: Identity sent out = josh
[1504] 13:41:50:518: ElWriteToPort entered: Pkt Length = 15
[1504] 13:41:50:518: ElWriteToPort: pPCB = 000E7E38, RefCnt = 4
[1504] 13:41:50:518: ElWriteToInterface entered
[1504] 13:41:50:518: ElWriteToInterface completed, RetCode = 0
[1504] 13:41:50:518: Authentication Started on port Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport using User credentials
[1504] 13:41:50:518: Setting state ACQUIRED for port Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:528: FSMAcquired completed for port Wireless-G Notebook
Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:528: ProcessReceivedPacket: Reposting buffer on port
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:50:528: ElReadFromPort entered, pPCB = 000E7E38
[1504] 13:41:50:528: ElReadFromPort: pPCB = 000E7E38, RefCnt = 5
[1712] 13:41:50:528: EAPOLQueryGUIDNCSState: For Port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport returning 12
[1504] 13:41:50:528: ProcessReceivedPacket: pPCB= 000E7E38, RefCnt = 4
[1504] 13:41:50:528: ProcessReceivedPacket exit
[1504] 13:41:50:528: ElWriteCompletionRoutine sent out 27 bytes with error 0
[1504] 13:41:50:528: ElWriteCompletionRoutine: pPCB= 000E7E38, RefCnt = 3
[1504] 13:41:50:528: EapolReceiveDataPacket: receive 28 bytes
[1504] 13:41:50:528: ProcessReceivedPacket entered, length = 24
[1504] 13:41:50:528: ProcessReceivedPacket: EAP_Packet
[1504] 13:41:50:528: ProcessReceivedPacket: EAPOLSTATE_ACQUIRED
[1504] 13:41:50:528: TIMER: Restart PCB Time: 2097148
[1504] 13:41:50:528: FSMAuthenticating entered for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:528: TIMER: Restart PCB Time: 30
[1504] 13:41:50:528: ElEapWork: EapolPkt created at 000E3E40
[1504] 13:41:50:528: ElEapMakeMessage entered
[1504] 13:41:50:528: ElMakeSupplicantMessage entered
[1504] 13:41:50:528: EAPSTATE_Initial
[1504] 13:41:50:528: ElEapDllBegin called for EAP Type 13
[1504] 13:41:50:528: ElEapDllBegin: Not Setting GUEST flag
[1504] 13:41:50:528: EAPSTATE_Working
[1504] 13:41:50:528: ElEapDllWork called for EAP Type 13
[1504] 13:41:50:958: EAP Dll returned Action=EAPACTION_Send
[1504] 13:41:50:958: ElEapDllWork finished for EAP Type 13 with error 0
[1504] 13:41:50:958: ElWriteToPort entered: Pkt Length = 86
[1504] 13:41:50:958: ElWriteToPort: pPCB = 000E7E38, RefCnt = 3
[1504] 13:41:50:958: ElWriteToInterface entered
[1504] 13:41:50:958: ElWriteToInterface completed, RetCode = 0
[1504] 13:41:50:958: Setting state AUTHENTICATING for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:958: WZCNetmanConnectionStatusChanged: Entered
[1504] 13:41:50:958: QueueEvent: CoCreateInstance succeeded
[1504] 13:41:50:958: ConnectionStatusChanged completed
[1504] 13:41:50:958: FSMAuthenticating completed for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:41:50:958: ProcessReceivedPacket: Reposting buffer on port
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:41:50:958: ElReadFromPort entered, pPCB = 000E7E38
[1504] 13:41:50:958: ElReadFromPort: pPCB = 000E7E38, RefCnt = 4
[1504] 13:41:50:958: ProcessReceivedPacket: pPCB= 000E7E38, RefCnt = 4
[1504] 13:41:50:958: ProcessReceivedPacket exit
[1504] 13:41:50:958: ElWriteCompletionRoutine sent out 98 bytes with error 0
[1504] 13:41:50:958: ElWriteCompletionRoutine: pPCB= 000E7E38, RefCnt = 3
[1504] 13:41:50:968: EAPOLQueryGUIDNCSState: For Port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport returning 8
[1712] 13:41:50:988: EapolReceiveDataPacket: receive 1046 bytes
[1712] 13:41:51:018: ProcessReceivedPacket entered, length = 1042
[1712] 13:41:51:018: ProcessReceivedPacket: EAP_Packet
[1712] 13:41:51:018: ProcessReceivedPacket: EAPOLSTATE_AUTHENTICATING
[1712] 13:41:51:018: TIMER: Restart PCB Time: 2097148
[1712] 13:41:51:018: FSMAuthenticating entered for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:018: TIMER: Restart PCB Time: 30
[1712] 13:41:51:018: ElEapWork: EapolPkt created at 000E3E40
[1712] 13:41:51:018: ElEapMakeMessage entered
[1712] 13:41:51:018: ElMakeSupplicantMessage entered
[1712] 13:41:51:018: EAPSTATE_Working
[1712] 13:41:51:018: ElEapDllWork called for EAP Type 13
[1712] 13:41:51:018: EAP Dll returned Action=EAPACTION_Send
[1712] 13:41:51:018: ElEapDllWork finished for EAP Type 13 with error 0
[1712] 13:41:51:018: ElWriteToPort entered: Pkt Length = 12
[1712] 13:41:51:018: ElWriteToPort: pPCB = 000E7E38, RefCnt = 3
[1712] 13:41:51:018: ElWriteToInterface entered
[1712] 13:41:51:018: ElWriteToInterface completed, RetCode = 0
[1712] 13:41:51:018: Setting state AUTHENTICATING for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:018: FSMAuthenticating completed for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:018: ProcessReceivedPacket: Reposting buffer on port
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1712] 13:41:51:018: ElReadFromPort entered, pPCB = 000E7E38
[1712] 13:41:51:018: ElReadFromPort: pPCB = 000E7E38, RefCnt = 4
[1712] 13:41:51:018: ProcessReceivedPacket: pPCB= 000E7E38, RefCnt = 4
[1712] 13:41:51:018: ProcessReceivedPacket exit
[1712] 13:41:51:018: ElWriteCompletionRoutine sent out 24 bytes with error 0
[1712] 13:41:51:018: ElWriteCompletionRoutine: pPCB= 000E7E38, RefCnt = 3
[1712] 13:41:51:028: EapolReceiveDataPacket: receive 1046 bytes
[1712] 13:41:51:028: ProcessReceivedPacket entered, length = 1042
[1712] 13:41:51:028: ProcessReceivedPacket: EAP_Packet
[1712] 13:41:51:028: ProcessReceivedPacket: EAPOLSTATE_AUTHENTICATING
[1712] 13:41:51:028: TIMER: Restart PCB Time: 2097148
[1712] 13:41:51:028: FSMAuthenticating entered for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:028: TIMER: Restart PCB Time: 30
[1712] 13:41:51:028: ElEapWork: EapolPkt created at 00184500
[1712] 13:41:51:028: ElEapMakeMessage entered
[1712] 13:41:51:028: ElMakeSupplicantMessage entered
[1712] 13:41:51:028: EAPSTATE_Working
[1712] 13:41:51:028: ElEapDllWork called for EAP Type 13
[1712] 13:41:51:028: EAP Dll returned Action=EAPACTION_Send
[1712] 13:41:51:028: ElEapDllWork finished for EAP Type 13 with error 0
[1712] 13:41:51:028: ElWriteToPort entered: Pkt Length = 12
[1712] 13:41:51:028: ElWriteToPort: pPCB = 000E7E38, RefCnt = 3
[1712] 13:41:51:028: ElWriteToInterface entered
[1712] 13:41:51:028: ElWriteToInterface completed, RetCode = 0
[1712] 13:41:51:028: Setting state AUTHENTICATING for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:028: FSMAuthenticating completed for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:028: ProcessReceivedPacket: Reposting buffer on port
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1712] 13:41:51:028: ElReadFromPort entered, pPCB = 000E7E38
[1712] 13:41:51:028: ElReadFromPort: pPCB = 000E7E38, RefCnt = 4
[1712] 13:41:51:028: ProcessReceivedPacket: pPCB= 000E7E38, RefCnt = 4
[1712] 13:41:51:028: ProcessReceivedPacket exit
[1712] 13:41:51:028: ElWriteCompletionRoutine sent out 24 bytes with error 0
[1712] 13:41:51:028: ElWriteCompletionRoutine: pPCB= 000E7E38, RefCnt = 3
[1712] 13:41:51:038: EapolReceiveDataPacket: receive 342 bytes
[1712] 13:41:51:038: ProcessReceivedPacket entered, length = 338
[1712] 13:41:51:038: ProcessReceivedPacket: EAP_Packet
[1712] 13:41:51:038: ProcessReceivedPacket: EAPOLSTATE_AUTHENTICATING
[1712] 13:41:51:038: TIMER: Restart PCB Time: 2097148
[1712] 13:41:51:038: FSMAuthenticating entered for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:038: TIMER: Restart PCB Time: 30
[1712] 13:41:51:038: ElEapWork: EapolPkt created at 000E3E40
[1712] 13:41:51:038: ElEapMakeMessage entered
[1712] 13:41:51:038: ElMakeSupplicantMessage entered
[1712] 13:41:51:038: EAPSTATE_Working
[1712] 13:41:51:038: ElEapDllWork called for EAP Type 13
[1712] 13:41:51:098: EAP Dll returned Action=EAPACTION_Send
[1712] 13:41:51:098: ElEapDllWork finished for EAP Type 13 with error 0
[1712] 13:41:51:098: ElWriteToPort entered: Pkt Length = 1496
[1712] 13:41:51:098: ElWriteToPort: pPCB = 000E7E38, RefCnt = 3
[1712] 13:41:51:098: ElWriteToInterface entered
[1712] 13:41:51:098: ElWriteToInterface completed, RetCode = 0
[1712] 13:41:51:098: Setting state AUTHENTICATING for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:098: FSMAuthenticating completed for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1712] 13:41:51:098: ProcessReceivedPacket: Reposting buffer on port
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1712] 13:41:51:098: ElReadFromPort entered, pPCB = 000E7E38
[1712] 13:41:51:098: ElReadFromPort: pPCB = 000E7E38, RefCnt = 4
[1712] 13:41:51:098: ProcessReceivedPacket: pPCB= 000E7E38, RefCnt = 4
[1712] 13:41:51:098: ProcessReceivedPacket exit
[1712] 13:41:51:098: ElWriteCompletionRoutine sent out 1508 bytes with
error 0
[1712] 13:41:51:098: ElWriteCompletionRoutine: pPCB= 000E7E38, RefCnt = 3
[1712] 13:41:53:352: EAPOLQueryGUIDNCSState: For Port Intel(R) PRO/100
VE Network Connection #2 - Packet Scheduler Miniport returning S_FALSE
[1712] 13:41:53:382: EAPOLQueryGUIDNCSState: 802.1X Port Inexistant for
{FD7F1225-FD60-480C-8857-1DF3ECBC9C18}. Returning S_OK
[1712] 13:41:53:412: EAPOLQueryGUIDNCSState: For Port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport returning 8
[1180] 13:42:16:405: ElSetCustomAuthData: EapTypeId=<13>,
SSIDLength=<11>, InfoLength=<42>
[1180] 13:42:16:405: ElSetCustomAuthData: Set value succeeded,
fFound=<1>, dwEapBlob=<106>, dwSizeOfSSID=<11>
[1180] 13:42:16:405: ElSetCustomAuthData: EapTypeId=<25>,
SSIDLength=<11>, InfoLength=<0>
[1180] 13:42:16:415: ElSetCustomAuthData: Set value succeeded,
fFound=<1>, dwEapBlob=<106>, dwSizeOfSSID=<11>
[1180] 13:42:16:415: ElSetInterfaceParams: EapTypeId=<13> SSIDLen=<11>
[1180] 13:42:16:415: Setting stuff in registry for
{B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1180] 13:42:16:415: ElSetInterfaceParams: Succeeded, EapTypeId=<13>
fFound=<1>, SSIDLen=<11>
[1180] 13:42:16:415: ElPostEapConfigChanged: SSIDLength=<11>
[2192] 13:42:16:415: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[2192] 13:42:16:415: ElProcessEapConfigChange: PCB ref and enabled,
continue check
[2192] 13:42:16:415: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[2192] 13:42:16:415: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[2192] 13:42:16:415: ElProcessEapConfigChange: Same SSID, EAPType,
CustomAuth, No content change
[2192] 13:42:16:415: ElProcessEapConfigChange: Finished with error 0
[1180] 13:42:16:415: ElWZCCfgUpdateSettings: Error in RegOpenKeyEx for
base key, 2
[1180] 13:42:16:415: ElWZCCfgChangeHandler: ElWZCCfgUpdateSettings HKCU
failed with error (2)
[1504] 13:42:19:519: ElMediaEventsHandler entered -- EventType=<6>
[1504] 13:42:19:519: ElMediaEventsHandler: Calling ElMediaSenseEventFromWZC
[1504] 13:42:19:519: ElMediaSenseEventFromWZC: Entered
[1504] 13:42:19:519: ElMediaSenseEventFromWZCWorker: For interface GUID
({B123A337-9DAB-45CD-B148-7A2E8A53AAED})
[1504] 13:42:19:519: ElMediaSenseEventFromWZCWorker: Callback for sense
disconnect
[1504] 13:42:19:519: Current Mode is NOT WPA2. PreAuth disabled
[1504] 13:42:19:519: FSMDisconnected entered for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:42:19:519: Setting state DISCONNECTED for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:42:19:519: FSMDisconnected completed for port Wireless-G
Notebook Adapter with RangeBooster - Packet Scheduler Miniport
[1504] 13:42:19:519: ElMediaSenseEventFromWZCWorker: Port marked
disconnected {B123A337-9DAB-45CD-B148-7A2E8A53AAED}
[1504] 13:42:19:519: ElMediaSenseCallbackWorker: processed, RetCode = 0
[1504] 13:42:19:519: ElReadFromPort entered, pPCB = 000E7E38
[1504] 13:42:19:519: ElReadFromPort: pPCB = 000E7E38, RefCnt = 3
[1712] 13:42:19:700: EAPOLQueryGUIDNCSState: For Port Intel(R) PRO/100
VE Network Connection #2 - Packet Scheduler Miniport returning S_FALSE
[1712] 13:42:19:740: EAPOLQueryGUIDNCSState: 802.1X Port Inexistant for
{FD7F1225-FD60-480C-8857-1DF3ECBC9C18}. Returning S_OK
[2192] 13:42:21:061: ElTimeoutCallbackRoutine entered
[2192] 13:42:21:061: ElTimeoutCallbackRoutine: No action in Disconnected
state
[2192] 13:42:21:061: ElTimeoutCallbackRoutine completed
[1180] 13:42:21:993: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1548] 13:42:21:993: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1180] 13:42:21:993: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1180] 13:42:21:993: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<25>,
Offset=<52/106>, dwAuthData=<0>
[1548] 13:42:22:013: ElGetInterfaceParams: SsidLength=<11>, Found
EapTypeId=<13>, SSIDLen=<11>
[1548] 13:42:22:013: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1548] 13:42:22:013: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<13>,
Offset=<52/106>, dwAuthData=<42>
[1548] 13:42:22:013: ElGetCustomAuthData: SSIDLen=<11>, EapTypeId=<25>,
Offset=<52/106>, dwAuthData=<0>
[1180] 13:42:22:023: ElGetInterfaceParams: SsidLength=<13>, Found
EapTypeId=<13>, SSIDLen=<13>
[1180] 13:42:22:023: ElGetCustomAuthData: SSIDLen=<13>, EapTypeId=<13>,
Offset=<52/104>, dwAuthData=<40>
[1180] 13:42:22:023: ElGetCustomAuthData: SSIDLen=<13>, EapTypeId=<13>,
Offset=<52/104>, dwAuthData=<40>
[1180] 13:42:22:033: ElGetCustomAuthData: SSIDLen=<13>, EapTypeId=<25>,
Offset=<52/104>, dwAuthData=<0>
[2192] 13:42:28:372: EAPOLQueryGUIDNCSState: For Port Intel(R) PRO/100
VE Network Connection #2 - Packet Scheduler Miniport returning S_FALSE
[2192] 13:42:28:392: EAPOLQueryGUIDNCSState: 802.1X Port Inexistant for
{FD7F1225-FD60-480C-8857-1DF3ECBC9C18}. Returning S_OK
More information about the Freeradius-Users
mailing list