Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. And see here for current known issues.

New Member

802.1x 'txWhen' Timer expired

Hello All,

802.11a clients can connect WLC,But 802.11b/g/n clients can't connect WLC。

Who encountered  similar problems? How to solve?

The following is the Debug Output:

(Cisco Controller) >debug client 00:0c:e7:41:37:88

(Cisco Controller) >*osapiBsnTimer: Jan 08 16:36:27.823: 00:0c:e7:41:37:88 802.1x 'txWhen' Timer expired for station 00:0c:e7:41:37:88 and for message = M0

*dot1xMsgTask: Jan 08 16:36:27.823: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:36:27.824: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 2)

*emWeb: Jan 08 16:36:51.774: 00:0c:e7:41:37:88 Central Switch = TRUE

*osapiBsnTimer: Jan 08 16:36:57.823: 00:0c:e7:41:37:88 802.1x 'txWhen' Timer expired for station 00:0c:e7:41:37:88 and for message = M0

*dot1xMsgTask: Jan 08 16:36:57.823: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:36:57.823: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 3)

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Association received from mobile on BSSID f8:4f:57:e2:2a:50

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 74

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 In processSsidIE:4619 setting Central switched to FALSE

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Applying site-specific Local Bridging override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Applying Local Bridging Interface Policy for station 00:0c:e7:41:37:88 - vlan 74, interface id 17, interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:37:09.056: 00:0c:e7:41:37:88 Applying site-specific override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 10

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 STA - rates (8): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 STA - rates (12): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 Processing RSN IE type 48, length 20 for mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 Received RSN IE with 0 PMKIDs from mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 Setting active key cache index 8 ---> 8

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 unsetting PmkIdValidatedByAp

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_0: Jan 08 16:37:09.057: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f8:4f:57:e2:2a:50 vapId 1 apVapId 1 flex-acl-name:

*apfMsConnTask_0: Jan 08 16:37:09.058: 00:0c:e7:41:37:88 apfPemAddUser2 (apf_policy.c:333) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*apfMsConnTask_0: Jan 08 16:37:09.058: 00:0c:e7:41:37:88 apfPemAddUser2:session timeout forstation 00:0c:e7:41:37:88 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_0: Jan 08 16:37:09.058: 00:0c:e7:41:37:88 Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_0: Jan 08 16:37:09.058: 00:0c:e7:41:37:88 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 08 16:37:09.058: 00:0c:e7:41:37:88 Sending Assoc Response to station on BSSID f8:4f:57:e2:2a:50 (status 0) ApVapId 1 Slot 0

*apfMsConnTask_0: Jan 08 16:37:09.058: 00:0c:e7:41:37:88 apfProcessAssocReq (apf_80211.c:7957) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*dot1xMsgTask: Jan 08 16:37:09.060: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:37:09.060: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 1)

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Association received from mobile on BSSID f8:4f:57:e2:2a:50

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 74

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 In processSsidIE:4619 setting Central switched to FALSE

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Applying site-specific Local Bridging override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Applying Local Bridging Interface Policy for station 00:0c:e7:41:37:88 - vlan 74, interface id 17, interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Applying site-specific override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:37:22.328: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 10

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 STA - rates (8): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 STA - rates (12): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 Processing RSN IE type 48, length 20 for mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 Received RSN IE with 0 PMKIDs from mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 Setting active key cache index 8 ---> 8

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 unsetting PmkIdValidatedByAp

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_0: Jan 08 16:37:22.329: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f8:4f:57:e2:2a:50 vapId 1 apVapId 1 flex-acl-name:

*apfMsConnTask_0: Jan 08 16:37:22.330: 00:0c:e7:41:37:88 apfPemAddUser2 (apf_policy.c:333) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*apfMsConnTask_0: Jan 08 16:37:22.330: 00:0c:e7:41:37:88 apfPemAddUser2:session timeout forstation 00:0c:e7:41:37:88 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_0: Jan 08 16:37:22.330: 00:0c:e7:41:37:88 Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_0: Jan 08 16:37:22.330: 00:0c:e7:41:37:88 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 08 16:37:22.330: 00:0c:e7:41:37:88 Sending Assoc Response to station on BSSID f8:4f:57:e2:2a:50 (status 0) ApVapId 1 Slot 0

*apfMsConnTask_0: Jan 08 16:37:22.330: 00:0c:e7:41:37:88 apfProcessAssocReq (apf_80211.c:7957) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*dot1xMsgTask: Jan 08 16:37:22.333: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:37:22.333: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 1)

*osapiBsnTimer: Jan 08 16:37:52.223: 00:0c:e7:41:37:88 802.1x 'txWhen' Timer expired for station 00:0c:e7:41:37:88 and for message = M0

*dot1xMsgTask: Jan 08 16:37:52.223: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:37:52.223: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 2)

*osapiBsnTimer: Jan 08 16:38:22.227: 00:0c:e7:41:37:88 802.1x 'txWhen' Timer expired for station 00:0c:e7:41:37:88 and for message = M0

*dot1xMsgTask: Jan 08 16:38:22.227: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:38:22.227: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 3)

*emWeb: Jan 08 16:38:26.697: 00:0c:e7:41:37:88 Central Switch = TRUE

*apfMsConnTask_0: Jan 08 16:38:33.459: 00:0c:e7:41:37:88 Association received from mobile on BSSID f8:4f:57:e2:2a:50

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 74

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 In processSsidIE:4619 setting Central switched to FALSE

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Applying site-specific Local Bridging override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Applying Local Bridging Interface Policy for station 00:0c:e7:41:37:88 - vlan 74, interface id 17, interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Applying site-specific override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 10

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_0: Jan 08 16:38:33.460: 00:0c:e7:41:37:88 processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 STA - rates (8): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 STA - rates (12): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 Processing RSN IE type 48, length 20 for mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 Received RSN IE with 0 PMKIDs from mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 Setting active key cache index 8 ---> 8

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 unsetting PmkIdValidatedByAp

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f8:4f:57:e2:2a:50 vapId 1 apVapId 1 flex-acl-name:

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 apfPemAddUser2 (apf_policy.c:333) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 apfPemAddUser2:session timeout forstation 00:0c:e7:41:37:88 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_0: Jan 08 16:38:33.461: 00:0c:e7:41:37:88 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 08 16:38:33.462: 00:0c:e7:41:37:88 Sending Assoc Response to station on BSSID f8:4f:57:e2:2a:50 (status 0) ApVapId 1 Slot 0

*apfMsConnTask_0: Jan 08 16:38:33.462: 00:0c:e7:41:37:88 apfProcessAssocReq (apf_80211.c:7957) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*dot1xMsgTask: Jan 08 16:38:33.465: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:38:33.466: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 1)

*apfMsConnTask_0: Jan 08 16:38:39.631: 00:0c:e7:41:37:88 Association received from mobile on BSSID f8:4f:57:e2:2a:50

*apfMsConnTask_0: Jan 08 16:38:39.631: 00:0c:e7:41:37:88 Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 08 16:38:39.631: 00:0c:e7:41:37:88 Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 74

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 In processSsidIE:4619 setting Central switched to FALSE

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Applying site-specific Local Bridging override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Applying Local Bridging Interface Policy for station 00:0c:e7:41:37:88 - vlan 74, interface id 17, interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Applying site-specific override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 10

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 STA - rates (8): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 STA - rates (12): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:38:39.632: 00:0c:e7:41:37:88 extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Processing RSN IE type 48, length 20 for mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Received RSN IE with 0 PMKIDs from mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Setting active key cache index 8 ---> 8

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 unsetting PmkIdValidatedByAp

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f8:4f:57:e2:2a:50 vapId 1 apVapId 1 flex-acl-name:

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 apfPemAddUser2 (apf_policy.c:333) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 apfPemAddUser2:session timeout forstation 00:0c:e7:41:37:88 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 Sending Assoc Response to station on BSSID f8:4f:57:e2:2a:50 (status 0) ApVapId 1 Slot 0

*apfMsConnTask_0: Jan 08 16:38:39.633: 00:0c:e7:41:37:88 apfProcessAssocReq (apf_80211.c:7957) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*dot1xMsgTask: Jan 08 16:38:39.635: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:38:39.635: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 1)

*apfMsConnTask_0: Jan 08 16:38:45.464: 00:0c:e7:41:37:88 Association received from mobile on BSSID f8:4f:57:e2:2a:50

*apfMsConnTask_0: Jan 08 16:38:45.464: 00:0c:e7:41:37:88 Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 08 16:38:45.464: 00:0c:e7:41:37:88 Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_0: Jan 08 16:38:45.464: 00:0c:e7:41:37:88 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 74

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 In processSsidIE:4619 setting Central switched to FALSE

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Applying site-specific Local Bridging override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Applying Local Bridging Interface Policy for station 00:0c:e7:41:37:88 - vlan 74, interface id 17, interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Applying site-specific override for station 00:0c:e7:41:37:88 - vapId 1, site 'CNWX-APGR', interface 'cnwx_interface'

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 10

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 Re-applying interface policy for client

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2164)

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2185)

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 STA - rates (8): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 STA - rates (12): 130 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0

*apfMsConnTask_0: Jan 08 16:38:45.465: 00:0c:e7:41:37:88 extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Processing RSN IE type 48, length 20 for mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Received RSN IE with 0 PMKIDs from mobile 00:0c:e7:41:37:88

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Setting active key cache index 8 ---> 8

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 unsetting PmkIdValidatedByAp

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f8:4f:57:e2:2a:50 vapId 1 apVapId 1 flex-acl-name:

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 apfPemAddUser2 (apf_policy.c:333) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 apfPemAddUser2:session timeout forstation 00:0c:e7:41:37:88 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 Sending Assoc Response to station on BSSID f8:4f:57:e2:2a:50 (status 0) ApVapId 1 Slot 0

*apfMsConnTask_0: Jan 08 16:38:45.466: 00:0c:e7:41:37:88 apfProcessAssocReq (apf_80211.c:7957) Changing state for mobile 00:0c:e7:41:37:88 on AP f8:4f:57:e2:2a:50 from Associated to Associated

*dot1xMsgTask: Jan 08 16:38:45.469: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

*dot1xMsgTask: Jan 08 16:38:45.469: 00:0c:e7:41:37:88 Sending EAP-Request/Identity to mobile 00:0c:e7:41:37:88 (EAP Id 1)

*osapiBsnTimer: Jan 08 16:39:15.431: 00:0c:e7:41:37:88 802.1x 'txWhen' Timer expired for station 00:0c:e7:41:37:88 and for message = M0

*dot1xMsgTask: Jan 08 16:39:15.431: 00:0c:e7:41:37:88 dot1x - moving mobile 00:0c:e7:41:37:88 into Connecting state

Everyone's tags (3)
5 REPLIES
VIP Purple

Re: 802.1x 'txWhen' Timer expired

Hi Liu,

Which controller you have ??

Firmware on controller, paster the output of the command: sh sysinfo

whcih kind of encryption are you using?

1. You must use wpa/tkip or wpa2/aes (Not mixed).

2. session timer must be higher than the user idle timer . So try to increase the idle timer to 7200.

Regards

New Member

802.1x 'txWhen' Timer expired

Thanks your answer.

My controller is 5508 and version is 7.5.102.0.

I used wpa2/aes.

session timeout is 86400.

802.11n clients also can't connect WLC.

Regards

VIP Purple

Re: 802.1x 'txWhen' Timer expired

HI Liang,

check your advanced eap timers on the WLC.  Run "show advanced eap" from the CLI.  It feels like you need to increase your EAP Request Identity timeout.

Try this:

Config wps client-exclusion all disable

Config advanced eap identity-request-timeout 30

Config advanced eap identity-request-retries 10

Config advanced eap request-timeout 30

Config advanced eap request-retries 10

config 802.11b disable network

config 802.11b preamble long

config 802.11b enable network

Regards

Dont forget to rate helpful posts.

New Member

802.1x 'txWhen' Timer expired

Hi sandeepchoudhary21,

Thank you very much.

I didn't make any changes on WLC.But now 802.11g clients can connect WLC.

I cann't find the root reason.

Thanks for your help again.

Best Regards

Liang

Hall of Fame Super Silver

802.1x 'txWhen' Timer expired

Can you post your show wlan for the SSID that your having issues with?

Thanks,

Scott

Help out other by using the rating system and marking answered questions as "Answered"

-Scott
*** Please rate helpful posts ***
826
Views
0
Helpful
5
Replies
CreatePlease login to create content