Still can't connect to WPA Enterprise

Guest · Sep 11, 2006

The problem that stopped me using Beta 2, is still there with RC1 for me.

Trying to connect to WPA Enterprise, TKIP PEAP with certificate validation
off, and just using a username/password combination, no domain name. This
worked fine in XP.

I get asked for my username and password, provide the ones which have worked
for a long time in XP, and after 10-20 seconds I just get a baloon I saying I
need to provide additional credentials. I put them in again, and after a
similar length of time I am told I can not connect.

Anyone have this problem or any ideas on a solution?

Guest · Sep 12, 2006

You're not alone.

Same setup, same problem. No idea how to solve this tough. Somehow my radius
server (ZyXEL Vantage Radius 50) doesn't seem to like what is passed to it.

Guest · Sep 12, 2006

I thought it would be something like that. I just don't really want to annoy
our sys admins about it too much as it is my personal laptop I have decided
to run Vista on, trying to connect to work wireless network.

J.P. Stewart [MSFT] · Sep 14, 2006

Are you on a domain or workgroup? Are you using certificate based
authentication?

Assuming that you are connecting to a network that requires a certificate,
you are probably hitting the following:

In vista the default authentication was changed from certificate based auth
to PEAP/MSCHAPv2. This means when using discovery to connect to a network
for which requires an EAP type, this is the new default. To connect to a
network which uses another eap type you need to create a wireless profile
for that network in advance. This can be done through group policy or by
creating a profile from the "Get connected Wizard."

To do this, just click "set up a connection or network" on the "connect to a
network" page (then chose "Manually connect to a wireless network"). After
typing the SSID (case sensitive) and setting the initial security settings,
click next. The next page will have a button which says "Change connection
settings." Click it. Here you can change the auth type back to "Smart card
or other certificate" on the "security" tab. Set any other relavent settings
while here. After pressing OK, you should be connected to your network
(assuming that automatic connections were allowed earlier in the process).

The design of the wireless "discovery" (using the UI) phase is not
necessarily designed to connect to networks which require management and
there are a range of options to create/distribute wireless profiles in
advance from netsh scripting to group policy.

Creating a wireless profile (steps above) is the more powerful method of
connecting to any type of network manually.

Guest · Sep 15, 2006

I the same problem, manualy configuration is correctly but after try connect
to wireless network - Vista not asked userneme and password.

In XP this working correctly and after 10-20 seconds I just get a baloon I
saying I need to provide additional credentials.

Ladislav

J.P. Stewart [MSFT] · Sep 15, 2006

Also note that in vista to get a balloon for entering credentials, you must
uncheck the box which says "Use my windows credentials" in the MSCHAPv2
advanced settings. OR just use discovery to connect to a 1x/WPA network
while logged on as a non-domain user. Either of these methods will cause a
balloon to appear to enter alternate credentials.

If you are still seeing a failure, the following steps will log what is
going wrong:

1. Launch an elevated command prompt
2. netsh wlan set tra yes
3. Reproduce the problem
4. netsh wlan set tra no (make sure this is still in an elevated command
prompt, and wait for the command to complete)
5. Tracing results will be in %windir%\tracing\wireless (try report.html or
just attach a zip file of the dir)

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

Guest · Sep 15, 2006

J.P. Stewart said:
Are you on a domain or workgroup? Are you using certificate based
authentication?

Assuming that you are connecting to a network that requires a certificate,
you are probably hitting the following:

In vista the default authentication was changed from certificate based auth
to PEAP/MSCHAPv2. This means when using discovery to connect to a network
for which requires an EAP type, this is the new default. To connect to a
network which uses another eap type you need to create a wireless profile
for that network in advance. This can be done through group policy or by
creating a profile from the "Get connected Wizard."

To do this, just click "set up a connection or network" on the "connect to a
network" page (then chose "Manually connect to a wireless network"). After
typing the SSID (case sensitive) and setting the initial security settings,
click next. The next page will have a button which says "Change connection
settings." Click it. Here you can change the auth type back to "Smart card
or other certificate" on the "security" tab. Set any other relavent settings
while here. After pressing OK, you should be connected to your network
(assuming that automatic connections were allowed earlier in the process).

The design of the wireless "discovery" (using the UI) phase is not
necessarily designed to connect to networks which require management and
there are a range of options to create/distribute wireless profiles in
advance from netsh scripting to group policy.

Creating a wireless profile (steps above) is the more powerful method of
connecting to any type of network manually.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

Guest · Sep 18, 2006

No, that seems to be the opposite of my problem.

I am not on a domain.
I am not using certificate based, and I turned off 'validate certificate'
I also turned off 'use my windows login and password by default'
I am set to use PEAP/MSCHAPv2 and provide the correct credentials when
asked, with no success.

I will attempt to log as per your next response.

Guest · Sep 18, 2006

I try

1. Launch an elevated command prompt
2. netsh wlan set tra yes
3. Reproduce the problem
4. netsh wlan set tra no (make sure this is still in an elevated command
prompt, and wait for the command to complete)
5. Tracing results will be in %windir%\tracing\wireless (try report.html or
just attach a zip file of the dir)

but %windir%\tracing is empty !!

Result - this is bug in RC1 (wireles networking not funct. properly).

Ladislav

J.P. Stewart [MSFT] · Sep 18, 2006

Are there any errors when attempting the instructions below? Are you sure
the command prompt is elevated?

Many others have reported results with these same instructions.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

J.P. Stewart [MSFT] · Sep 18, 2006

Ok, yes those sound like exactly the proper settings for a user name /
password based authentication on a non-domain machine.

If you are getting prompted properly, but never connect after entering your
credentials, then its true that the logs will be the only way at this point
to get to what the error is. The logs for this scenario are located in
%windir%\tracing\wireless\traces\onex.txt after running the tracing
instructions I included in the other post.

J.P.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

Guest · Sep 19, 2006

Here is the tail of that file as I can't post it all.

[4912] 15:05:05.941 OneXUpdatePortProfile
[4912] 15:05:05.944 OneXSetRuntimeState
[4912] 15:05:05.944 OneXStartAuthentication
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [ConfigChanged]
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEConfigChanged)
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAEConfigChanged)
[4912] 15:05:05.944 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.944 Port(7): Processing global event complete: (ConfigChanged)
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [SetRuntimeState]
[4912] 15:05:05.944 Port(7): Start processing local event:
(PAESetRuntimeState)
[4912] 15:05:05.944 Port(7): Set runtime state containing a user token
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAESetRuntimeState)
[4912] 15:05:05.944 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.944 Port(7): Processing global event complete:
(SetRuntimeState)
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [StartAuth]
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEStartAuth)
[4912] 15:05:05.944 Port(7): Starting a new 802.1X authentication (MSM
initiated)
[4912] 15:05:05.944 Port(7): StateSpaeAuthNotStarted ----> StateSpaeStartAuth
[4912] 15:05:05.944 Port(7): Sending notification = (ResultUpdate) to MSM
[4912] 15:05:05.944 Port(7): Updating MSM with OneX Result
[4912] 15:05:05.944 Port(7): Processing local event complete: (PAEStartAuth)
[4912] 15:05:05.944 Port(7): Start processing local event:
(BackendStartBackend)
[4912] 15:05:05.944 Port(7): StateSBackendNotStarted ---->
StateSBackendDeactivated
[4912] 15:05:05.944 Port(7): Processing local event complete:
(BackendStartBackend)
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEUCT)
[4912] 15:05:05.944 Port(7): A user token has been specified to be used.
Proposing user auth
[4912] 15:05:05.944 Port(7): Identified OneX credentials. Using User Auth
[4912] 15:05:05.945 Port(7): User name = Chris, domain name = cmayer-notebook
[4912] 15:05:05.945 Port(7): 802.1X user identified. auth identity = User
Auth, sessionId = 1, username=Chris, domain=cmayer-notebook
[4912] 15:05:05.945 Port(7): StateSpaeStartAuth ----> StateSpaeInitialize
[4912] 15:05:05.945 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.945 Port(7): The auth and port status is same as last time.
Not updating MSM with OneX Result
[4912] 15:05:05.945 Port(7): Processing local event complete: (PAEUCT)
[4912] 15:05:05.945 Port(7): Start processing local event:
(BackendUserChanged)
[4912] 15:05:05.945 Port(7): Setting the fResume flag to FALSE
[4912] 15:05:05.945 Port(7): StateSBackendDeactivated ---->
StateSBackendUserChanged
[4912] 15:05:05.945 Port(7): Processing local event complete:
(BackendUserChanged)
[4912] 15:05:05.945 Port(7): Start processing local event: (PAEUserIdentified)
[4912] 15:05:05.945 Port(7): Sending OneX packet of size 5 to MSM
[4912] 15:05:05.946 Port(7): Sent an Eapol start packet
[4912] 15:05:05.946 Port(7): StateSpaeInitialize ----> StateSpaeConnecting
[4912] 15:05:05.946 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.946 Port(7): The auth and port status is same as last time.
Not updating MSM with OneX Result
[4912] 15:05:05.946 Port(7): Processing local event complete:
(PAEUserIdentified)
[4912] 15:05:05.946 Port(7): Start processing local event: (BackendUCT)
[4912] 15:05:05.946 Port(7): StateSBackendUserChanged ---->
StateSBackendDeactivated
[4912] 15:05:05.946 Port(7): Processing local event complete: (BackendUCT)
[4912] 15:05:05.946 Port(7): Start processing local event:
(BackendEapActivate)
[4912] 15:05:05.946 Port(7): Querying OneX user profile from MSM
[4912] 15:05:05.946 Port(7): EapEndSession called for eap type 25
[4912] 15:05:05.946 Port(7): EapBeginSession called for eap type 25
[4912] 15:05:05.946 Port(7): The eap flags being passed into
EapHostPeerBeginSession are 0x40080
[4912] 15:05:05.946 Port(7): Calling EapHostPeerBeginSession...
[4912] 15:05:05.949 Port(7): EapHostPeerBeginSession returned
[4912] 15:05:05.949 Port(7): StateSBackendDeactivated ----> StateSBackendIdle
[4912] 15:05:05.949 Port(7): Processing local event complete:
(BackendEapActivate)
[4912] 15:05:05.949 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.949 Port(7): Processing global event complete: (StartAuth)
[4912] 15:05:05.949 OneXIndicatePacket
[4912] 15:05:05.949 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=0, eapType=0
[4912] 15:05:05.949 OneXIndicatePacket
[4912] 15:05:05.949 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=0, eapType=0
[4912] 15:05:05.949 Port(7): ProcessOneXEvent: Event [EapPkt]
[4912] 15:05:05.949 Port(7): Start processing local event:
(BackendEapPktReceived)
[4912] 15:05:05.949 Port(7): Sending notification = (OneXNetworkIdentified)
to MSM
[4912] 15:05:05.949 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseInvokeUI
[4912] 15:05:05.951 Port(7): Calling EapHostPeerGetUIContext ...
[4912] 15:05:05.951 Port(7): EapHostPeerGetUIContext returned
[4912] 15:05:05.951 Port(7): StateSBackendIdle ----> StateSBackendRequest
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Start processing local event: (PAEAuthenticating)
[4912] 15:05:05.951 Port(7): StateSpaeConnecting ----> StateSpaeAuthenticating
[4912] 15:05:05.951 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.951 Port(7): The auth and port status is same as last time.
Not updating MSM with OneX Result
[4912] 15:05:05.951 Port(7): Processing local event complete:
(PAEAuthenticating)
[4912] 15:05:05.951 Port(7): Start processing local event: (BackendUINeeded)
[4912] 15:05:05.951 Port(7): There is no cached UI request Sourcing a new UI
request
[4912] 15:05:05.951 Port(7): There is a new outgoing UI request. Clearing
the cached response for future
[4912] 15:05:05.951 Port(7): Sending UI Request (code = 0) to MSM
[4912] 15:05:05.951 Port(7): Successfully sent UI Request (code = 0) to MSM
[4912] 15:05:05.951 Port(7): StateSBackendRequest ----> StateSBackendRequestUI
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendUINeeded)
[4912] 15:05:05.951 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.951 Port(7): Processing global event complete: (EapPkt)
[4912] 15:05:05.951 Port(7): ProcessOneXEvent: Event [EapPkt]
[4912] 15:05:05.951 Port(7): Start processing local event:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
[4912] 15:05:05.951 Port(7): EapProcessPacketValidityAndGetResult returned
action Discard
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.951 Port(7): Processing global event complete: (EapPkt)
[5052] 15:05:14.363 OneXUIResponse
[2296] 15:05:14.363 Port(7): ProcessOneXEvent: Event [UIResponse]
[2296] 15:05:14.363 Port(7): Start processing local event: (BackendUIResponse)
[2296] 15:05:14.363 Port(7): Caching the UI request (size=204) for session 1
[2296] 15:05:14.363 Port(7): Caching the received UI Response for future use
(size=768)
[2296] 15:05:14.363 Port(7): Successfully received UI Response
[2296] 15:05:14.363 Port(7): Processing local event complete:
(BackendUIResponse)
[2296] 15:05:14.363 Port(7): Start processing local event:
(BackendGotInteractiveData)
[2296] 15:05:14.363 Port(7): Calling EapHostPeerSetUIContext ...
[2296] 15:05:14.380 Port(7): EapHostPeerSetUIContext returned
[2296] 15:05:14.381 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.381 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.381 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.381 Port(7): Eaphost asked us to send an Eap packet
length=11, type=EapResponseId, identifier=0, eapType=0
[2296] 15:05:14.381 Port(7): Identity being sent in the ResponseId packet is
cmayer
[2296] 15:05:14.381 Port(7): Sending OneX packet of size 15 to MSM
[2296] 15:05:14.381 Port(7): Send an Eap response to the peer
[2296] 15:05:14.381 Port(7): StateSBackendRequestUI ----> StateSBackendRequest
[2296] 15:05:14.381 Port(7): Processing local event complete:
(BackendGotInteractiveData)
[2296] 15:05:14.381 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.381 Port(7): StateSBackendRequest ----> StateSBackendReceive
[2296] 15:05:14.381 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.381 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.381 Port(7): Processing global event complete: (UIResponse)
[2296] 15:05:14.388 OneXIndicatePacket
[2296] 15:05:14.388 Port(7): Received an Eap packet length=6,
type=EapRequestOther, identifier=1, eapType=25
[2296] 15:05:14.388 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.388 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.388 Port(7): Setting the Eap method backend support to
BackendSupported
[2296] 15:05:14.388 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.392 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.392 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.392 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.392 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.392 Port(7): Eaphost asked us to send an Eap packet
length=117, type=EapResponseOther, identifier=1, eapType=25
[2296] 15:05:14.392 Port(7): Sending OneX packet of size 121 to MSM
[2296] 15:05:14.393 Port(7): Send an Eap response to the peer
[2296] 15:05:14.393 Port(7): StateSBackendReceive ----> StateSBackendRequest
[2296] 15:05:14.393 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.393 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.393 Port(7): StateSBackendRequest ----> StateSBackendReceive
[2296] 15:05:14.393 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.393 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.393 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.402 OneXIndicatePacket
[2296] 15:05:14.402 Port(7): Received an Eap packet length=1034,
type=EapRequestOther, identifier=2, eapType=25
[2296] 15:05:14.402 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.402 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.402 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.402 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.402 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.402 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.402 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.402 Port(7): Eaphost asked us to send an Eap packet
length=6, type=EapResponseOther, identifier=2, eapType=25
[2296] 15:05:14.402 Port(7): Sending OneX packet of size 10 to MSM
[2296] 15:05:14.402 Port(7): Send an Eap response to the peer
[2296] 15:05:14.402 Port(7): StateSBackendReceive ----> StateSBackendRequest
[2296] 15:05:14.402 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.402 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.402 Port(7): StateSBackendRequest ----> StateSBackendReceive
[2296] 15:05:14.402 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.402 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.402 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.409 OneXIndicatePacket
[2296] 15:05:14.409 Port(7): Received an Eap packet length=759,
type=EapRequestOther, identifier=3, eapType=25
[2296] 15:05:14.409 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.409 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.409 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.410 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.410 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.410 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.411 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.411 Port(7): Eaphost asked us to send an Eap packet
length=208, type=EapResponseOther, identifier=3, eapType=25
[2296] 15:05:14.411 Port(7): Sending OneX packet of size 212 to MSM
[2296] 15:05:14.411 Port(7): Send an Eap response to the peer
[2296] 15:05:14.411 Port(7): StateSBackendReceive ----> StateSBackendRequest
[2296] 15:05:14.411 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.411 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.411 Port(7): StateSBackendRequest ----> StateSBackendReceive
[2296] 15:05:14.411 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.411 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.411 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.428 OneXIndicatePacket
[2296] 15:05:14.428 Port(7): Received an Eap packet length=65,
type=EapRequestOther, identifier=4, eapType=25
[2296] 15:05:14.428 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.428 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.428 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.428 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.428 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.428 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.428 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.428 Port(7): Eaphost asked us to send an Eap packet
length=6, type=EapResponseOther, identifier=4, eapType=25
[2296] 15:05:14.428 Port(7): Sending OneX packet of size 10 to MSM
[2296] 15:05:14.429 Port(7): Send an Eap response to the peer
[2296] 15:05:14.429 Port(7): StateSBackendReceive ----> StateSBackendRequest
[2296] 15:05:14.429 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.429 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.429 Port(7): StateSBackendRequest ----> StateSBackendReceive
[2296] 15:05:14.429 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.429 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.429 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.435 OneXIndicatePacket
[2296] 15:05:14.435 Port(7): Received an Eap packet length=80,
type=EapRequestOther, identifier=5, eapType=25
[2296] 15:05:14.435 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.435 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.435 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseDiscard
[2296] 15:05:14.435 Port(7): StateSBackendReceive ----> StateSBackendRequest
[2296] 15:05:14.435 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.435 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.435 Port(7): StateSBackendRequest ----> StateSBackendReceive
[2296] 15:05:14.435 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.435 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.435 Port(7): Processing global event complete: (EapPkt)
[5344] 15:05:32.436 Port(7): ProcessOneXEvent: Event [Timeout]
[5344] 15:05:32.436 Port(7): Start processing local event: (BackendAuthWhile)
[5344] 15:05:32.436 Port(7): StateSBackendReceive ----> StateSBackendTimeout
[5344] 15:05:32.436 Port(7): Processing local event complete:
(BackendAuthWhile)
[5344] 15:05:32.436 Port(7): Start processing local event: (PAESuppTimeout)
[5344] 15:05:32.436 Port(7): Restarting authentication due to reason =
AuthTimeout
[5344] 15:05:32.436 Port(7): Sending notification = (AuthRestarted) to MSM
[5344] 15:05:32.436 Port(7): Sending OneX packet of size 5 to MSM
[5344] 15:05:32.436 Port(7): Sent an Eapol start packet
[5344] 15:05:32.436 Port(7): StateSpaeAuthenticating ----> StateSpaeConnecting
[5344] 15:05:32.436 Port(7): Sending notification = (ResultUpdate) to MSM
[5344] 15:05:32.436 Port(7): The auth and port status is same as last time.
Not updating MSM with OneX Result
[5344] 15:05:32.436 Port(7): Processing local event complete: (PAESuppTimeout)
[5344] 15:05:32.436 Port(7): Start processing local event:
(BackendEapActivate)
[5344] 15:05:32.436 Port(7): EapEndSession called for eap type 25
[5344] 15:05:32.436 Port(7): Calling EapHostPeerEndSession...
[5344] 15:05:32.437 Port(7): EapHostPeerEndSession returned
[5344] 15:05:32.437 Port(7): EapBeginSession called for eap type 25
[5344] 15:05:32.437 Port(7): The eap flags being passed into
EapHostPeerBeginSession are 0x40080
[5344] 15:05:32.437 Port(7): Calling EapHostPeerBeginSession...
[2296] 15:05:32.438 OneXIndicatePacket
[5344] 15:05:32.439 Port(7): EapHostPeerBeginSession returned
[5344] 15:05:32.439 Port(7): StateSBackendTimeout ----> StateSBackendIdle
[5344] 15:05:32.439 Port(7): Processing local event complete:
(BackendEapActivate)
[5344] 15:05:32.439 Port(7): Draining the event queue (SupplicantQueue)
[5344] 15:05:32.439 Port(7): Processing global event complete: (Timeout)
[2296] 15:05:32.439 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=1, eapType=0
[5344] 15:05:32.439 Port(7): ProcessOneXEvent: Event [EapPkt]
[5344] 15:05:32.439 Port(7): Start processing local event:
(BackendEapPktReceived)
[5344] 15:05:32.439 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[5344] 15:05:32.441 Port(7): EapHostPeerProcessReceivedPacket returned
[5344] 15:05:32.441 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseInvokeUI
[5344] 15:05:32.441 Port(7): Calling EapHostPeerGetUIContext ...
[5344] 15:05:32.441 Port(7): EapHostPeerGetUIContext returned
[5344] 15:05:32.441 Port(7): StateSBackendIdle ----> StateSBackendRequest
[5344] 15:05:32.441 Port(7): Processing local event complete:
(BackendEapPktReceived)
[5344] 15:05:32.441 Port(7): Start processing local event: (PAEAuthenticating)
[5344] 15:05:32.441 Port(7): StateSpaeConnecting ----> StateSpaeAuthenticating
[5344] 15:05:32.441 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[5344] 15:05:32.441 Port(7): The auth and port status is same as last time.
Not updating MSM with OneX Result
[5344] 15:05:32.441 Port(7): Processing local event complete:
(PAEAuthenticating)
[5344] 15:05:32.441 Port(7): Start processing local event: (BackendUINeeded)
[5344] 15:05:32.441 Port(7): No cached UI response is present
[5344] 15:05:32.441 Port(7): There is a new outgoing UI request. Clearing
the cached response for future
[5344] 15:05:32.441 Port(7): Sending UI Request (code = 0) to MSM
[5344] 15:05:32.442 Port(7): Successfully sent UI Request (code = 0) to MSM
[5344] 15:05:32.442 Port(7): StateSBackendRequest ----> StateSBackendRequestUI
[5344] 15:05:32.442 Port(7): Processing local event complete:
(BackendUINeeded)
[5344] 15:05:32.442 Port(7): Draining the event queue (SupplicantQueue)
[5344] 15:05:32.442 Port(7): Processing global event complete: (EapPkt)

J.P. Stewart [MSFT] · Sep 20, 2006

The failure is an auth timeout.

029 [4912] 15:05:05.945 Port(7): 802.1X user identified. auth identity =
User Auth, sessionId = 1, username=Chris, domain=cmayer-notebook
051 [4912] 15:05:05.946 Port(7): EapBeginSession called for eap type 25
082 [4912] 15:05:05.951 Port(7): Successfully sent UI Request (code = 0) to
MSM
[9 seconds later]
096 [2296] 15:05:14.363 Port(7): ProcessOneXEvent: Event [UIResponse]
109 [2296] 15:05:14.381 Port(7): Identity being sent in the ResponseId
packet is cmayer
.....
This continues for a while going back and forth with the server.

But then there is a:

202 [2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseDiscard

Followed by a: [18 seconds later]

210 [5344] 15:05:32.436 Port(7): ProcessOneXEvent: Event [Timeout]

I will forward this on to see if anymore debugging can be done, but the
problems seem to point to isssues from the auth server side.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

Costanza said:
Here is the tail of that file as I can't post it all.

[4912] 15:05:05.941 OneXUpdatePortProfile
[4912] 15:05:05.944 OneXSetRuntimeState
[4912] 15:05:05.944 OneXStartAuthentication
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [ConfigChanged]
[4912] 15:05:05.944 Port(7): Start processing local event:
(PAEConfigChanged)
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAEConfigChanged)
[4912] 15:05:05.944 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.944 Port(7): Processing global event complete:
(ConfigChanged)
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [SetRuntimeState]
[4912] 15:05:05.944 Port(7): Start processing local event:
(PAESetRuntimeState)
[4912] 15:05:05.944 Port(7): Set runtime state containing a user token
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAESetRuntimeState)
[4912] 15:05:05.944 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.944 Port(7): Processing global event complete:
(SetRuntimeState)
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [StartAuth]
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEStartAuth)
[4912] 15:05:05.944 Port(7): Starting a new 802.1X authentication (MSM
initiated)
[4912] 15:05:05.944 Port(7): StateSpaeAuthNotStarted ---->
StateSpaeStartAuth
[4912] 15:05:05.944 Port(7): Sending notification = (ResultUpdate) to MSM
[4912] 15:05:05.944 Port(7): Updating MSM with OneX Result
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAEStartAuth)
[4912] 15:05:05.944 Port(7): Start processing local event:
(BackendStartBackend)
[4912] 15:05:05.944 Port(7): StateSBackendNotStarted ---->
StateSBackendDeactivated
[4912] 15:05:05.944 Port(7): Processing local event complete:
(BackendStartBackend)
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEUCT)
[4912] 15:05:05.944 Port(7): A user token has been specified to be used.
Proposing user auth
[4912] 15:05:05.944 Port(7): Identified OneX credentials. Using User Auth
[4912] 15:05:05.945 Port(7): User name = Chris, domain name =
cmayer-notebook
[4912] 15:05:05.945 Port(7): 802.1X user identified. auth identity = User
Auth, sessionId = 1, username=Chris, domain=cmayer-notebook
[4912] 15:05:05.945 Port(7): StateSpaeStartAuth ----> StateSpaeInitialize
[4912] 15:05:05.945 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.945 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[4912] 15:05:05.945 Port(7): Processing local event complete: (PAEUCT)
[4912] 15:05:05.945 Port(7): Start processing local event:
(BackendUserChanged)
[4912] 15:05:05.945 Port(7): Setting the fResume flag to FALSE
[4912] 15:05:05.945 Port(7): StateSBackendDeactivated ---->
StateSBackendUserChanged
[4912] 15:05:05.945 Port(7): Processing local event complete:
(BackendUserChanged)
[4912] 15:05:05.945 Port(7): Start processing local event:
(PAEUserIdentified)
[4912] 15:05:05.945 Port(7): Sending OneX packet of size 5 to MSM
[4912] 15:05:05.946 Port(7): Sent an Eapol start packet
[4912] 15:05:05.946 Port(7): StateSpaeInitialize ----> StateSpaeConnecting
[4912] 15:05:05.946 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.946 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[4912] 15:05:05.946 Port(7): Processing local event complete:
(PAEUserIdentified)
[4912] 15:05:05.946 Port(7): Start processing local event: (BackendUCT)
[4912] 15:05:05.946 Port(7): StateSBackendUserChanged ---->
StateSBackendDeactivated
[4912] 15:05:05.946 Port(7): Processing local event complete: (BackendUCT)
[4912] 15:05:05.946 Port(7): Start processing local event:
(BackendEapActivate)
[4912] 15:05:05.946 Port(7): Querying OneX user profile from MSM
[4912] 15:05:05.946 Port(7): EapEndSession called for eap type 25
[4912] 15:05:05.946 Port(7): EapBeginSession called for eap type 25
[4912] 15:05:05.946 Port(7): The eap flags being passed into
EapHostPeerBeginSession are 0x40080
[4912] 15:05:05.946 Port(7): Calling EapHostPeerBeginSession...
[4912] 15:05:05.949 Port(7): EapHostPeerBeginSession returned
[4912] 15:05:05.949 Port(7): StateSBackendDeactivated ---->
StateSBackendIdle
[4912] 15:05:05.949 Port(7): Processing local event complete:
(BackendEapActivate)
[4912] 15:05:05.949 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.949 Port(7): Processing global event complete: (StartAuth)
[4912] 15:05:05.949 OneXIndicatePacket
[4912] 15:05:05.949 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=0, eapType=0
[4912] 15:05:05.949 OneXIndicatePacket
[4912] 15:05:05.949 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=0, eapType=0
[4912] 15:05:05.949 Port(7): ProcessOneXEvent: Event [EapPkt]
[4912] 15:05:05.949 Port(7): Start processing local event:
(BackendEapPktReceived)
[4912] 15:05:05.949 Port(7): Sending notification =
(OneXNetworkIdentified)
to MSM
[4912] 15:05:05.949 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseInvokeUI
[4912] 15:05:05.951 Port(7): Calling EapHostPeerGetUIContext ...
[4912] 15:05:05.951 Port(7): EapHostPeerGetUIContext returned
[4912] 15:05:05.951 Port(7): StateSBackendIdle ----> StateSBackendRequest
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Start processing local event:
(PAEAuthenticating)
[4912] 15:05:05.951 Port(7): StateSpaeConnecting ---->
StateSpaeAuthenticating
[4912] 15:05:05.951 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.951 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[4912] 15:05:05.951 Port(7): Processing local event complete:
(PAEAuthenticating)
[4912] 15:05:05.951 Port(7): Start processing local event:
(BackendUINeeded)
[4912] 15:05:05.951 Port(7): There is no cached UI request Sourcing a new
UI
request
[4912] 15:05:05.951 Port(7): There is a new outgoing UI request. Clearing
the cached response for future
[4912] 15:05:05.951 Port(7): Sending UI Request (code = 0) to MSM
[4912] 15:05:05.951 Port(7): Successfully sent UI Request (code = 0) to
MSM
[4912] 15:05:05.951 Port(7): StateSBackendRequest ---->
StateSBackendRequestUI
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendUINeeded)
[4912] 15:05:05.951 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.951 Port(7): Processing global event complete: (EapPkt)
[4912] 15:05:05.951 Port(7): ProcessOneXEvent: Event [EapPkt]
[4912] 15:05:05.951 Port(7): Start processing local event:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
[4912] 15:05:05.951 Port(7): EapProcessPacketValidityAndGetResult returned
action Discard
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.951 Port(7): Processing global event complete: (EapPkt)
[5052] 15:05:14.363 OneXUIResponse
[2296] 15:05:14.363 Port(7): ProcessOneXEvent: Event [UIResponse]
[2296] 15:05:14.363 Port(7): Start processing local event:
(BackendUIResponse)
[2296] 15:05:14.363 Port(7): Caching the UI request (size=204) for session
1
[2296] 15:05:14.363 Port(7): Caching the received UI Response for future
use
(size=768)
[2296] 15:05:14.363 Port(7): Successfully received UI Response
[2296] 15:05:14.363 Port(7): Processing local event complete:
(BackendUIResponse)
[2296] 15:05:14.363 Port(7): Start processing local event:
(BackendGotInteractiveData)
[2296] 15:05:14.363 Port(7): Calling EapHostPeerSetUIContext ...
[2296] 15:05:14.380 Port(7): EapHostPeerSetUIContext returned
[2296] 15:05:14.381 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.381 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.381 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.381 Port(7): Eaphost asked us to send an Eap packet
length=11, type=EapResponseId, identifier=0, eapType=0
[2296] 15:05:14.381 Port(7): Identity being sent in the ResponseId packet
is
cmayer
[2296] 15:05:14.381 Port(7): Sending OneX packet of size 15 to MSM
[2296] 15:05:14.381 Port(7): Send an Eap response to the peer
[2296] 15:05:14.381 Port(7): StateSBackendRequestUI ---->
StateSBackendRequest
[2296] 15:05:14.381 Port(7): Processing local event complete:
(BackendGotInteractiveData)
[2296] 15:05:14.381 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.381 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.381 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.381 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.381 Port(7): Processing global event complete:
(UIResponse)
[2296] 15:05:14.388 OneXIndicatePacket
[2296] 15:05:14.388 Port(7): Received an Eap packet length=6,
type=EapRequestOther, identifier=1, eapType=25
[2296] 15:05:14.388 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.388 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.388 Port(7): Setting the Eap method backend support to
BackendSupported
[2296] 15:05:14.388 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.392 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.392 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.392 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.392 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.392 Port(7): Eaphost asked us to send an Eap packet
length=117, type=EapResponseOther, identifier=1, eapType=25
[2296] 15:05:14.392 Port(7): Sending OneX packet of size 121 to MSM
[2296] 15:05:14.393 Port(7): Send an Eap response to the peer
[2296] 15:05:14.393 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.393 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.393 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.393 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.393 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.393 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.393 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.402 OneXIndicatePacket
[2296] 15:05:14.402 Port(7): Received an Eap packet length=1034,
type=EapRequestOther, identifier=2, eapType=25
[2296] 15:05:14.402 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.402 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.402 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.402 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.402 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.402 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.402 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.402 Port(7): Eaphost asked us to send an Eap packet
length=6, type=EapResponseOther, identifier=2, eapType=25
[2296] 15:05:14.402 Port(7): Sending OneX packet of size 10 to MSM
[2296] 15:05:14.402 Port(7): Send an Eap response to the peer
[2296] 15:05:14.402 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.402 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.402 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.402 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.402 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.402 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.402 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.409 OneXIndicatePacket
[2296] 15:05:14.409 Port(7): Received an Eap packet length=759,
type=EapRequestOther, identifier=3, eapType=25
[2296] 15:05:14.409 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.409 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.409 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.410 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.410 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.410 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.411 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.411 Port(7): Eaphost asked us to send an Eap packet
length=208, type=EapResponseOther, identifier=3, eapType=25
[2296] 15:05:14.411 Port(7): Sending OneX packet of size 212 to MSM
[2296] 15:05:14.411 Port(7): Send an Eap response to the peer
[2296] 15:05:14.411 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.411 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.411 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.411 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.411 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.411 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.411 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.428 OneXIndicatePacket
[2296] 15:05:14.428 Port(7): Received an Eap packet length=65,
type=EapRequestOther, identifier=4, eapType=25
[2296] 15:05:14.428 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.428 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.428 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.428 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.428 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.428 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.428 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.428 Port(7): Eaphost asked us to send an Eap packet
length=6, type=EapResponseOther, identifier=4, eapType=25
[2296] 15:05:14.428 Port(7): Sending OneX packet of size 10 to MSM
[2296] 15:05:14.429 Port(7): Send an Eap response to the peer
[2296] 15:05:14.429 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.429 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.429 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.429 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.429 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.429 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.429 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.435 OneXIndicatePacket
[2296] 15:05:14.435 Port(7): Received an Eap packet length=80,
type=EapRequestOther, identifier=5, eapType=25
[2296] 15:05:14.435 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.435 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.435 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseDiscard
[2296] 15:05:14.435 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.435 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.435 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.435 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.435 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.435 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.435 Port(7): Processing global event complete: (EapPkt)
[5344] 15:05:32.436 Port(7): ProcessOneXEvent: Event [Timeout]
[5344] 15:05:32.436 Port(7): Start processing local event:
(BackendAuthWhile)
[5344] 15:05:32.436 Port(7): StateSBackendReceive ---->
StateSBackendTimeout
[5344] 15:05:32.436 Port(7): Processing local event complete:
(BackendAuthWhile)
[5344] 15:05:32.436 Port(7): Start processing local event:
(PAESuppTimeout)
[5344] 15:05:32.436 Port(7): Restarting authentication due to reason =
AuthTimeout
[5344] 15:05:32.436 Port(7): Sending notification = (AuthRestarted) to MSM
[5344] 15:05:32.436 Port(7): Sending OneX packet of size 5 to MSM
[5344] 15:05:32.436 Port(7): Sent an Eapol start packet
[5344] 15:05:32.436 Port(7): StateSpaeAuthenticating ---->
StateSpaeConnecting
[5344] 15:05:32.436 Port(7): Sending notification = (ResultUpdate) to MSM
[5344] 15:05:32.436 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[5344] 15:05:32.436 Port(7): Processing local event complete:
(PAESuppTimeout)
[5344] 15:05:32.436 Port(7): Start processing local event:
(BackendEapActivate)
[5344] 15:05:32.436 Port(7): EapEndSession called for eap type 25
[5344] 15:05:32.436 Port(7): Calling EapHostPeerEndSession...
[5344] 15:05:32.437 Port(7): EapHostPeerEndSession returned
[5344] 15:05:32.437 Port(7): EapBeginSession called for eap type 25
[5344] 15:05:32.437 Port(7): The eap flags being passed into
EapHostPeerBeginSession are 0x40080
[5344] 15:05:32.437 Port(7): Calling EapHostPeerBeginSession...
[2296] 15:05:32.438 OneXIndicatePacket
[5344] 15:05:32.439 Port(7): EapHostPeerBeginSession returned
[5344] 15:05:32.439 Port(7): StateSBackendTimeout ----> StateSBackendIdle
[5344] 15:05:32.439 Port(7): Processing local event complete:
(BackendEapActivate)
[5344] 15:05:32.439 Port(7): Draining the event queue (SupplicantQueue)
[5344] 15:05:32.439 Port(7): Processing global event complete: (Timeout)
[2296] 15:05:32.439 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=1, eapType=0
[5344] 15:05:32.439 Port(7): ProcessOneXEvent: Event [EapPkt]
[5344] 15:05:32.439 Port(7): Start processing local event:
(BackendEapPktReceived)
[5344] 15:05:32.439 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[5344] 15:05:32.441 Port(7): EapHostPeerProcessReceivedPacket returned
[5344] 15:05:32.441 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseInvokeUI
[5344] 15:05:32.441 Port(7): Calling EapHostPeerGetUIContext ...
[5344] 15:05:32.441 Port(7): EapHostPeerGetUIContext returned
[5344] 15:05:32.441 Port(7): StateSBackendIdle ----> StateSBackendRequest
[5344] 15:05:32.441 Port(7): Processing local event complete:
(BackendEapPktReceived)
[5344] 15:05:32.441 Port(7): Start processing local event:
(PAEAuthenticating)
[5344] 15:05:32.441 Port(7): StateSpaeConnecting ---->
StateSpaeAuthenticating
[5344] 15:05:32.441 Port(7): The result and the backend support is same as
last time. Not sending ResultUpdate notification
[5344] 15:05:32.441 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[5344] 15:05:32.441 Port(7): Processing local event complete:
(PAEAuthenticating)
[5344] 15:05:32.441 Port(7): Start processing local event:
(BackendUINeeded)
[5344] 15:05:32.441 Port(7): No cached UI response is present
[5344] 15:05:32.441 Port(7): There is a new outgoing UI request. Clearing
the cached response for future
[5344] 15:05:32.441 Port(7): Sending UI Request (code = 0) to MSM
[5344] 15:05:32.442 Port(7): Successfully sent UI Request (code = 0) to
MSM
[5344] 15:05:32.442 Port(7): StateSBackendRequest ---->
StateSBackendRequestUI
[5344] 15:05:32.442 Port(7): Processing local event complete:
(BackendUINeeded)
[5344] 15:05:32.442 Port(7): Draining the event queue (SupplicantQueue)
[5344] 15:05:32.442 Port(7): Processing global event complete: (EapPkt)

J.P. Stewart said:

Ok, yes those sound like exactly the proper settings for a user name /
password based authentication on a non-domain machine.

If you are getting prompted properly, but never connect after entering
your
credentials, then its true that the logs will be the only way at this
point
to get to what the error is. The logs for this scenario are located in
%windir%\tracing\wireless\traces\onex.txt after running the tracing
instructions I included in the other post.

J.P.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

Click to expand...

drahnier · Sep 24, 2006

Fwiw: I still get this kind of timeout with build 5728; difference to build
5600 is: Now a dialog box pops up informing me that the auth server did not
respond in a timely manner (something like that). My radius server is ZyXEL
Vantage Radius 50. Although the VR50 is somewhat slow in authenticating, it
is working flawlessly for my XP clients. And: WPA2-PSK/AES does work, and so
does WPA-PSK/TKIP. AP is ZyXEL G-560.

J.P. Stewart said:
The failure is an auth timeout.

029 [4912] 15:05:05.945 Port(7): 802.1X user identified. auth identity =
User Auth, sessionId = 1, username=Chris, domain=cmayer-notebook
051 [4912] 15:05:05.946 Port(7): EapBeginSession called for eap type 25
082 [4912] 15:05:05.951 Port(7): Successfully sent UI Request (code = 0)
to MSM
[9 seconds later]
096 [2296] 15:05:14.363 Port(7): ProcessOneXEvent: Event [UIResponse]
109 [2296] 15:05:14.381 Port(7): Identity being sent in the ResponseId
packet is cmayer
....
This continues for a while going back and forth with the server.

But then there is a:

202 [2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseDiscard

Followed by a: [18 seconds later]

210 [5344] 15:05:32.436 Port(7): ProcessOneXEvent: Event [Timeout]

I will forward this on to see if anymore debugging can be done, but the
problems seem to point to isssues from the auth server side.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

Costanza said:

Here is the tail of that file as I can't post it all.

[4912] 15:05:05.941 OneXUpdatePortProfile
[4912] 15:05:05.944 OneXSetRuntimeState
[4912] 15:05:05.944 OneXStartAuthentication
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [ConfigChanged]
[4912] 15:05:05.944 Port(7): Start processing local event:
(PAEConfigChanged)
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAEConfigChanged)
[4912] 15:05:05.944 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.944 Port(7): Processing global event complete:
(ConfigChanged)
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [SetRuntimeState]
[4912] 15:05:05.944 Port(7): Start processing local event:
(PAESetRuntimeState)
[4912] 15:05:05.944 Port(7): Set runtime state containing a user token
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAESetRuntimeState)
[4912] 15:05:05.944 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.944 Port(7): Processing global event complete:
(SetRuntimeState)
[4912] 15:05:05.944 Port(7): ProcessOneXEvent: Event [StartAuth]
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEStartAuth)
[4912] 15:05:05.944 Port(7): Starting a new 802.1X authentication (MSM
initiated)
[4912] 15:05:05.944 Port(7): StateSpaeAuthNotStarted ---->
StateSpaeStartAuth
[4912] 15:05:05.944 Port(7): Sending notification = (ResultUpdate) to MSM
[4912] 15:05:05.944 Port(7): Updating MSM with OneX Result
[4912] 15:05:05.944 Port(7): Processing local event complete:
(PAEStartAuth)
[4912] 15:05:05.944 Port(7): Start processing local event:
(BackendStartBackend)
[4912] 15:05:05.944 Port(7): StateSBackendNotStarted ---->
StateSBackendDeactivated
[4912] 15:05:05.944 Port(7): Processing local event complete:
(BackendStartBackend)
[4912] 15:05:05.944 Port(7): Start processing local event: (PAEUCT)
[4912] 15:05:05.944 Port(7): A user token has been specified to be used.
Proposing user auth
[4912] 15:05:05.944 Port(7): Identified OneX credentials. Using User Auth
[4912] 15:05:05.945 Port(7): User name = Chris, domain name =
cmayer-notebook
[4912] 15:05:05.945 Port(7): 802.1X user identified. auth identity = User
Auth, sessionId = 1, username=Chris, domain=cmayer-notebook
[4912] 15:05:05.945 Port(7): StateSpaeStartAuth ----> StateSpaeInitialize
[4912] 15:05:05.945 Port(7): The result and the backend support is same
as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.945 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[4912] 15:05:05.945 Port(7): Processing local event complete: (PAEUCT)
[4912] 15:05:05.945 Port(7): Start processing local event:
(BackendUserChanged)
[4912] 15:05:05.945 Port(7): Setting the fResume flag to FALSE
[4912] 15:05:05.945 Port(7): StateSBackendDeactivated ---->
StateSBackendUserChanged
[4912] 15:05:05.945 Port(7): Processing local event complete:
(BackendUserChanged)
[4912] 15:05:05.945 Port(7): Start processing local event:
(PAEUserIdentified)
[4912] 15:05:05.945 Port(7): Sending OneX packet of size 5 to MSM
[4912] 15:05:05.946 Port(7): Sent an Eapol start packet
[4912] 15:05:05.946 Port(7): StateSpaeInitialize ---->
StateSpaeConnecting
[4912] 15:05:05.946 Port(7): The result and the backend support is same
as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.946 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[4912] 15:05:05.946 Port(7): Processing local event complete:
(PAEUserIdentified)
[4912] 15:05:05.946 Port(7): Start processing local event: (BackendUCT)
[4912] 15:05:05.946 Port(7): StateSBackendUserChanged ---->
StateSBackendDeactivated
[4912] 15:05:05.946 Port(7): Processing local event complete:
(BackendUCT)
[4912] 15:05:05.946 Port(7): Start processing local event:
(BackendEapActivate)
[4912] 15:05:05.946 Port(7): Querying OneX user profile from MSM
[4912] 15:05:05.946 Port(7): EapEndSession called for eap type 25
[4912] 15:05:05.946 Port(7): EapBeginSession called for eap type 25
[4912] 15:05:05.946 Port(7): The eap flags being passed into
EapHostPeerBeginSession are 0x40080
[4912] 15:05:05.946 Port(7): Calling EapHostPeerBeginSession...
[4912] 15:05:05.949 Port(7): EapHostPeerBeginSession returned
[4912] 15:05:05.949 Port(7): StateSBackendDeactivated ---->
StateSBackendIdle
[4912] 15:05:05.949 Port(7): Processing local event complete:
(BackendEapActivate)
[4912] 15:05:05.949 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.949 Port(7): Processing global event complete:
(StartAuth)
[4912] 15:05:05.949 OneXIndicatePacket
[4912] 15:05:05.949 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=0, eapType=0
[4912] 15:05:05.949 OneXIndicatePacket
[4912] 15:05:05.949 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=0, eapType=0
[4912] 15:05:05.949 Port(7): ProcessOneXEvent: Event [EapPkt]
[4912] 15:05:05.949 Port(7): Start processing local event:
(BackendEapPktReceived)
[4912] 15:05:05.949 Port(7): Sending notification =
(OneXNetworkIdentified)
to MSM
[4912] 15:05:05.949 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseInvokeUI
[4912] 15:05:05.951 Port(7): Calling EapHostPeerGetUIContext ...
[4912] 15:05:05.951 Port(7): EapHostPeerGetUIContext returned
[4912] 15:05:05.951 Port(7): StateSBackendIdle ----> StateSBackendRequest
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Start processing local event:
(PAEAuthenticating)
[4912] 15:05:05.951 Port(7): StateSpaeConnecting ---->
StateSpaeAuthenticating
[4912] 15:05:05.951 Port(7): The result and the backend support is same
as
last time. Not sending ResultUpdate notification
[4912] 15:05:05.951 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[4912] 15:05:05.951 Port(7): Processing local event complete:
(PAEAuthenticating)
[4912] 15:05:05.951 Port(7): Start processing local event:
(BackendUINeeded)
[4912] 15:05:05.951 Port(7): There is no cached UI request Sourcing a new
UI
request
[4912] 15:05:05.951 Port(7): There is a new outgoing UI request. Clearing
the cached response for future
[4912] 15:05:05.951 Port(7): Sending UI Request (code = 0) to MSM
[4912] 15:05:05.951 Port(7): Successfully sent UI Request (code = 0) to
MSM
[4912] 15:05:05.951 Port(7): StateSBackendRequest ---->
StateSBackendRequestUI
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendUINeeded)
[4912] 15:05:05.951 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.951 Port(7): Processing global event complete: (EapPkt)
[4912] 15:05:05.951 Port(7): ProcessOneXEvent: Event [EapPkt]
[4912] 15:05:05.951 Port(7): Start processing local event:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[4912] 15:05:05.951 Port(7): EapHostPeerProcessReceivedPacket returned
[4912] 15:05:05.951 Port(7): EapProcessPacketValidityAndGetResult
returned
action Discard
[4912] 15:05:05.951 Port(7): Processing local event complete:
(BackendEapPktReceived)
[4912] 15:05:05.951 Port(7): Draining the event queue (SupplicantQueue)
[4912] 15:05:05.951 Port(7): Processing global event complete: (EapPkt)
[5052] 15:05:14.363 OneXUIResponse
[2296] 15:05:14.363 Port(7): ProcessOneXEvent: Event [UIResponse]
[2296] 15:05:14.363 Port(7): Start processing local event:
(BackendUIResponse)
[2296] 15:05:14.363 Port(7): Caching the UI request (size=204) for
session 1
[2296] 15:05:14.363 Port(7): Caching the received UI Response for future
use
(size=768)
[2296] 15:05:14.363 Port(7): Successfully received UI Response
[2296] 15:05:14.363 Port(7): Processing local event complete:
(BackendUIResponse)
[2296] 15:05:14.363 Port(7): Start processing local event:
(BackendGotInteractiveData)
[2296] 15:05:14.363 Port(7): Calling EapHostPeerSetUIContext ...
[2296] 15:05:14.380 Port(7): EapHostPeerSetUIContext returned
[2296] 15:05:14.381 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.381 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.381 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.381 Port(7): Eaphost asked us to send an Eap packet
length=11, type=EapResponseId, identifier=0, eapType=0
[2296] 15:05:14.381 Port(7): Identity being sent in the ResponseId packet
is
cmayer
[2296] 15:05:14.381 Port(7): Sending OneX packet of size 15 to MSM
[2296] 15:05:14.381 Port(7): Send an Eap response to the peer
[2296] 15:05:14.381 Port(7): StateSBackendRequestUI ---->
StateSBackendRequest
[2296] 15:05:14.381 Port(7): Processing local event complete:
(BackendGotInteractiveData)
[2296] 15:05:14.381 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.381 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.381 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.381 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.381 Port(7): Processing global event complete:
(UIResponse)
[2296] 15:05:14.388 OneXIndicatePacket
[2296] 15:05:14.388 Port(7): Received an Eap packet length=6,
type=EapRequestOther, identifier=1, eapType=25
[2296] 15:05:14.388 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.388 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.388 Port(7): Setting the Eap method backend support to
BackendSupported
[2296] 15:05:14.388 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.392 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.392 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.392 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.392 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.392 Port(7): Eaphost asked us to send an Eap packet
length=117, type=EapResponseOther, identifier=1, eapType=25
[2296] 15:05:14.392 Port(7): Sending OneX packet of size 121 to MSM
[2296] 15:05:14.393 Port(7): Send an Eap response to the peer
[2296] 15:05:14.393 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.393 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.393 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.393 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.393 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.393 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.393 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.402 OneXIndicatePacket
[2296] 15:05:14.402 Port(7): Received an Eap packet length=1034,
type=EapRequestOther, identifier=2, eapType=25
[2296] 15:05:14.402 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.402 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.402 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.402 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.402 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.402 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.402 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.402 Port(7): Eaphost asked us to send an Eap packet
length=6, type=EapResponseOther, identifier=2, eapType=25
[2296] 15:05:14.402 Port(7): Sending OneX packet of size 10 to MSM
[2296] 15:05:14.402 Port(7): Send an Eap response to the peer
[2296] 15:05:14.402 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.402 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.402 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.402 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.402 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.402 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.402 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.409 OneXIndicatePacket
[2296] 15:05:14.409 Port(7): Received an Eap packet length=759,
type=EapRequestOther, identifier=3, eapType=25
[2296] 15:05:14.409 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.409 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.409 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.410 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.410 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.410 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.411 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.411 Port(7): Eaphost asked us to send an Eap packet
length=208, type=EapResponseOther, identifier=3, eapType=25
[2296] 15:05:14.411 Port(7): Sending OneX packet of size 212 to MSM
[2296] 15:05:14.411 Port(7): Send an Eap response to the peer
[2296] 15:05:14.411 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.411 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.411 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.411 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.411 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.411 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.411 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.428 OneXIndicatePacket
[2296] 15:05:14.428 Port(7): Received an Eap packet length=65,
type=EapRequestOther, identifier=4, eapType=25
[2296] 15:05:14.428 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.428 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.428 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.428 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.428 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseSend
[2296] 15:05:14.428 Port(7): Calling EapHostPeerGetSendPacket...
[2296] 15:05:14.428 Port(7): EapHostPeerGetSendPacket returned
[2296] 15:05:14.428 Port(7): Eaphost asked us to send an Eap packet
length=6, type=EapResponseOther, identifier=4, eapType=25
[2296] 15:05:14.428 Port(7): Sending OneX packet of size 10 to MSM
[2296] 15:05:14.429 Port(7): Send an Eap response to the peer
[2296] 15:05:14.429 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.429 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.429 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.429 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.429 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.429 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.429 Port(7): Processing global event complete: (EapPkt)
[2296] 15:05:14.435 OneXIndicatePacket
[2296] 15:05:14.435 Port(7): Received an Eap packet length=80,
type=EapRequestOther, identifier=5, eapType=25
[2296] 15:05:14.435 Port(7): ProcessOneXEvent: Event [EapPkt]
[2296] 15:05:14.435 Port(7): Start processing local event:
(BackendEapPktReceived)
[2296] 15:05:14.435 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
[2296] 15:05:14.435 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseDiscard
[2296] 15:05:14.435 Port(7): StateSBackendReceive ---->
StateSBackendRequest
[2296] 15:05:14.435 Port(7): Processing local event complete:
(BackendEapPktReceived)
[2296] 15:05:14.435 Port(7): Start processing local event:
(BackendEapWaitForPkt)
[2296] 15:05:14.435 Port(7): StateSBackendRequest ---->
StateSBackendReceive
[2296] 15:05:14.435 Port(7): Processing local event complete:
(BackendEapWaitForPkt)
[2296] 15:05:14.435 Port(7): Draining the event queue (SupplicantQueue)
[2296] 15:05:14.435 Port(7): Processing global event complete: (EapPkt)
[5344] 15:05:32.436 Port(7): ProcessOneXEvent: Event [Timeout]
[5344] 15:05:32.436 Port(7): Start processing local event:
(BackendAuthWhile)
[5344] 15:05:32.436 Port(7): StateSBackendReceive ---->
StateSBackendTimeout
[5344] 15:05:32.436 Port(7): Processing local event complete:
(BackendAuthWhile)
[5344] 15:05:32.436 Port(7): Start processing local event:
(PAESuppTimeout)
[5344] 15:05:32.436 Port(7): Restarting authentication due to reason =
AuthTimeout
[5344] 15:05:32.436 Port(7): Sending notification = (AuthRestarted) to
MSM
[5344] 15:05:32.436 Port(7): Sending OneX packet of size 5 to MSM
[5344] 15:05:32.436 Port(7): Sent an Eapol start packet
[5344] 15:05:32.436 Port(7): StateSpaeAuthenticating ---->
StateSpaeConnecting
[5344] 15:05:32.436 Port(7): Sending notification = (ResultUpdate) to MSM
[5344] 15:05:32.436 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[5344] 15:05:32.436 Port(7): Processing local event complete:
(PAESuppTimeout)
[5344] 15:05:32.436 Port(7): Start processing local event:
(BackendEapActivate)
[5344] 15:05:32.436 Port(7): EapEndSession called for eap type 25
[5344] 15:05:32.436 Port(7): Calling EapHostPeerEndSession...
[5344] 15:05:32.437 Port(7): EapHostPeerEndSession returned
[5344] 15:05:32.437 Port(7): EapBeginSession called for eap type 25
[5344] 15:05:32.437 Port(7): The eap flags being passed into
EapHostPeerBeginSession are 0x40080
[5344] 15:05:32.437 Port(7): Calling EapHostPeerBeginSession...
[2296] 15:05:32.438 OneXIndicatePacket
[5344] 15:05:32.439 Port(7): EapHostPeerBeginSession returned
[5344] 15:05:32.439 Port(7): StateSBackendTimeout ----> StateSBackendIdle
[5344] 15:05:32.439 Port(7): Processing local event complete:
(BackendEapActivate)
[5344] 15:05:32.439 Port(7): Draining the event queue (SupplicantQueue)
[5344] 15:05:32.439 Port(7): Processing global event complete: (Timeout)
[2296] 15:05:32.439 Port(7): Received an Eap packet length=5,
type=EapRequestId, identifier=1, eapType=0
[5344] 15:05:32.439 Port(7): ProcessOneXEvent: Event [EapPkt]
[5344] 15:05:32.439 Port(7): Start processing local event:
(BackendEapPktReceived)
[5344] 15:05:32.439 Port(7): Calling EapHostPeerProcessReceivedPacket ...
[5344] 15:05:32.441 Port(7): EapHostPeerProcessReceivedPacket returned
[5344] 15:05:32.441 Port(7): EapHostPeerProcessReceivedPacket returned
Action = EapHostPeerResponseInvokeUI
[5344] 15:05:32.441 Port(7): Calling EapHostPeerGetUIContext ...
[5344] 15:05:32.441 Port(7): EapHostPeerGetUIContext returned
[5344] 15:05:32.441 Port(7): StateSBackendIdle ----> StateSBackendRequest
[5344] 15:05:32.441 Port(7): Processing local event complete:
(BackendEapPktReceived)
[5344] 15:05:32.441 Port(7): Start processing local event:
(PAEAuthenticating)
[5344] 15:05:32.441 Port(7): StateSpaeConnecting ---->
StateSpaeAuthenticating
[5344] 15:05:32.441 Port(7): The result and the backend support is same
as
last time. Not sending ResultUpdate notification
[5344] 15:05:32.441 Port(7): The auth and port status is same as last
time.
Not updating MSM with OneX Result
[5344] 15:05:32.441 Port(7): Processing local event complete:
(PAEAuthenticating)
[5344] 15:05:32.441 Port(7): Start processing local event:
(BackendUINeeded)
[5344] 15:05:32.441 Port(7): No cached UI response is present
[5344] 15:05:32.441 Port(7): There is a new outgoing UI request. Clearing
the cached response for future
[5344] 15:05:32.441 Port(7): Sending UI Request (code = 0) to MSM
[5344] 15:05:32.442 Port(7): Successfully sent UI Request (code = 0) to
MSM
[5344] 15:05:32.442 Port(7): StateSBackendRequest ---->
StateSBackendRequestUI
[5344] 15:05:32.442 Port(7): Processing local event complete:
(BackendUINeeded)
[5344] 15:05:32.442 Port(7): Draining the event queue (SupplicantQueue)
[5344] 15:05:32.442 Port(7): Processing global event complete: (EapPkt)

J.P. Stewart said:

Ok, yes those sound like exactly the proper settings for a user name /
password based authentication on a non-domain machine.

If you are getting prompted properly, but never connect after entering
your
credentials, then its true that the logs will be the only way at this
point
to get to what the error is. The logs for this scenario are located in
%windir%\tracing\wireless\traces\onex.txt after running the tracing
instructions I included in the other post.

J.P.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

No, that seems to be the opposite of my problem.

I am not on a domain.
I am not using certificate based, and I turned off 'validate
certificate'
I also turned off 'use my windows login and password by default'
I am set to use PEAP/MSCHAPv2 and provide the correct credentials when
asked, with no success.

I will attempt to log as per your next response.

:

Are you on a domain or workgroup? Are you using certificate based
authentication?

Assuming that you are connecting to a network that requires a
certificate,
you are probably hitting the following:

In vista the default authentication was changed from certificate
based
auth
to PEAP/MSCHAPv2. This means when using discovery to connect to a
network
for which requires an EAP type, this is the new default. To connect
to a
network which uses another eap type you need to create a wireless
profile
for that network in advance. This can be done through group policy or
by
creating a profile from the "Get connected Wizard."

To do this, just click "set up a connection or network" on the
"connect
to a
network" page (then chose "Manually connect to a wireless network").
After
typing the SSID (case sensitive) and setting the initial security
settings,
click next. The next page will have a button which says "Change
connection
settings." Click it. Here you can change the auth type back to "Smart
card
or other certificate" on the "security" tab. Set any other relavent
settings
while here. After pressing OK, you should be connected to your
network
(assuming that automatic connections were allowed earlier in the
process).

The design of the wireless "discovery" (using the UI) phase is not
necessarily designed to connect to networks which require management
and
there are a range of options to create/distribute wireless profiles
in
advance from netsh scripting to group policy.

Creating a wireless profile (steps above) is the more powerful method
of
connecting to any type of network manually.

--
J.P.
_____________________________________
This posting is provided "AS IS" with
no warranties, and confers no rights.

I thought it would be something like that. I just don't really want
to
annoy
our sys admins about it too much as it is my personal laptop I have
decided
to run Vista on, trying to connect to work wireless network.

:

You're not alone.

Same setup, same problem. No idea how to solve this tough. Somehow
my
radius
server (ZyXEL Vantage Radius 50) doesn't seem to like what is
passed
to
it.

:

The problem that stopped me using Beta 2, is still there with
RC1
for
me.

Trying to connect to WPA Enterprise, TKIP PEAP with certificate
validation
off, and just using a username/password combination, no domain
name.
This
worked fine in XP.

I get asked for my username and password, provide the ones which
have
worked
for a long time in XP, and after 10-20 seconds I just get a
baloon I
saying I
need to provide additional credentials. I put them in again, and
after
a
similar length of time I am told I can not connect.

Anyone have this problem or any ideas on a solution?

Click to expand...

Click to expand...

Guest · Sep 27, 2006

Our school is using a similar network with radius/802.1x credential based
authentication that only requires a username and password. I followed the
instructions and have zipped the entire folder and made them available at
http://www.utdallas.edu/~jmg019010/wireless_trace.zip. I ran similar
commands on a successful XP client that is almost exactly the same and made
it available at http://www.utdallas.edu/~jmg019010/XP_trace.zip. Today was
my first attempt at this, and i will be taking the system home to try on my
home network to make sure it can connect to open, wep, wpa networks as well.

I look forward to future updates,
Jeff Gibson

Guest · Sep 27, 2006

Our school is using a similar network with radius/802.1x credential based
authentication that only requires a username and password. I followed the
instructions and have zipped the entire folder and made them available at
http://www.utdallas.edu/~jmg019010/wireless_trace.zip. I ran similar
commands on a successful XP client that is almost exactly the same and made
it available at http://www.utdallas.edu/~jmg019010/XP_trace.zip. Today was
my first attempt at this, and i will be taking the system home to try on my
home network to make sure it can connect to open, wep, wpa networks as well.

I look forward to future updates,
Jeff Gibson

Guest · Sep 27, 2006

Our school is using a similar network with radius/802.1x credential based
authentication that only requires a username and password. I followed the
instructions and have zipped the entire folder and made them available at
http://www.utdallas.edu/~jmg019010/wireless_trace.zip. I ran similar
commands on a successful XP client that is almost exactly the same and made
it available at http://www.utdallas.edu/~jmg019010/XP_trace.zip. Today was
my first attempt at this, and i will be taking the system home to try on my
home network to make sure it can connect to open, wep, wpa networks as well.

I look forward to future updates,
Jeff Gibson

Petely \(Peter Sloan\) · Oct 2, 2006

I can confirm that I am having the same issues. I work for the ITI Data
Networks group here at Purdue University. We too use 802.1x/RADIUS
authentication utilizing PEAP/MSCHAP. I have tried setting up Vista just
like I would in XP.

Wireless Profile:
Connection
Connect Automatically when this network is in range (checked)
Connect even if the network is not broadcasting (checked)
Security
Security Type: WPA-Enterprise
Encryption Type: TKIP
Network Authentication Method: Protected EAP (PEAP)
Settings
Validate Server Certificate (checked)
Trusted Root Certification Authorities: Thawte Premium
Server CA
Authentication Method: Secured Password (EAP-MSCHAP v2)
Configure
Automatically use my windows login name and password
(UNCHECKED)
Enable Fast Reconnect (checked)
Cache user information for subsequent connections (checked)

The result is that you receive a bubble asking for "Addition information is
required to connect to PAL2.0"
Clicking it prompts you for your credentials. I enter the username and
password and click OK. Within 30 seconds "Addition information is required
to connect to PAL2.0" bubble appears again. This loop will continue to
happen. Enter in a username and password, wait, bubble back again, repeat.

Is this a known problem with Vista?

--Peter

Guest · Oct 7, 2006

Currently I have a simple wireless network with a Linksys WRT54G router
running here. The Wireless auth is set to WPA-RADIUS/TKIP pointing to a
radius server that is connected on the ethernet. The radius server is
Freeradius 1.1.1-r0.0.1 for win32, running on Windows XP. This is faster for
me than going to Uni everytime a new Vista build is released. They use this
same method there, hence this setup.

The radiusserver uses PEAP/MSChapv2. Login is without client certificates,
only with a normal server CA and a root CA. Login at Uni works with LDAP
connection from the RADIUS but at home I just a simple plain text file with
testuser/testpw.

I just finished installing RC2 build 5744 on a test PC here. Wireless via
WPA-PSK/TKIP works fine. I installed the server CA and the root CA and then
connected to the Router with WPA-RADIUS. Entered the credentials and I get
the same loop as before, enter credentials keeps coming back.

I ran the trace as well and it looks just the same as with build 5600 and
5728.

I thought it was interesting though to show my radiuslog, maybe it will help
debugging:

---
Waking up in 6 seconds...
rad_recv: Access-Request packet from host 192.168.1.1:2057, id=0, length=140
User-Name = "testuser"
NAS-IP-Address = 192.168.1.1
Called-Station-Id = "001217dd8a6b"
Calling-Station-Id = "0012f0544d89"
NAS-Identifier = "001217dd8a6b"
NAS-Port = 16
Framed-MTU = 1400
State = 0xf82e117e6e83065b4dd9c03513bb7721
NAS-Port-Type = Wireless-802.11
EAP-Message = 0x020500061900
Message-Authenticator = 0xb5686d39f2cc4950236a1e4127513b26
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 8
modcall[authorize]: module "preprocess" returns ok for request 8
modcall[authorize]: module "chap" returns noop for request 8
modcall[authorize]: module "mschap" returns noop for request 8
rlm_realm: No '@' in User-Name = "testuser", looking up realm NULL
rlm_realm: No such realm "NULL"
modcall[authorize]: module "suffix" returns noop for request 8
rlm_eap: EAP packet type response id 5 length 6
rlm_eap: No EAP Start, assuming it's an on-going EAP conversation
modcall[authorize]: module "eap" returns updated for request 8
users: Matched entry testuser at line 83
modcall[authorize]: module "files" returns ok for request 8
modcall: leaving group authorize (returns updated) for request 8
rad_check_password: Found Auth-Type EAP
auth: type "EAP"
Processing the authenticate section of radiusd.conf
modcall: entering group authenticate for request 8
rlm_eap: Request found, released from the list
rlm_eap: EAP/peap
rlm_eap: processing type peap
rlm_eap_peap: Authenticate
rlm_eap_tls: processing TLS
rlm_eap_tls: Received EAP-TLS ACK message
rlm_eap_tls: ack handshake is finished
eaptls_verify returned 3
eaptls_process returned 3
rlm_eap_peap: EAPTLS_SUCCESS
modcall[authenticate]: module "eap" returns handled for request 8
modcall: leaving group authenticate (returns handled) for request 8
Sending Access-Challenge of id 0 to 192.168.1.1 port 2057
EAP-Message =
0x010600501900170301002035248996f19e1afb8a94c4f381b5d8029c
231bf7d81da98d10102ddda32b5e9717030100207f0605fe898e801a56cabd84978e09d9d9179dab
b3e39b75ef3ad1b5c099b485
Message-Authenticator = 0x00000000000000000000000000000000
State = 0xf0145388890c19f2135d4831c6e0e410
Finished request 8
Going to the next request
--- Walking the entire request list ---
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 8 ID 0 with timestamp 452804be
Nothing to do. Sleeping until we see a request.

Costanza said:
The problem that stopped me using Beta 2, is still there with RC1 for me.

Trying to connect to WPA Enterprise, TKIP PEAP with certificate validation
off, and just using a username/password combination, no domain name. This
worked fine in XP.

I get asked for my username and password, provide the ones which have worked
for a long time in XP, and after 10-20 seconds I just get a baloon I saying I
need to provide additional credentials. I put them in again, and after a
similar length of time I am told I can not connect.

Anyone have this problem or any ideas on a solution?

---

Still can't connect to WPA Enterprise

Guest

Guest

Guest

J.P. Stewart [MSFT]

Guest

J.P. Stewart [MSFT]

Guest

Guest

Guest

J.P. Stewart [MSFT]

J.P. Stewart [MSFT]

Guest

J.P. Stewart [MSFT]

drahnier

Guest

Guest

Guest

Petely \(Peter Sloan\)

Guest