Mobile devices are not getting connected Wifi

[susanth_ks]

We have encountered new issue that, Mobile are not getting connected with WiFi network and it was working fine before 1 week. WLC model is 5520

Laptops are Still working fine.

It will show trying to connect when we try to connect mobile devices.

one of the mobile MAC: 4c:4f:ee:0a:0d:d8

Logs from WLC

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2021.11.22 08:35:38 =~=~=~=~=~=~=~=~=~=~=~=
4c:4f:ee:0a:0d:d8

(Cisco Controller) >*apfOpenDtlSocket: Nov 22 08:38:46.408: 4c:4f:ee:0a:0d:d8 Recevied management frame REASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:8e destination addr 00:38:df:3b:3e:8e
*spamApTask4: Nov 22 08:38:46.414: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 47)
*spamApTask4: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Removing BSSID 54:a2:74:f2:e6:a1 from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:8e at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: [0000] dd 14 00 0f ac 04 96 89 6f e0 92 71 96 4d 23 87

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: [0016] 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.417: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.424: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:38:46.432: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*apfOpenDtlSocket: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Recevied management frame ASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:8e destination addr 00:38:df:3b:3e:8e
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -50. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Association received from mobile on BSSID 00:38:df:3b:3e:77 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -50. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Global 200 Clients are allowed to AP radio

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 6

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 STA - rates (4): 176 72 96 108 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:39:23.744: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:39:23.744: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_2: Nov 22 08:39:23.744: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 AID 6 in Assoc Req from flex AP 00:38:df:3b:3e:80 is same as in mscb 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 on apVapId 2
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 VHT Operation IE: width 80/1 ch 52 freq0 58 freq1 0 msc0 0x3f msc1 0x3f
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:8e ApVapId 2 Slot 1, mobility role 1
*apfMsConnTask_2: Nov 22 08:39:23.744: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:39:23.745: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 56)
*spamApTask4: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:8e from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.753: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:8e at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: [0000] dd 14 00 0f ac 04 96 89 6f e0 92 71 96 4d 23 87

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: [0016] 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.754: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.763: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*spamApTask4: Nov 22 08:39:23.772: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*apfOpenDtlSocket: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Recevied management frame ASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:81 destination addr 00:38:df:3b:3e:81
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-00 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -31. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 0
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Association received from mobile on BSSID 00:38:df:3b:3e:98 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -31. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 0
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 7

*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 override from ap group, removing intf group from mscb
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:39:45.825: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 STA - rates (8): 22 12 18 24 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 STA - rates (9): 22 12 18 24 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:39:45.826: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:39:45.826: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Deleted mobile LWAPP rule on AP [00:38:df:3b:3e:80]
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Succesfully freed AID 6, slot 1 on AP 00:38:df:3b:3e:80, #client on this slot 4
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 New ctxOwnerMwarIp: 10.228.159.249 New ctxOwnerApMac: 00:38:DF:3B:3E:80 New ctxOwnerApEthMac: 00:38:DF:3A:36:00 New ctxOwnerApSlotId: 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Updated location for station old AP 00:38:df:3b:3e:80 oldSlot 1, new AP 00:38:df:3b:3e:80 newSlot 0, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Updating AID for REAP AP Client 00:38:df:3b:3e:80 - AID ===> 15
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-00 on apVapId 2
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:81 ApVapId 2 Slot 0, mobility role 1
*apfMsConnTask_2: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Delete Mobile request on slot 1 sent to the AP 00:38:df:3b:3e:80 IP: 10.228.70.8:5264

*spamApTask4: Nov 22 08:39:45.826: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:39:45.833: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 67)
*spamApTask4: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:8e from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:81 at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: [0000] 54 88 96 7c 36 4e 6a 42 db 32 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:81 in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:81 in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: [0000] 54 88 96 7c 36 4e 6a 42 db 32 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: [0000] dd 14 00 0f ac 04 54 88 96 7c 36 4e 6a 42 db 32

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: [0016] 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.841: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.851: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*spamApTask4: Nov 22 08:39:45.862: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*apfOpenDtlSocket: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Recevied management frame REASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:8e destination addr 00:38:df:3b:3e:8e
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -43. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Reassociation received from mobile on BSSID 00:38:df:3b:3e:77 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -43. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Global 200 Clients are allowed to AP radio

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 override from ap group, removing intf group from mscb
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 STA - rates (4): 176 72 96 108 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:39:55.465: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:39:55.465: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_2: Nov 22 08:39:55.465: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Deleted mobile LWAPP rule on AP [00:38:df:3b:3e:80]
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Succesfully freed AID 15, slot 0 on AP 00:38:df:3b:3e:80, #client on this slot 7
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 New ctxOwnerMwarIp: 10.228.159.249 New ctxOwnerApMac: 00:38:DF:3B:3E:80 New ctxOwnerApEthMac: 00:38:DF:3A:36:00 New ctxOwnerApSlotId: 1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Updated location for station old AP 00:38:df:3b:3e:80 oldSlot 0, new AP 00:38:df:3b:3e:80 newSlot 1, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Updating AID for REAP AP Client 00:38:df:3b:3e:80 - AID ===> 6
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 on apVapId 2
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:8e ApVapId 2 Slot 1, mobility role 1
*apfMsConnTask_2: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Delete Mobile request on slot 0 sent to the AP 00:38:df:3b:3e:80 IP: 10.228.70.8:5264

*spamApTask4: Nov 22 08:39:55.465: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:39:55.472: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 71)
*spamApTask4: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:81 from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:8e at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: [0000] dd 14 00 0f ac 04 96 89 6f e0 92 71 96 4d 23 87

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: [0016] 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.480: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.486: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:39:55.493: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.493: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.493: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.493: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*spamApTask4: Nov 22 08:39:55.494: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*apfOpenDtlSocket: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Recevied management frame ASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:81 destination addr 00:38:df:3b:3e:81
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-00 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -35. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Association received from mobile on BSSID 00:38:df:3b:3e:98 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -35. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Global 200 Clients are allowed to AP radio

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 7

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 override from ap group, removing intf group from mscb
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 STA - rates (8): 22 12 18 24 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 STA - rates (9): 22 12 18 24 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:40:07.832: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:40:07.832: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_2: Nov 22 08:40:07.832: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Deleted mobile LWAPP rule on AP [00:38:df:3b:3e:80]
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Succesfully freed AID 6, slot 1 on AP 00:38:df:3b:3e:80, #client on this slot 4
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 New ctxOwnerMwarIp: 10.228.159.249 New ctxOwnerApMac: 00:38:DF:3B:3E:80 New ctxOwnerApEthMac: 00:38:DF:3A:36:00 New ctxOwnerApSlotId: 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Updated location for station old AP 00:38:df:3b:3e:80 oldSlot 1, new AP 00:38:df:3b:3e:80 newSlot 0, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Updating AID for REAP AP Client 00:38:df:3b:3e:80 - AID ===> 15
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-00 on apVapId 2
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:81 ApVapId 2 Slot 0, mobility role 1
*apfMsConnTask_2: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Delete Mobile request on slot 1 sent to the AP 00:38:df:3b:3e:80 IP: 10.228.70.8:5264

*spamApTask4: Nov 22 08:40:07.832: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:40:07.839: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 75)
*spamApTask4: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:8e from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:81 at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: [0000] 54 88 96 7c 36 4e 6a 42 db 32 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:81 in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:81 in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: [0000] 54 88 96 7c 36 4e 6a 42 db 32 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: [0000] dd 14 00 0f ac 04 54 88 96 7c 36 4e 6a 42 db 32

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: [0016] 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.847: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.856: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*spamApTask4: Nov 22 08:40:07.864: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*apfOpenDtlSocket: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Recevied management frame REASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:8e destination addr 00:38:df:3b:3e:8e
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -37. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Reassociation received from mobile on BSSID 00:38:df:3b:3e:77 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -37. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Global 200 Clients are allowed to AP radio

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 STA - rates (4): 176 72 96 108 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:40:21.949: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:40:21.949: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_2: Nov 22 08:40:21.949: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Deleted mobile LWAPP rule on AP [00:38:df:3b:3e:80]
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Succesfully freed AID 15, slot 0 on AP 00:38:df:3b:3e:80, #client on this slot 7
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 New ctxOwnerMwarIp: 10.228.159.249 New ctxOwnerApMac: 00:38:DF:3B:3E:80 New ctxOwnerApEthMac: 00:38:DF:3A:36:00 New ctxOwnerApSlotId: 1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Updated location for station old AP 00:38:df:3b:3e:80 oldSlot 0, new AP 00:38:df:3b:3e:80 newSlot 1, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Updating AID for REAP AP Client 00:38:df:3b:3e:80 - AID ===> 6
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 on apVapId 2
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:8e ApVapId 2 Slot 1, mobility role 1
*apfMsConnTask_2: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Delete Mobile request on slot 0 sent to the AP 00:38:df:3b:3e:80 IP: 10.228.70.8:5264

*spamApTask4: Nov 22 08:40:21.949: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:40:21.957: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 78)
*spamApTask4: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:81 from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:8e at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: [0000] dd 14 00 0f ac 04 96 89 6f e0 92 71 96 4d 23 87

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: [0016] 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.965: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.972: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Freeing EAP 4 Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.979: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.980: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.980: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.980: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.980: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:40:21.980: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*spamApTask4: Nov 22 08:40:21.980: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*apfOpenDtlSocket: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Recevied management frame ASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:81 destination addr 00:38:df:3b:3e:81
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-00 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -38. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Association received from mobile on BSSID 00:38:df:3b:3e:98 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -38. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Global 200 Clients are allowed to AP radio

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 7

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 override from ap group, removing intf group from mscb
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 STA - rates (8): 22 12 18 24 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 STA - rates (9): 22 12 18 24 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:40:32.020: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:40:32.020: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_2: Nov 22 08:40:32.020: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Deleted mobile LWAPP rule on AP [00:38:df:3b:3e:80]
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Succesfully freed AID 6, slot 1 on AP 00:38:df:3b:3e:80, #client on this slot 4
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 New ctxOwnerMwarIp: 10.228.159.249 New ctxOwnerApMac: 00:38:DF:3B:3E:80 New ctxOwnerApEthMac: 00:38:DF:3A:36:00 New ctxOwnerApSlotId: 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Updated location for station old AP 00:38:df:3b:3e:80 oldSlot 1, new AP 00:38:df:3b:3e:80 newSlot 0, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Updating AID for REAP AP Client 00:38:df:3b:3e:80 - AID ===> 15
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-00 on apVapId 2
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:81 ApVapId 2 Slot 0, mobility role 1
*apfMsConnTask_2: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Delete Mobile request on slot 1 sent to the AP 00:38:df:3b:3e:80 IP: 10.228.70.8:5264

*spamApTask4: Nov 22 08:40:32.020: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:40:32.028: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 81)
*spamApTask4: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:8e from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:81 at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: [0000] 54 88 96 7c 36 4e 6a 42 db 32 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:81 in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:81 in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: [0000] 54 88 96 7c 36 4e 6a 42 db 32 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: [0000] dd 14 00 0f ac 04 54 88 96 7c 36 4e 6a 42 db 32

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: [0016] 07 e4 13 3b fa a5

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.036: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.046: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*spamApTask4: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:40:32.055: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*apfOpenDtlSocket: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Recevied management frame REASSOCIATION REQUEST on BSSID 00:38:df:3b:3e:8e destination addr 00:38:df:3b:3e:8e
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Processing assoc-req station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 ssid : GUEST-MARINE thread:1c4f2ec0
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -58. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Reassociation received from mobile on BSSID 00:38:df:3b:3e:77 AP D-Ring-1F-Left
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 trying to join WLAN with RSSI -58. Checking for XOR roam conditions on AP: 00:38:DF:3B:3E:80 Slot: 1
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Station: 4C:4F:EE:0A:0D:D8 is associating to AP 00:38:DF:3B:3E:80 which is not XOR roam capable
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Global 200 Clients are allowed to AP radio

*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 In processSsidIE:6609 setting Central switched to FALSE
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Set Clinet MSCB as Central Association Disabled
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Applying site-specific Local Bridging override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Applying Local Bridging Interface Policy for station 4c:4f:ee:0a:0d:d8 - vlan 400, interface id 37, interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 override from ap group, removing intf group from mscb
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Applying site-specific override for station 4c:4f:ee:0a:0d:d8 - vapId 25, site 'GWC-EXPRESS', interface 'dring-ups-adsl'
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Applying Interface(dring-ups-adsl) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 400

*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Not re-applying interface policy for local switching Client

*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Setting the NAS Id to AP group specific Id 'GWC-WLC01'
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Set Clinet Non AP specific Flexgroup apfMsAccessVlan = 400
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 This apfMsAccessVlan may be changed later from AAA after L2 Auth
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Cleared localSwitchingVlan, may be assigned later based on AAA override
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 STA - rates (4): 176 72 96 108 36 176 72 96 108 0 0 0 0 0 0 0
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_2: Nov 22 08:40:37.943: RSNIE in Assoc. Req.: (20)

*apfMsConnTask_2: Nov 22 08:40:37.943: [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_2: Nov 22 08:40:37.943: [0016] ac 02 00 00

*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Processing RSN IE type 48, length 20 for mobile 4c:4f:ee:0a:0d:d8
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 RSN Capabilities: 0
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Marking Mobile as non-11w Capable
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Deleted mobile LWAPP rule on AP [00:38:df:3b:3e:80]
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Succesfully freed AID 15, slot 0 on AP 00:38:df:3b:3e:80, #client on this slot 7
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 New ctxOwnerMwarIp: 10.228.159.249 New ctxOwnerApMac: 00:38:DF:3B:3E:80 New ctxOwnerApEthMac: 00:38:DF:3A:36:00 New ctxOwnerApSlotId: 1
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Updated location for station old AP 00:38:df:3b:3e:80 oldSlot 0, new AP 00:38:df:3b:3e:80 newSlot 1, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 Updating AID for REAP AP Client 00:38:df:3b:3e:80 - AID ===> 6
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 apfMsRunStateDec
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 apfMs1xStateDec
*apfMsConnTask_2: Nov 22 08:40:37.943: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Initializing policy
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 192.168.123.201 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 AuthenticationRequired = 1
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Encryption policy is set to 0x80000001
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 apfPemAddUser2:session timeout forstation 4c:4f:ee:0a:0d:d8 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Sending assoc-resp with status 0 station:4c:4f:ee:0a:0d:d8 AP:00:38:df:3b:3e:80-01 on apVapId 2
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 VHT Operation IE: width 80/1 ch 52 freq0 58 freq1 0 msc0 0x3f msc1 0x3f
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Sending Assoc Response (status: '0') to station on AP D-Ring-1F-Left on BSSID 00:38:df:3b:3e:8e ApVapId 2 Slot 1, mobility role 1
*apfMsConnTask_2: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4c:4f:ee:0a:0d:d8 on AP 00:38:df:3b:3e:80 from Associated to Associated

*spamApTask4: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Delete Mobile request on slot 0 sent to the AP 00:38:df:3b:3e:80 IP: 10.228.70.8:5264

*spamApTask4: Nov 22 08:40:37.944: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80
*spamApTask4: Nov 22 08:40:37.951: 4c:4f:ee:0a:0d:d8 apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask4: Nov 22 08:40:37.958: 4c:4f:ee:0a:0d:d8 Received ADD_MOBILE ack - Initiating 1x to STA 4c:4f:ee:0a:0d:d8 (idx 88)
*spamApTask4: Nov 22 08:40:37.958: 4c:4f:ee:0a:0d:d8 Sent dot1x auth initiate message for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 reauth_sm state transition 0 ---> 0 for mobile 4c:4f:ee:0a:0d:d8 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Creating a PKC PMKID Cache entry for station 4c:4f:ee:0a:0d:d8 (RSN 2)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Resetting MSCB PMK Cache Entry @index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Removing BSSID 00:38:df:3b:3e:81 from PMKID cache of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Created PMKID PMK Cache for BSSID 00:38:df:3b:3e:8e at index 0 for station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: New PMKID: (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Initiating RSN PSK to mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 EAP-PARAM Debug - eap-params for Wlan-Id :25 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 dot1x - moving mobile 4c:4f:ee:0a:0d:d8 into Force Auth state
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Skipping EAP-Success to mobile 4c:4f:ee:0a:0d:d8 (encryptBit:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Found an cache entry for BSSID 00:38:df:3b:3e:8e in PMKID cache at index 0 of station 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: [0000] 96 89 6f e0 92 71 96 4d 23 87 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: M1 - Key Data: (22)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: [0000] dd 14 00 0f ac 04 96 89 6f e0 92 71 96 4d 23 87

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: [0016] 50 5a f7 f6 bf d0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Starting key exchange to mobile 4c:4f:ee:0a:0d:d8, data packets will be dropped
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.959: 4c:4f:ee:0a:0d:d8 Allocating EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTK_START state (message 2) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:0,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Sending EAPOL-Key Message to mobile 4c:4f:ee:0a:0d:d8
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.965: 4c:4f:ee:0a:0d:d8 Reusing allocated memory for EAP Pkt for retransmission to mobile 4c:4f:ee:0a:0d:d8
*dot1xSocketTask: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 validating eapol pkt: key version = 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 Received EAPOL-Key from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 key Desc Version FT - 0

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 Stopping retransmission timer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.972: 4c:4f:ee:0a:0d:d8 Freeing EAP Retransmit Bufer for mobile 4c:4f:ee:0a:0d:d8
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 apfMs1xStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Client already has IP 192.168.123.201, DHCP Not required on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:38:df:3b:3e:80 vapId 25 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 apfMsRunStateInc
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 192.168.123.201 RUN (20) NO release MSCB
*Dot1x_NW_MsgTask_0: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 Successfully Plumbed PTK session Keysfor mobile 4c:4f:ee:0a:0d:d8
*spamApTask4: Nov 22 08:40:37.973: 4c:4f:ee:0a:0d:d8 Successful transmission of LWAPP Add-Mobile to AP 00:38:df:3b:3e:80

[Sandeep Choudhary]

can you run this command"debug client <mac address>" in wlc and then try to connect the mobile. Later collect all logs from wlc and paste here .

Regards

Dont forget to rate helpful posts

[Sandeep Choudhary]

This client is alredy in RUN state(means working perfectly)

4c:4f:ee:0a:0d:d8 192.168.123.201 RUN

what exactly the issue:

is not able to connect to WLAN (which I don't believe)or not able to access internet?

[susanth_ks]

Hi Sandeep,

Actually the issue is.

We we try to connect wifi it is telling unable to connect. Some time it is showing unable to get IP address.(This issue is happening is 1st floor and second floor). that also for mobile devices.

In ground floor everything is working perfectly fine.

I have attached latest logs for your reference

MAC is:04:b1:a1:27:93:4c

[susanth_ks]

Hi Sandeep,

The log which I sheared is the same "debug client 4c:4f:ee:0a:0d:d8"

[Mark Elsen]

 - You can have your log analyzed with :

                https://cway.cisco.com/wireless-debug-analyzer/

 M.

[Arshad Safrulla]

Hi, 

Did you check whether there are any DHCP issues upstream? May be you can try disabling DHCP Addr. assignment option under the WLAN.