cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
934
Views
25
Helpful
13
Replies
ziqex
Beginner

Wireless disconnection - verifying disconnection reason in Prime

Facing the following issue.

Connection not working properly, connection is getting disconnecting frequently from AP that is connected to central WLC.

I have found the device in prime system, in the associated history I can see multiple disassociation for this specific device.

Is there and option to check disassociation reason in prime? I would like to find out what causes this random disconnections. Thanks.

 

Regards,

Daniel

 

13 REPLIES 13
marce1000
VIP Advisor

 

 - Foe starters make sure the controller used syslog and prime as destination syslog-server (for all events). Same for snmp-traps.

 M.

I can see the association history as attached but wonder how can I view the reason for disconnection. I can see snmp set to prime as one of the destinations. Thanks.

Leo Laohoo
VIP Community Legend

Do you have access to the WLC?

 

 -  Check required syslog setting too.

 M.

Do you mean syslog configuration on the actual WLC?

It points to the observium so we are notified when WLC goes down etc.

Thanks

 

The issue I am facing is only with with specific WLAN. The APs does not reboot at the time of disconnection. 

Issue description:

I have checked with other have same problem with mobile phones but working fine with their laptop and PC. Observation of Wi-Fi signals of mobile phone on ideal and browsing mode , few minutes signal strength shows good and moderate then sudden drops and disconnects for 30-40 sec then again reconnects…this is the continuous process through the day

 

any suggestion would be greatly appreciated

Scott Fella
Hall of Fame Master

Have you tried to look at a packet capture or investigate if the mobile device is disassociating because it’s going to sleep or power save? Run a debug on one of the clients and then search online the reason or status code you see from the debug. You can also import that debug on the Cisco online debug analyzer which can help parse the data. You should also post your wlan configuration so everyone has an idea of what the device is connecting to.
-Scott
*** Please rate helpful posts ***

I have run the debug for affected mobile device and found the following:

 

*osapiBsnTimer: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb 802.1x 'timeoutEvt' Timer expired for station ee:5c:4d:a1:ac:fb and for message = M0
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Retransmit failure for EAP-Request to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Sent Deauthenticate to mobile on BSSID 68:ca:e4:58:6f:01 slot 0(caller 1x_ptsm.c:634)
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Deleting the PMK cache when de-authenticating the client.
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb PMK: Sending cache delete
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Removing PMK cache entry for station ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Scheduling deletion of Mobile Station: (callerId: 57) in 10 seconds
*Dot1x_NW_MsgTask_3: Sep 29 14:43:03.318: ee:5c:4d:a1:ac:fb Freeing EAP Retransmit Bufer for mobile ee:5c:4d:a1:ac:fb
*apfMsConnTask_2: Sep 29 14:43:07.998: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Sep 29 14:43:07.998: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*osapiBsnTimer: Sep 29 14:43:13.317: ee:5c:4d:a1:ac:fb apfMsExpireCallback (apf_ms.c:637) Expiring Mobile!
*apfReceiveTask: Sep 29 14:43:13.318: ee:5c:4d:a1:ac:fb apfMsExpireMobileStation (apf_ms.c:7529) Changing state for mobile ee:5c:4d:a1:ac:fb on AP 68:ca:e4:58:6f:00 from Associated to Disassociated

*apfReceiveTask: Sep 29 14:43:13.318: ee:5c:4d:a1:ac:fb Scheduling deletion of Mobile Station: (callerId: 45) in 10 seconds
*apfMsConnTask_3: Sep 29 14:43:15.137: Ms with adaptive capability found

*apfMsConnTask_6: Sep 29 14:43:18.270: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Sep 29 14:43:18.270: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Sep 29 14:43:19.014: Ms with adaptive capability found

*apfMsConnTask_2: Sep 29 14:43:20.821: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Sep 29 14:43:20.821: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*osapiBsnTimer: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb apfMsExpireCallback (apf_ms.c:637) Expiring Mobile!
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb apfSendDisAssocMsgDebug (apf_80211.c:3549) Changing state for mobile ee:5c:4d:a1:ac:fb on AP 68:ca:e4:58:6f:00 from Disassociated to Disassociated

*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Sent Disassociate to mobile on AP 68:ca:e4:58:6f:00-0 (reason 1, caller apf_ms.c:7622)
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Sent Deauthenticate to mobile on BSSID 68:ca:e4:58:6f:01 slot 0(caller apf_ms.c:7624)
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Setting active key cache index 8 ---> 8
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Deleting the PMK cache when de-authenticating the client.
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Global PMK Cache deletion failed.
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Accounting NAI-Realm: rajiv.v, from Mscb username : rajiv.v
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb apfMsAssoStateDec
*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb apfMsExpireMobileStation (apf_ms.c:7665) Changing state for mobile ee:5c:4d:a1:ac:fb on AP 68:ca:e4:58:6f:00 from Disassociated to Idle

*apfReceiveTask: Sep 29 14:43:23.318: ee:5c:4d:a1:ac:fb Scheduling deletion of Mobile Station: (callerId: 47) in 10 seconds
*spamApTask4: Sep 29 14:43:23.319: ee:5c:4d:a1:ac:fb Delete Mobile request sent to the AP 10.96.71.36:5272

*Dot1x_NW_MsgTask_6: Sep 29 14:43:23.520: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_3: Sep 29 14:43:25.280: Ms with adaptive capability found

*apfMsConnTask_3: Sep 29 14:43:27.105: Ms with adaptive capability found

*apfMsConnTask_2: Sep 29 14:43:30.978: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Sep 29 14:43:30.978: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*osapiBsnTimer: Sep 29 14:43:33.318: ee:5c:4d:a1:ac:fb apfMsExpireCallback (apf_ms.c:637) Expiring Mobile!
*apfReceiveTask: Sep 29 14:43:33.318: ee:5c:4d:a1:ac:fb pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Sep 29 14:43:33.318: ee:5c:4d:a1:ac:fb 192.168.1.178 START (0) Deleted mobile LWAPP rule on AP [68:ca:e4:58:6f:00]
*apfReceiveTask: Sep 29 14:43:33.318: ee:5c:4d:a1:ac:fb Username entry 'rajiv.v' is deleted for mobile from the UserName table
*apfReceiveTask: Sep 29 14:43:33.318: ee:5c:4d:a1:ac:fb Username entry rajiv.v deleted for mobile
*apfReceiveTask: Sep 29 14:43:33.318: ee:5c:4d:a1:ac:fb Deleting mobile on AP 68:ca:e4:58:6f:00(0)
*apfMsConnTask_6: Sep 29 14:43:36.319: Ms with adaptive capability found
*apfMsConnTask_1: Sep 29 14:44:57.629: ee:5c:4d:a1:ac:fb Updating 11r vendor IE

*apfMsConnTask_1: Sep 29 14:44:57.629: ee:5c:4d:a1:ac:fb Processing assoc-req station:ee:5c:4d:a1:ac:fb AP:68:ca:e4:58:6f:00-00 thread:1a6c79a0
*apfMsConnTask_1: Sep 29 14:44:57.629: ee:5c:4d:a1:ac:fb Adding mobile on LWAPP AP 68:ca:e4:58:6f:00(0)
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Association received from mobile on BSSID 68:ca:e4:58:6f:04 AP AP-OM-MCT-SH-02
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Station: EE:5C:4D:A1:AC:FB trying to join WLAN with RSSI 196. Checking for XOR roam conditions on AP: 68:CA:E4:58:6F:00 Slot: 0
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Station: EE:5C:4D:A1:AC:FB is associating to AP 68:CA:E4:58:6F:00 which is not XOR roam capable
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Global 200 Clients are allowed to AP radio

*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Max Client Trap Threshold: 0 cur: 2

*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb override for default ap group, marking intgrp NULL
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Applying Interface(dc_staff) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Not re-applying interface policy for local switching Client

*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2931)
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2951)
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2972)
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb apfApplyWlanPolicy: Retaining (ACL [255] / Flexconnect ACL [65535]) recieved in AAA attributes on mobile
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb In processSsidIE:6509 setting Central switched to FALSE
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Applying site-specific Local Bridging override for station ee:5c:4d:a1:ac:fb - vapId 5, site 'Corp-Staff-Guest', interface 'dc_staff'
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Applying Local Bridging Interface Policy for station ee:5c:4d:a1:ac:fb - vlan 780, interface id 15, interface 'dc_staff'
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb override from ap group, removing intf group from mscb
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Applying site-specific override for station ee:5c:4d:a1:ac:fb - vapId 5, site 'Corp-Staff-Guest', interface 'dc_staff'
*apfMsConnTask_1: Sep 29 14:44:57.630: ee:5c:4d:a1:ac:fb Applying Interface(dc_staff) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Not re-applying interface policy for local switching Client

*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2931)
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2951)
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2972)
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Set Clinet Non AP specific WLAN apfMsAccessVlan = 781
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb STA - rates (6): 152 36 48 72 96 108 0 0 0 0 0 0 0 0 0 0
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_1: Sep 29 14:44:57.631: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_1: Sep 29 14:44:57.631: [0016] ac 01 0c 00

*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Processing RSN IE type 48, length 20 for mobile ee:5c:4d:a1:ac:fb
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb
ccmp128 is enabled
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Received 802.11i 802.1X key management suite, enabling dot1x Authentication
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb RSN Capabilities: 12
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Marking Mobile as non-11w Capable
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Received RSN IE with 0 PMKIDs from mobile ee:5c:4d:a1:ac:fb
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Setting active key cache index 8 ---> 8
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb unsetting PmkIdValidatedByAp
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Updating AID for REAP AP Client 68:ca:e4:58:6f:00 - AID ===> 3
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Initializing policy
*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_1: Sep 29 14:44:57.631: ee:5c:4d:a1:ac:fb Encryption policy is set to 0x80000001
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb 0.0.0.0 8021X_REQD (3) DHCP required on AP 68:ca:e4:58:6f:00 vapId 5 apVapId 2for this client
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb Not Using WMM Compliance code qosCap 00
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 68:ca:e4:58:6f:00 vapId 5 apVapId 2 flex-acl-name:
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb apfMsAssoStateInc
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb apfPemAddUser2 (apf_policy.c:437) Changing state for mobile ee:5c:4d:a1:ac:fb on AP 68:ca:e4:58:6f:00 from Idle to Associated

*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb apfPemAddUser2:session timeout forstation ee:5c:4d:a1:ac:fb - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb Sending assoc-resp with status 0 station:ee:5c:4d:a1:ac:fb AP:68:ca:e4:58:6f:00-00 on apVapId 2
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb Sending Assoc Response to station on BSSID 68:ca:e4:58:6f:01 (status 0) ApVapId 2 Slot 0
*apfMsConnTask_1: Sep 29 14:44:57.632: ee:5c:4d:a1:ac:fb apfProcessAssocReq (apf_80211.c:10884) Changing state for mobile ee:5c:4d:a1:ac:fb on AP 68:ca:e4:58:6f:00 from Associated to Associated

*spamApTask4: Sep 29 14:44:57.633: ee:5c:4d:a1:ac:fb Successful transmission of LWAPP Add-Mobile to AP 68:ca:e4:58:6f:00
*spamApTask4: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb Received ADD_MOBILE ack - Initiating 1x to STA ee:5c:4d:a1:ac:fb (idx 88)
*spamApTask4: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb Sent dot1x auth initiate message for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb reauth_sm state transition 0 ---> 1 for mobile ee:5c:4d:a1:ac:fb at 1x_reauth_sm.c:47
*Dot1x_NW_MsgTask_3: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb EAP-PARAM Debug - eap-params for Wlan-Id :5 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_3: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_3: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb Station ee:5c:4d:a1:ac:fb setting dot1x reauth timeout = 43200
*Dot1x_NW_MsgTask_3: Sep 29 14:44:57.795: ee:5c:4d:a1:ac:fb dot1x - moving mobile ee:5c:4d:a1:ac:fb into Connecting state
*Dot1x_NW_MsgTask_3: Sep 29 14:44:57.796: ee:5c:4d:a1:ac:fb Sending EAP-Request/Identity to mobile ee:5c:4d:a1:ac:fb (EAP Id 1)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb Received Identity Response (count=1) from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb Resetting reauth count 1 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb EAP State update from Connecting to Authenticating for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb dot1x - moving mobile ee:5c:4d:a1:ac:fb into Authenticating state
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.044: ee:5c:4d:a1:ac:fb Created Acct-Session-ID (5f73486a/ee:5c:4d:a1:ac:fb/11712) for the mobile
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.047: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.047: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=173) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.047: ee:5c:4d:a1:ac:fb WARNING: updated EAP-Identifier 1 ===> 173 for STA ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.047: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 173)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.047: ee:5c:4d:a1:ac:fb Allocating EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.210: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.210: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 173, EAP Type 3)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.210: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.210: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.212: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.212: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=174) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.212: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 174)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.212: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.379: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.379: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 174, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.379: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.379: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.386: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.386: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=175) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.386: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 175)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.386: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.580: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.580: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 175, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.580: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.580: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.582: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.582: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=176) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.582: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 176)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.582: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.743: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.743: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 176, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.743: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.743: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.745: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.745: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=177) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.745: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 177)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.745: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.909: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.909: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 177, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.909: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.909: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.911: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.911: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=178) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.911: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 178)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:58.911: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*apfMsConnTask_0: Sep 29 14:44:59.044: Ms with adaptive capability found

*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.076: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.076: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 178, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.076: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.076: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.078: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.078: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=179) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.078: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 179)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.078: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.245: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.245: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 179, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.245: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.245: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.246: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.246: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=180) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.246: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 180)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.246: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.448: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.448: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 180, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.448: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.448: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.452: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.452: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=181) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.452: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 181)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.452: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.614: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.614: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 181, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.614: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.614: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.616: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.617: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=182) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.617: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 182)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.617: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.780: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.780: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 182, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.780: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.780: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.782: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.782: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=183) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.782: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 183)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.782: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.947: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.947: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 183, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.947: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.947: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.956: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.956: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=184) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.956: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 184)
*Dot1x_NW_MsgTask_3: Sep 29 14:44:59.956: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.116: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.116: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 184, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.116: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.116: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.118: ee:5c:4d:a1:ac:fb Processing Access-Challenge for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.118: ee:5c:4d:a1:ac:fb Entering Backend Auth Req state (id=185) for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.118: ee:5c:4d:a1:ac:fb Sending EAP Request from AAA to mobile ee:5c:4d:a1:ac:fb (EAP Id 185)
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.118: ee:5c:4d:a1:ac:fb Reusing allocated memory for EAP Pkt for retransmission to mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.278: ee:5c:4d:a1:ac:fb Received EAPOL EAPPKT from mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.278: ee:5c:4d:a1:ac:fb Received EAP Response from mobile ee:5c:4d:a1:ac:fb (EAP Id 185, EAP Type 25)
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.278: ee:5c:4d:a1:ac:fb Resetting reauth count 0 to 0 for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.278: ee:5c:4d:a1:ac:fb Entering Backend Auth Response state for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.291: ee:5c:4d:a1:ac:fb Processing Access-Accept for mobile ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.291: ee:5c:4d:a1:ac:fb Resetting web IPv4 acl from 255 to 255

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.291: ee:5c:4d:a1:ac:fb Resetting web IPv4 Flex acl from 65535 to 65535

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.291: ee:5c:4d:a1:ac:fb Username entry (rajiv.v) created for mobile, length = 253
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.291: ee:5c:4d:a1:ac:fb Username entry (rajiv.v) created in mscb for mobile, length = 253
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Received MPPE_SEND_KEY: KeyLen: 32

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Received MPPE_RECV_KEY: KeyLen: 32

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb override for default ap group, marking intgrp NULL
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb apfApplyWlanPolicy: Retaining (ACL [255] / Flexconnect ACL [65535]) recieved in AAA attributes on mobile
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Inserting AAA Override struct for mobile
MAC: ee:5c:4d:a1:ac:fb, source 4

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Setting re-auth timeout to 43200 seconds, got from WLAN config.
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Station ee:5c:4d:a1:ac:fb setting dot1x reauth timeout = 43200
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Creating a PKC PMKID Cache entry for station ee:5c:4d:a1:ac:fb (RSN 2)
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Resetting MSCB PMK Cache Entry 0 for station ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Adding BSSID 68:ca:e4:58:6f:01 to PMKID cache at index 0 for station ee:5c:4d:a1:ac:fb
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: New PMKID: (16)

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: [0000] 72 de 3b d2 9c 5c 30 4e 5a d6 2c ac e1 b8 27 d6

*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb unsetting PmkIdValidatedByAp
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Zeroize AAA Overrides from local for station
*Dot1x_NW_MsgTask_3: Sep 29 14:45:00.292: ee:5c:4d:a1:ac:fb Adding Audit session ID payload in Mobility handoff

Scott Fella
Hall of Fame Master

What else have you done to troubleshoot? Do you know if the issue is with a specific phone model/type? Is the device going to sleep or power save? Is the device also configured to connect to another SSID? Did you try to upload your debug to the online debug analyzer? Post your wlan config.
-Scott
*** Please rate helpful posts ***
Scott Fella
Hall of Fame Master

I don’t see anything wrong from your debug:
Time Task TranslatedSep 29 14:44:57.630 *apfMsConnTask_1 Client made new Association to AP/BSSID BSSID 68:ca:e4:58:6f:04 AP AP-OM-MCT-SH-02
Sep 29 14:44:57.631 *apfMsConnTask_1 The WLC/AP has found from client association request Information Element that claims PMKID Caching support
Sep 29 14:44:57.631 *apfMsConnTask_1 The Reassociation Request from the client comes with 0 PMKID
Sep 29 14:44:57.631 *apfMsConnTask_1 Client is entering the 802.1x or PSK Authentication state
Sep 29 14:44:57.632 *apfMsConnTask_1 Client has successfully cleared AP association phase
Sep 29 14:44:57.632 *apfMsConnTask_1 WLC/AP is sending an Association Response to the client with status code 0 = Successful association
Sep 29 14:44:57.632 *apfMsConnTask_1 Client is trying to associate in 2.4 Ghz band
Sep 29 14:44:57.795 *Dot1x_NW_MsgTask_3 Client will be required to Reauthenticate in 43200
seconds
Sep 29 14:44:57.796 *Dot1x_NW_MsgTask_3 WLC/AP is sending EAP-Identity-Request to the client
Sep 29 14:44:58.044 *Dot1x_NW_MsgTask_3 Client sent EAP-Identity-Response to WLC/AP
Sep 29 14:45:00.291 *Dot1x_NW_MsgTask_3 RADIUS Server permitted access
Sep 29 14:45:00.292 *Dot1x_NW_MsgTask_3 Client will be required to Reauthenticate in 43200
seconds
-Scott
*** Please rate helpful posts ***

Debug was collected for the device.

Disconnected 12:12,- 2 times 12:14,12:15,12:18

 

I do not see any relevant messages for the above time stamps. Please advise if you see any relevant log message that could help with finding the cause of the issue. Thank you.

 

 

 

(Cisco Controller) >debug client EE:5C:4D:A1:AC:FB

(Cisco Controller) >*apfMsConnTask_1: Oct 05 11:59:43.700: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 11:59:45.952: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 11:59:45.977: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 11:59:48.927: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 11:59:52.647: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 11:59:53.928: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 11:59:57.000: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 11:59:58.135: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:00:08.648: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:00:09.522: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:00:10.040: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:00:10.040: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_0: Oct 05 12:00:14.018: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_0: Oct 05 12:00:14.018: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:00:15.679: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:00:16.637: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:00:20.790: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:00:26.375: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:00:29.715: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:00:29.715: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:00:30.943: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:00:31.169: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:00:31.169: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:00:41.305: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:00:46.460: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:00:52.528: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:00:57.443: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:01:05.039: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:01:13.000: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:01:58.376: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:02:22.209: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:02:33.247: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:02:33.247: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:02:33.485: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:02:35.445: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:02:36.000: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:02:36.000: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:02:37.406: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:02:38.238: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:02:40.962: Ms with adaptive capability found

*apfMsConnTask_0: Oct 05 12:02:41.204: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:02:43.283: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:02:44.407: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:02:52.302: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:02:56.406: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:02:57.518: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:02:58.954: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:03:00.382: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:03:03.168: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:03:06.614: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:03:06.614: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:03:06.994: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:03:07.505: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:07.971: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:03:09.315: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:03:09.712: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:14.449: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:03:14.768: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:15.145: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:03:18.296: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:03:18.296: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:03:18.413: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:03:19.169: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:19.955: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:03:21.198: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:21.309: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:03:22.614: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:26.246: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:28.679: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:03:29.704: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:30.137: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:03:30.494: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:03:30.614: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:32.350: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:03:36.475: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:03:37.027: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:37.571: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:03:38.518: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:03:38.518: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:03:39.406: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:41.815: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:03:42.477: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:03:43.067: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:44.635: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:49.073: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:03:49.073: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:03:49.971: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:03:51.139: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:03:51.601: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:03:53.304: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:03:56.607: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_0: Oct 05 12:03:56.866: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_0: Oct 05 12:03:56.866: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:03:57.394: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:04:01.444: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:04:01.636: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:04:01.636: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:04:01.760: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:04:02.432: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:04:03.667: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:04:03.667: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:04:05.979: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:04:06.197: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:04:06.197: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:04:08.564: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:04:08.841: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:04:08.841: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:04:13.365: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:04:19.112: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:04:19.112: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:04:27.089: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:04:27.089: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_0: Oct 05 12:04:32.336: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:04:56.610: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:05:07.083: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:05:09.631: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:05:14.306: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:05:18.863: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:05:24.768: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:05:24.769: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:05:30.627: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:05:33.514: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:05:44.285: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:05:44.285: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:05:47.296: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:05:49.855: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:05:54.439: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:05:56.619: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:05:56.619: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:06:01.177: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:06:01.212: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:06:04.891: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:06:06.022: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:06:17.641: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:06:17.641: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:06:26.905: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:06:26.905: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:06:29.195: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:06:29.195: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:06:33.938: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:06:38.550: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:06:42.948: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:06:44.439: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:06:44.439: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:06:45.717: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:06:45.717: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:06:49.529: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:06:50.188: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:06:50.484: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:06:52.949: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:06:54.988: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:06:55.596: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:07:05.095: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:07:17.471: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:07:17.471: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*Dot1x_NW_MsgTask_5: Oct 05 12:07:17.916: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_3: Oct 05 12:08:11.506: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:08:25.961: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:08:28.875: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:08:53.102: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:09:04.556: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:09:31.426: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:09:35.988: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:09:38.018: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:09:38.019: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*Dot1x_NW_MsgTask_4: Oct 05 12:09:45.707: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_4: Oct 05 12:09:47.485: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:09:52.855: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:09:52.855: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:10:02.957: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:10:03.480: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:10:04.204: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:10:04.204: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:10:05.801: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:10:06.801: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:10:06.801: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:10:09.534: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:10:09.534: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:10:10.352: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:10:11.888: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:10:14.713: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:10:15.672: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:10:18.745: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:10:23.495: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:10:29.303: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:10:31.618: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:10:33.765: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:10:34.612: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:10:38.327: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:10:40.317: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:10:40.676: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:10:46.924: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:10:46.926: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:10:46.926: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*Dot1x_NW_MsgTask_1: Oct 05 12:10:48.657: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_5: Oct 05 12:10:51.276: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:10:54.227: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:10:59.705: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:11:04.614: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:11:47.625: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:11:49.266: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:11:59.663: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:00.249: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:12:00.249: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:12:02.364: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:04.727: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:12:16.091: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:12:17.478: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:12:25.813: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:12:34.244: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:36.798: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:12:36.798: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:12:37.447: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:12:38.211: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:39.008: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:40.780: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:12:40.780: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:12:46.904: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:12:48.711: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:50.908: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:55.288: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:12:55.288: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:12:57.448: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:57.512: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:12:57.637: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:12:58.732: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:13:01.550: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:13:04.346: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:13:06.510: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:13:07.663: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:13:13.569: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:13:14.215: Ms with adaptive capability found

*Dot1x_NW_MsgTask_6: Oct 05 12:13:16.782: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_3: Oct 05 12:13:30.801: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:13:35.510: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:13:36.138: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:13:36.261: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:13:37.992: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:13:38.683: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:13:39.499: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:13:40.754: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:13:41.050: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:13:41.617: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:13:41.617: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:13:42.377: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:13:44.572: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:13:54.388: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:13:55.667: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:14:11.027: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:14:11.027: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:14:11.030: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:14:18.662: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:14:19.763: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:14:19.763: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:14:21.632: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:14:21.632: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:14:26.335: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:14:30.380: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:14:32.880: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:14:32.880: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:14:33.273: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:14:33.652: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:14:38.112: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:14:40.194: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:14:40.194: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:14:43.277: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:14:46.887: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:14:48.998: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:14:50.341: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:14:53.220: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:14:53.270: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:14:55.386: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:15:04.593: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:15:04.593: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_3: Oct 05 12:15:05.120: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:15:05.338: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:15:06.045: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:15:09.376: Ms with adaptive capability found

*Dot1x_NW_MsgTask_6: Oct 05 12:15:12.192: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_3: Oct 05 12:15:17.033: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*Dot1x_NW_MsgTask_6: Oct 05 12:15:22.841: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_7: Oct 05 12:15:34.850: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:15:51.778: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:16:02.911: Ms with adaptive capability found

*Dot1x_NW_MsgTask_0: Oct 05 12:16:05.042: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_1: Oct 05 12:16:08.456: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:16:08.456: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:16:21.182: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:16:21.182: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:16:21.189: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:16:23.457: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:16:25.679: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:16:27.734: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:16:32.483: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:16:34.627: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:16:36.821: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:16:41.055: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:05.539: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:06.353: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:11.169: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:13.733: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:18.380: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:20.189: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:17:21.120: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:17:22.672: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:25.613: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:17:26.700: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:17:28.033: Ms with adaptive capability found

*apfMsConnTask_3: Oct 05 12:17:28.072: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:17:30.523: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:17:31.639: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:17:32.983: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:17:35.798: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:17:38.437: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*Dot1x_NW_MsgTask_0: Oct 05 12:17:39.241: CCKM: Sending CCKM PMK (Version_2) information to mobility group
*apfMsConnTask_3: Oct 05 12:17:48.147: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:17:54.088: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:17:54.281: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:17:54.281: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:17:54.717: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:17:54.717: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:17:54.824: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:17:54.824: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:17:57.810: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:18:07.026: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:18:07.026: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:18:24.061: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:18:40.626: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:18:44.921: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:18:47.247: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:18:53.881: Ms with adaptive capability found

*apfMsConnTask_4: Oct 05 12:18:56.578: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_4: Oct 05 12:18:56.578: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:19:05.832: Ms with adaptive capability found

debug
(Cisco Controller) debug>*apfMsConnTask_0: Oct 05 12:19:18.088: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:19:22.131: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:19:22.131: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:19:24.329: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:19:24.329: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:19:25.103: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:19:32.208: Ms with adaptive capability found

*apfMsConnTask_7: Oct 05 12:19:32.512: Ms with adaptive capability found

*apfMsConnTask_6: Oct 05 12:19:37.719: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:19:37.719: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
debug disable-all

Incorrect usage. Use the '?' or <TAB> key to list commands.

(Cisco Controller) debug>debug disable-all

Incorrect usage. Use the '?' or <TAB> key to list commands.

(Cisco Controller) debug>*apfMsConnTask_6: Oct 05 12:19:45.246: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_6: Oct 05 12:19:45.246: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:19:45.981: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:19:45.982: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0

(Cisco Controller) debug>debug disable-all*apfMsConnTask_2: Oct 05 12:19:46.749: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:19:46.927: Ms with adaptive capability found

debug disable-all

Incorrect usage. Use the '?' or <TAB> key to list commands.

(Cisco Controller) debug>debug disable-all

Incorrect usage. Use the '?' or <TAB> key to list commands.

(Cisco Controller) debug>*apfMsConnTask_1: Oct 05 12:19:56.421: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:19:56.421: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_2: Oct 05 12:19:56.881: Ms with adaptive capability found

*apfMsConnTask_5: Oct 05 12:19:59.598: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_5: Oct 05 12:19:59.598: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_7: Oct 05 12:20:02.300: Ms with adaptive capability found

*apfMsConnTask_1: Oct 05 12:20:04.451: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0
*apfMsConnTask_1: Oct 05 12:20:04.451: dot1xDoesPmkIdMatchPmk2, Received 11w Flag: 0

(Cisco Controller) debug>
(Cisco Controller) debug>debug disable-all

Incorrect usage. Use the '?' or <TAB> key to list commands.

(Cisco Controller) debug>
(Cisco Controller) debug>
(Cisco Controller) debug>debug disable-all

Incorrect usage. Use the '?' or <TAB> key to list commands.

(Cisco Controller) debug>
(Cisco Controller) debug>*apfMsConnTask_1: Oct 05 12:20:06.828: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:20:07.800: Ms with adaptive capability found


(Cisco Controller) debug>exit*apfMsConnTask_2: Oct 05 12:20:11.960: Ms with adaptive capability found

*apfMsConnTask_2: Oct 05 12:20:13.381: Ms with adaptive capability found

 

Jesus Pavon
Participant

If you have access to WLC CLI, you can create a debug session for that MAC and see the DISASSOCIATION packet with the reason code, if not, you can try to make a packet capture over-the-air (OTA) (with MAC Books this is an embedded feature, with Linux you only need a compatible wireless USB dongle and there are lot of them, and with Windows, you need to pay for such tools as there isn't many free monitor drivers out there).

Moreover, you can try to create an operating system trace to capture that event:

Windows: https://docs.microsoft.com/en-us/windows/client-management/advanced-troubleshooting-wireless-network-connectivity
Windows: https://docs.microsoft.com/en-us/windows/client-management/data-collection-for-802-authentication

Linux: https://wireless.wiki.kernel.org/en/users/drivers/iwlwifi/debugging

MacOS: https://developer.apple.com/documentation/network/recording_a_packet_trace

 

HTH
-Jesus
*** Please Rate Helpful Responses ***

ziqex
Beginner

We have identified the issue. The affected APs were not a members of the flexconnect group. The debugs did not provide any disassociation reason code. Thank you.

 

I was looking for a simpler explanation of the solution to this problem. Seems like I will have to contact an IT specialist

Content for Community-Ad