Solved: VLAN have good Wi-F connection but droopin down L3 access to internet

avitgroup · ‎08-13-2023

We have 2 WLC 5520 with 170 AP 1852. over the sudden one of the vlans become doing this error. Clients are connected to WiFi but no internet access. I dont see reason why.

I did client debug command and this is output:

(Cisco Controller) >debug client 4e2322771e4c

(Cisco Controller) >*apfOpenDtlSocket: Aug 13 15:07:14.570: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfOpenDtlSocket: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:1E
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_4: Aug 13 15:07:30.299: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:29
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_4: Aug 13 15:07:32.589: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:59:a0-00 ssid : KGuest thread:1c4f74e0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:59:a4 AP Fwd_Corridor
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 1

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a4-00 on apVapId 5
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP Fwd_Corridor on BSSID 70:d3:79:e3:59:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_0: Aug 13 15:07:42.200: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*apfOpenDtlSocket: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c Recevied management frame AUTH on BSSID 70:d3:79:e3:58:84 destination addr 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the Air
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:3C
*apfMsConnTask_1: Aug 13 15:07:42.222: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:58:84
*apfOpenDtlSocket: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c Recevied management frame AUTH on BSSID 70:d3:79:e3:58:84 destination addr 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:3D
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.552: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:58:84
*apfOpenDtlSocket: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:58:84 destination addr 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:58:80-00 ssid : KGuest thread:1c4f7b40
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:58:80
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:58:84 AP Staff_Cabin_311
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:58:80
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 0

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] R1KHID+S1KHID: (12)

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] [0000] c4 f7 d5 e4 55 9f 4e 23 22 77 1e 4c

*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Roaming succeed for this client.
*apfMsConnTask_1: Aug 13 15:07:42.561: [SA] 4e:23:22:77:1e:4c Found an cache entry for BSSID 70:d3:79:e3:4d:a4 in PMKID cache at index 0 of station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Removing BSSID 70:d3:79:e3:4d:a4 from PMKID cache of station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Setting active key cache index 0 ---> 8
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 8
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Deleted mobile LWAPP rule on AP [70:d3:79:e3:4d:a0]
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Succesfully freed AID 13, slot 0 on AP 70:d3:79:e3:4d:a0, #client on this slot 6
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c New ctxOwnerMwarIp: 172.16.0.10 New ctxOwnerApMac: 70:D3:79:E3:58:80 New ctxOwnerApEthMac: 70:D3:79:E2:3B:C8 New ctxOwnerApSlotId: 0
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Updated location for station old AP 70:d3:79:e3:4d:a0 oldSlot 0, new AP 70:d3:79:e3:58:80 newSlot 0, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Allocate AID 1 slot 0 on AP Staff_Cabin_311 #clients on this slot 1
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c apfMsRunStateDec
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Initializing policy
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c AuthenticationRequired = 1
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Encryption policy is set to 0x80000004
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:58:80 vapId 5 apVapId 5 flex-acl-name:
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:58:80 from Associated to Associated

*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 0 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:58:80-00 on apVapId 5
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in reassociation assoc Resp to mobile
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: '0') to station on AP Staff_Cabin_311 on BSSID 70:d3:79:e3:58:84 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_1: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:58:80 from Associated to Associated

*spamApTask6: Aug 13 15:07:42.562: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:58:80
*spamApTask0: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*spamApTask6: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Received ADD_MOBILE ack - Initiating 1x to STA 4e:23:22:77:1e:4c (idx 28)
*spamApTask6: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Sent dot1x auth initiate message for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c reauth_sm state transition 1 ---> 0 for mobile 4e:23:22:77:1e:4c at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Finishing FT roaming for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c EAP-PARAM Debug - eap-params for Wlan-Id :5 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c dot1x - moving mobile 4e:23:22:77:1e:4c into Force Auth state
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Skipping EAP-Success to mobile 4e:23:22:77:1e:4c (encryptBit:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:58:80 vapId 5 apVapId 5 flex-acl-name:
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c apfMsRunStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Adding Fast Path rule
type = Airespace AP Client
on AP 70:d3:79:e3:58:80, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv6 ACL ID
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) NO release MSCB
*pemReceiveTask: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Added NPU entry of type 1, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fe80:0000:0000:0000:0091:5733:c1b0:5fe0 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*pemReceiveTask: Aug 13 15:07:42.566: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fdec:f400:31d6:4a26:18b0:525a:3120:cc37 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*apfOpenDtlSocket: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Recevied management frame AUTH on BSSID 70:d3:79:e3:58:84 destination addr 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the Air
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:3F
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.933: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:58:84
*apfOpenDtlSocket: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:58:84 destination addr 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:58:80-00 ssid : KGuest thread:1c4f7b40
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:58:80
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:58:84 AP Staff_Cabin_311
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:58:80
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 1

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] R1KHID+S1KHID: (12)

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] [0000] c4 f7 d5 e4 55 9f 4e 23 22 77 1e 4c

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Roaming succeed for this client.
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Setting active key cache index 0 ---> 8
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 8
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Created PKC PMK Cache entry for station 4e:23:22:77:1e:4c (RSN 2) on BSSID 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfMsRunStateDec
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfMs1xStateDec
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Initializing policy
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c AuthenticationRequired = 1
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Encryption policy is set to 0x80000004
*pemReceiveTask: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Removed NPU entry.
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:58:80 vapId 5 apVapId 5 flex-acl-name:
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:58:80 from Associated to Associated

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfPemAddUser2:session timeout forstation 4e:23:22:77:1e:4c - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 0 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:58:80-00 on apVapId 5
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in reassociation assoc Resp to mobile
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: '0') to station on AP Staff_Cabin_311 on BSSID 70:d3:79:e3:58:84 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_1: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:58:80 from Associated to Associated

*spamApTask6: Aug 13 15:07:42.958: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:58:80
*spamApTask6: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Received ADD_MOBILE ack - Initiating 1x to STA 4e:23:22:77:1e:4c (idx 30)
*spamApTask6: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Sent dot1x auth initiate message for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c reauth_sm state transition 0 ---> 0 for mobile 4e:23:22:77:1e:4c at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Finishing FT roaming for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c EAP-PARAM Debug - eap-params for Wlan-Id :5 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c dot1x - moving mobile 4e:23:22:77:1e:4c into Force Auth state
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Skipping EAP-Success to mobile 4e:23:22:77:1e:4c (encryptBit:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:58:80 vapId 5 apVapId 5 flex-acl-name:
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c apfMsRunStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Adding Fast Path rule
type = Airespace AP Client
on AP 70:d3:79:e3:58:80, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv6 ACL ID
*spamApTask6: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:58:80
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_4: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) NO release MSCB
*pemReceiveTask: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Added NPU entry of type 1, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fe80:0000:0000:0000:0091:5733:c1b0:5fe0 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*pemReceiveTask: Aug 13 15:07:42.962: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fdec:f400:31d6:4a26:18b0:525a:3120:cc37 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*apfOpenDtlSocket: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:58:84 destination addr 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:58:84
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:4A
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_1: Aug 13 15:07:45.623: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:59:a0-00 ssid : KGuest thread:1c4f74e0
*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:59:a4 AP Fwd_Corridor
*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 2

*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_0: Aug 13 15:07:45.880: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:58:80-00 on apVapId 5
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP Fwd_Corridor on BSSID 70:d3:79:e3:59:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_0: Aug 13 15:07:45.881: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*osapiBsnTimer: Aug 13 15:07:48.938: [SA] 4e:23:22:77:1e:4c apfMsExpireCallback (apf_ms.c:639) Expiring Mobile!
*apfReceiveTask: Aug 13 15:07:48.938: [SA] 4e:23:22:77:1e:4c apfMsExpireMobileStation (apf_ms.c:7688) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:58:80 from Associated to Disassociated

*apfReceiveTask: Aug 13 15:07:48.938: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 45) in 10 seconds
*osapiBsnTimer: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c apfMsExpireCallback (apf_ms.c:639) Expiring Mobile!
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Client already in disassociated state, not sending disassociation
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Setting active key cache index 0 ---> 8
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Deleting the PMK cache when de-authenticating the client.
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c DOT11R: Sending cache delete
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Removing PMK cache entry for station 4e:23:22:77:1e:4c
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry on AP station 70:d3:79:e3:4d:ab
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry on AP station 70:d3:79:e3:58:84
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Succesfully freed AID 1, slot 0 on AP 70:d3:79:e3:58:80, #client on this slot 0
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c apfMsAssoStateDec
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c apfMsWepPskStateDec
*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c apfMsExpireMobileStation (apf_ms.c:7821) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:58:80 from Disassociated to Idle

*apfReceiveTask: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 47) in 10 seconds
*spamApTask6: Aug 13 15:07:59.282: [SA] 4e:23:22:77:1e:4c Delete Mobile request on slot 0 sent to the AP 70:d3:79:e3:58:80 IP: 172.16.0.166:5256

*spamApTask6: Aug 13 15:07:59.285: [SA] 4e:23:22:77:1e:4c apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*osapiBsnTimer: Aug 13 15:08:09.654: [SA] 4e:23:22:77:1e:4c apfMsExpireCallback (apf_ms.c:639) Expiring Mobile!
*apfReceiveTask: Aug 13 15:08:09.654: [SA] 4e:23:22:77:1e:4c pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Aug 13 15:08:09.654: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Deleted mobile LWAPP rule on AP [70:d3:79:e3:58:80]
*pemReceiveTask: Aug 13 15:08:09.654: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Removed NPU entry.
*apfReceiveTask: Aug 13 15:08:09.654: [SA] 4e:23:22:77:1e:4c Deleting mobile on AP 70:d3:79:e3:58:80(0)
*apfOpenDtlSocket: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Recevied management frame ASSOCIATION REQUEST on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 ssid : KGuest thread:1c4f8e60
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Created Acct-Session-ID (64d8f1e5/4e:23:22:77:1e:4c/24505) for the mobile
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Adding mobile on LWAPP AP 70:d3:79:e3:4d:a0(0)
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Association received from mobile on BSSID 70:d3:79:e3:4d:a4 AP AV_Room
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 6

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] RSNIE in Assoc. Req.: (20)

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] [0016] ac 04 0c 00

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 20 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Allocate AID 17 slot 0 on AP AV_Room #clients on this slot 7
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c 0.0.0.0 START (0) Initializing policy
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c AuthenticationRequired = 1
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Encryption policy is set to 0x80000001
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:4d:a0 vapId 5 apVapId 5 flex-acl-name:
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c apfMsAssoStateInc
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c apfPemAddUser2:session timeout forstation 4e:23:22:77:1e:4c - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 0 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 on apVapId 5
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: '0') to station on AP AV_Room on BSSID 70:d3:79:e3:4d:a4 ApVapId 5 Slot 0, mobility role 0
*apfMsConnTask_4: Aug 13 15:08:21.843: [SA] 4e:23:22:77:1e:4c apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:4d:a0 from Associated to Associated

*spamApTask0: Aug 13 15:08:21.844: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:4d:a0
*spamApTask0: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c Received ADD_MOBILE ack - Initiating 1x to STA 4e:23:22:77:1e:4c (idx 77)
*spamApTask0: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c Sent dot1x auth initiate message for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c reauth_sm state transition 0 ---> 1 for mobile 4e:23:22:77:1e:4c at 1x_reauth_sm.c:47
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c Creating a PKC PMKID Cache entry for station 4e:23:22:77:1e:4c (RSN 2)
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.848: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Created PMKID PMK Cache for BSSID 70:d3:79:e3:4d:a4 at index 0 for station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] New PMKID: (16)

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] [0000] b2 0c a7 86 5f 3b 03 92 65 d2 46 3e 1f 22 d0 f6

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Creating global PMK cache for this TGr client
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Created PMK Cache Entry for TGr AKM:PSK 4e:23:22:77:1e:4c

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c R0KH-ID:172.16.0.10 R1KH-ID:c4:f7:d5:e4:55:9f MSK Len:48
pmkValidTime:86419

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c apfCreatePmkCacheEntry: added a new pmk cache entry for 4e:23:22:77:1e:4c

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Adding Audit session ID payload in Mobility handoff

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c 0 PMK-update groupcast messages sent
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Initiating RSN PSK to mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c EAP-PARAM Debug - eap-params for Wlan-Id :5 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c dot1x - moving mobile 4e:23:22:77:1e:4c into Force Auth state
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Skipping EAP-Success to mobile 4e:23:22:77:1e:4c (encryptBit:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Found an cache entry for BSSID 70:d3:79:e3:4d:a4 in PMKID cache at index 0 of station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Found an cache entry for BSSID 70:d3:79:e3:4d:a4 in PMKID cache at index 0 of station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] [0000] b2 0c a7 86 5f 3b 03 92 65 d2 46 3e 1f 22 d0 f6

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] M1 - Key Data: (22)

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] [0000] dd 14 00 0f ac 04 b2 0c a7 86 5f 3b 03 92 65 d2

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] [0016] 46 3e 1f 22 d0 f6

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Starting key exchange to mobile 4e:23:22:77:1e:4c, data packets will be dropped
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Sending EAPOL-Key Message to mobile 4e:23:22:77:1e:4c
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.849: [SA] 4e:23:22:77:1e:4c Allocating EAP Pkt for retransmission to mobile 4e:23:22:77:1e:4c
*dot1xSocketTask: Aug 13 15:08:21.861: [SA] 4e:23:22:77:1e:4c validating eapol pkt: key version = 3
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Received EAPOL-Key from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Received EAPOL-key in PTK_START state (message 2) from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] R1KHID+S1KHID: (12)

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] [0000] c4 f7 d5 e4 55 9f 4e 23 22 77 1e 4c

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:16,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 00000010: 00 0f ac 04 0c 00 ......
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 00000010: ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac ..........nec...
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 00000020: 03 4a 16 61 5a 6d .J.aZm
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c 11r-FT: Validate MDIE info in M2 message from mobile
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Calculating PMKR0Name
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Encrypt EAPOL packets = FALSE
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Validate the 11r FTIE element received in the M2 against the assoc respnse FTIE
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Received FTIE matches with what is being sent in Assoc Response frame
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Successfully validated the FTIE in M2 with IE sent in association response
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Not Flex client. Do not distribute dot11r Key cache.
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Stopping retransmission timer for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Sending EAPOL-Key Message to mobile 4e:23:22:77:1e:4c
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.862: [SA] 4e:23:22:77:1e:4c Reusing allocated memory for EAP Pkt for retransmission to mobile 4e:23:22:77:1e:4c
*dot1xSocketTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c validating eapol pkt: key version = 3
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Received EAPOL-Key from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Stopping retransmission timer for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Freeing EAP Retransmit Bufer for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Mobility query, PEM State: L2AUTHCOMPLETE

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 L2AUTHCOMPLETE (4) NO release MSCB
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Building Mobile Announce :

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Building Client Payload:

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Client Ip: 0.0.0.0

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Client Vlan Ip: 192.168.120.5, Vlan mask : 255.255.255.0

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Client Vap Security: 1073758208

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Virtual Ip: 1.1.1.1

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c ssid: KGuest

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Building VlanIpPayload.

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:4d:a0 vapId 5 apVapId 5 flex-acl-name:
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 6757, Adding TMP rule
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule
type = Airespace AP - Learn IP address
on AP 70:d3:79:e3:4d:a0, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) NO release MSCB
*Dot1x_NW_MsgTask_4: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Successfully Plumbed PTK session Keysfor mobile 4e:23:22:77:1e:4c
*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) mobility role update request from Unassociated to Local
Peer = 0.0.0.0, Old Anchor = 0.0.0.0, New Anchor = 172.16.0.10
*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED
*spamApTask0: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:4d:a0
*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule
type = Airespace AP - Learn IP address
on AP 70:d3:79:e3:4d:a0, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255,
*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*apfReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) NO release MSCB
*pemReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:08:21.870: [SA] 4e:23:22:77:1e:4c Sent an XID frame
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP received op BOOTREQUEST (1) (len 308,vlan 15, port 2, encap 0xec03, xid 0x9a14cf9f)
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP processing DHCP REQUEST (3)
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 0
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP xid: 0x9fcf149a (2681148570), secs: 0, flags: 0
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP chaddr: 4e:23:22:77:1e:4c
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP requested ip: 192.168.120.214
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP Opt82 bridge mode insertion enabled, inserts opt82 if opt82 is enabled vlan=120, datalen =18, optlen=64
*DHCP Socket Task: Aug 13 15:08:22.025: [SA] 4e:23:22:77:1e:4c DHCP successfully bridged packet to DS
*IPv6_Msg_Task: Aug 13 15:08:22.034: [SA] 4e:23:22:77:1e:4c Calling pemAdvanceState from ipv6 addr learn, with pem session state 7
*IPv6_Msg_Task: Aug 13 15:08:22.034: [SA] 4e:23:22:77:1e:4c apfMsRunStateInc
*IPv6_Msg_Task: Aug 13 15:08:22.034: [SA] 4e:23:22:77:1e:4c 0.0.0.0 DHCP_REQD (7) Change state to RUN (20) last state DHCP_REQD (7)

*IPv6_Msg_Task: Aug 13 15:08:22.034: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) Reached PLUMBFASTPATH: from line 7396, null
*IPv6_Msg_Task: Aug 13 15:08:22.034: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) Replacing Fast Path rule
type = Airespace AP Client
on AP 70:d3:79:e3:4d:a0, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv6 ACL ID = 255
*IPv6_Msg_Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*IPv6_Msg_Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*IPv6_Msg_Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*IPv6_Msg_Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*IPv6_Msg_Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) No 11v BTM
*IPv6_Msg_Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 RUN (20) NO release MSCB
*pemReceiveTask: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0 Added NPU entry of type 1, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fdec:f400:31d6:4a26:18b0:525a:3120:cc37 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*pemReceiveTask: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 0.0.0.0, VLAN Id 4216 Not sending gratuitous ARP
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP received op BOOTREPLY (2) (len 313,vlan 120, port 2, encap 0xec00, xid 0x9a14cf9f)
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP processing DHCP ACK (5)
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP xid: 0x9fcf149a (2681148570), secs: 0, flags: 0
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP chaddr: 4e:23:22:77:1e:4c
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP ciaddr: 0.0.0.0, yiaddr: 192.168.120.214
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP siaddr: 0.0.0.0, giaddr: 192.168.120.1
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c DHCP server id: 172.19.4.1 rcvd server id: 172.19.4.1
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c Static IP client associated to interface if_cguest which can support client subnet.
*DHCP Socket Task: Aug 13 15:08:22.035: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) DHCP Address Re-established
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Reached PLUMBFASTPATH: from line 7619, null
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Replacing Fast Path rule
type = Airespace AP Client
on AP 70:d3:79:e3:4d:a0, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv6 ACL
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) No 11v BTM
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) NO release MSCB
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c Assigning Address 192.168.120.214 to mobile
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c DHCP success event for client. Clearing dhcp failure count for interface if_cguest.
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c DHCP success event for client. Clearing dhcp failure count for interface if_cguest.
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c Client learned IP from Orphan Packet.Updated the GW and NW from interface for client.Ip 192.168.120.214, gateway 192.168.120.1,netmask 255.255.255.0
*DHCP Socket Task: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c DHCP successfully bridged packet to STA
*pemReceiveTask: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Added NPU entry of type 1, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:08:22.036: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fdec:f400:31d6:4a26:18b0:525a:3120:cc37 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*apfOpenDtlSocket: Aug 13 15:08:24.609: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:8B
*apfMsConnTask_4: Aug 13 15:08:24.610: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:08:26.587: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:8F
*apfMsConnTask_4: Aug 13 15:08:26.588: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:91
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_4: Aug 13 15:08:30.145: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*osapiBsnTimer: Aug 13 15:08:50.770: [SA] 4e:23:22:77:1e:4c FT request timer expired, send msg to delete this preauth(bssid:70:d3:79:e3:4d:ab)
*apfReceiveTask: Aug 13 15:08:50.770: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:AF
*apfMsConnTask_4: Aug 13 15:09:00.568: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*osapiBsnTimer: Aug 13 15:09:21.218: [SA] 4e:23:22:77:1e:4c FT request timer expired, send msg to delete this preauth(bssid:70:d3:79:e3:4d:ab)
*apfOpenDtlSocket: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:09:31.287: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:D9
*apfMsConnTask_4: Aug 13 15:09:31.288: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*osapiBsnTimer: Aug 13 15:09:51.842: [SA] 4e:23:22:77:1e:4c FT request timer expired, send msg to delete this preauth(bssid:70:d3:79:e3:4d:ab)
*apfReceiveTask: Aug 13 15:09:51.842: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B1:F5
*apfMsConnTask_4: Aug 13 15:10:02.195: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*osapiBsnTimer: Aug 13 15:10:22.738: [SA] 4e:23:22:77:1e:4c FT request timer expired, send msg to delete this preauth(bssid:70:d3:79:e3:4d:ab)
*apfReceiveTask: Aug 13 15:10:22.738: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:59:a0-00 ssid : KGuest thread:1c4f74e0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:59:a4 AP Fwd_Corridor
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 0

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 on apVapId 5
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP Fwd_Corridor on BSSID 70:d3:79:e3:59:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_0: Aug 13 15:10:29.369: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*apfOpenDtlSocket: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c Recevied management frame AUTH on BSSID f4:7f:35:dc:43:04 destination addr f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the Air
*apfMsConnTask_1: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:24
*apfMsConnTask_1: Aug 13 15:10:29.387: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:f4:7f:35:dc:43:04
*apfOpenDtlSocket: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID f4:7f:35:dc:43:04 destination addr f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:f4:7f:35:dc:43:00-00 ssid : KGuest thread:1c4f7b40
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP F4:7F:35:DC:43:00
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID f4:7f:35:dc:43:04 AP MD_HVAC_Next_To_AVIT_Room
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP F4:7F:35:DC:43:00
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 0

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] R1KHID+S1KHID: (12)

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] [0000] c4 f7 d5 e4 55 9f 4e 23 22 77 1e 4c

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Calculating PMKR0Name
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Roaming succeed for this client.
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Found an cache entry for BSSID 70:d3:79:e3:4d:a4 in PMKID cache at index 0 of station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Removing BSSID 70:d3:79:e3:4d:a4 from PMKID cache of station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 8
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 0
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Created PKC PMK Cache entry for station 4e:23:22:77:1e:4c (RSN 2) on BSSID 70:d3:79:e3:4d:a4
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Deleted mobile LWAPP rule on AP [70:d3:79:e3:4d:a0]
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Succesfully freed AID 17, slot 0 on AP 70:d3:79:e3:4d:a0, #client on this slot 5
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Updated location for station old AP 70:d3:79:e3:4d:a0 oldSlot 0, new AP f4:7f:35:dc:43:00 newSlot 0, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Allocate AID 1 slot 0 on AP MD_HVAC_Next_To_AVIT_Room #clients on this slot 1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c apfMsRunStateDec
*spamApTask0: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Delete Mobile request on slot 0 sent to the AP 70:d3:79:e3:4d:a0 IP: 172.16.0.128:5248

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c apfMs1xStateDec
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Initializing policy
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c AuthenticationRequired = 1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Encryption policy is set to 0x80000004
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f4:7f:35:dc:43:00 vapId 5 apVapId 5 flex-acl-name:
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4e:23:22:77:1e:4c on AP f4:7f:35:dc:43:00 from Associated to Associated

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 0 station:4e:23:22:77:1e:4c AP:f4:7f:35:dc:43:00-00 on apVapId 5
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in reassociation assoc Resp to mobile
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: '0') to station on AP MD_HVAC_Next_To_AVIT_Room on BSSID f4:7f:35:dc:43:04 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_1: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4e:23:22:77:1e:4c on AP f4:7f:35:dc:43:00 from Associated to Associated

*spamApTask3: Aug 13 15:10:29.394: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP f4:7f:35:dc:43:00
*spamApTask3: Aug 13 15:10:29.396: [SA] 4e:23:22:77:1e:4c Received ADD_MOBILE ack - Initiating 1x to STA 4e:23:22:77:1e:4c (idx 7)
*spamApTask3: Aug 13 15:10:29.396: [SA] 4e:23:22:77:1e:4c Sent dot1x auth initiate message for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c reauth_sm state transition 1 ---> 0 for mobile 4e:23:22:77:1e:4c at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Finishing FT roaming for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c EAP-PARAM Debug - eap-params for Wlan-Id :5 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c dot1x - moving mobile 4e:23:22:77:1e:4c into Force Auth state
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Skipping EAP-Success to mobile 4e:23:22:77:1e:4c (encryptBit:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP f4:7f:35:dc:43:00 vapId 5 apVapId 5 flex-acl-name:
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c apfMsRunStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*spamApTask3: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP f4:7f:35:dc:43:00
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Adding Fast Path rule
type = Airespace AP Client
on AP f4:7f:35:dc:43:00, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv6 ACL ID
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_4: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) NO release MSCB
*pemReceiveTask: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Added NPU entry of type 1, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:10:29.397: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fdec:f400:31d6:4a26:18b0:525a:3120:cc37 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*spamApTask0: Aug 13 15:10:29.399: [SA] 4e:23:22:77:1e:4c apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*apfOpenDtlSocket: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID f4:7f:35:dc:43:04 destination addr f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:2A
*apfMsConnTask_1: Aug 13 15:10:32.206: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 ssid : KGuest thread:1c4f8e60
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:4d:a4 AP AV_Room
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_4: Aug 13 15:10:32.374: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:f4:7f:35:dc:43:00-00 on apVapId 5
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP AV_Room on BSSID 70:d3:79:e3:4d:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_4: Aug 13 15:10:32.375: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*apfOpenDtlSocket: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 ssid : KGuest thread:1c4f8e60
*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:4d:a4 AP AV_Room
*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_4: Aug 13 15:10:33.042: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:f4:7f:35:dc:43:00-00 on apVapId 5
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP AV_Room on BSSID 70:d3:79:e3:4d:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_4: Aug 13 15:10:33.043: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*apfOpenDtlSocket: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 ssid : KGuest thread:1c4f8e60
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:4d:a4 AP AV_Room
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:f4:7f:35:dc:43:00-00 on apVapId 5
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP AV_Room on BSSID 70:d3:79:e3:4d:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_4: Aug 13 15:10:33.396: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*apfOpenDtlSocket: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 ssid : KGuest thread:1c4f8e60
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:4d:a4 AP AV_Room
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_4: Aug 13 15:10:33.716: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:33.717: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:33.717: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_4: Aug 13 15:10:33.717: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_4: Aug 13 15:10:33.717: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_4: Aug 13 15:10:33.717: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP AV_Room on BSSID 70:d3:79:e3:4d:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_4: Aug 13 15:10:33.717: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 22) in 3 seconds
*apfOpenDtlSocket: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID f4:7f:35:dc:43:04 destination addr f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:3B
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_1: Aug 13 15:10:35.781: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*osapiBsnTimer: Aug 13 15:10:36.642: [SA] 4e:23:22:77:1e:4c apfMsExpireCallback (apf_ms.c:639) Expiring Mobile!
*apfReceiveTask: Aug 13 15:10:36.642: [SA] 4e:23:22:77:1e:4c apfMsExpireMobileStation (apf_ms.c:7688) Changing state for mobile 4e:23:22:77:1e:4c on AP f4:7f:35:dc:43:00 from Associated to Disassociated

*apfReceiveTask: Aug 13 15:10:36.642: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 45) in 10 seconds
*spamApTask0: Aug 13 15:10:39.321: [SA] 4e:23:22:77:1e:4c Received DELETE mobile, reason MN_REASSOC_TIMEOUT, from AP 70:d3:79:e3:4d:a0, slot 0 ...cleaning up mscb
*spamApTask0: Aug 13 15:10:39.321: [SA] 4e:23:22:77:1e:4c Warning, ignore the DELETE_MOBILE_PAYLOAD from AP: 70:d3:79:e3:4d:a0, slot 0. STA connecting AP: f4:7f:35:dc:43:00, slot 0
*apfOpenDtlSocket: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID f4:7f:35:dc:43:04 destination addr f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address f4:7f:35:dc:43:04
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:42
*apfMsConnTask_1: Aug 13 15:10:40.469: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Recevied management frame REASSOCIATION REQUEST on BSSID 70:d3:79:e3:4d:a4 destination addr 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:4d:a0-00 ssid : KGuest thread:1c4f8e60
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Reassociation received from mobile on BSSID 70:d3:79:e3:4d:a4 AP AV_Room
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:4D:A0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] RSNIE in Assoc. Req.: (38)

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] [0016] ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] [0032] 03 4a 16 61 5a 6d

*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 38 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c No preauth entry found for this mobile on AP 70:d3:79:e3:4d:a4
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 1 station:4e:23:22:77:1e:4c AP:f4:7f:35:dc:43:00-00 on apVapId 5
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Including FT IE (length 98) in Initial Assoc Resp to mobile
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: 'unspecified failure') to station on AP AV_Room on BSSID 70:d3:79:e3:4d:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_4: Aug 13 15:10:40.666: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 84) in 1 seconds
*osapiBsnTimer: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c apfMsExpireCallback (apf_ms.c:639) Expiring Mobile!
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Client already in disassociated state, not sending disassociation
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Setting active key cache index 0 ---> 8
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Deleting the PMK cache when de-authenticating the client.
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c DOT11R: Sending cache delete
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Removing PMK cache entry for station 4e:23:22:77:1e:4c
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry on AP station 70:d3:79:e3:4d:ab
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry on AP station f4:7f:35:dc:43:04
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Succesfully freed AID 1, slot 0 on AP f4:7f:35:dc:43:00, #client on this slot 0
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Sent Deauthenticate to mobile on BSSID f4:7f:35:dc:43:04 slot 0(caller apf_ms.c:7780)
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c apfMsAssoStateDec
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c apfMsWepPskStateDec
*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c apfMsExpireMobileStation (apf_ms.c:7821) Changing state for mobile 4e:23:22:77:1e:4c on AP f4:7f:35:dc:43:00 from Disassociated to Idle

*apfReceiveTask: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Scheduling deletion of Mobile Station: (callerId: 47) in 10 seconds
*spamApTask3: Aug 13 15:10:41.630: [SA] 4e:23:22:77:1e:4c Delete Mobile request on slot 0 sent to the AP f4:7f:35:dc:43:00 IP: 172.16.0.110:26689

*spamApTask3: Aug 13 15:10:41.631: [SA] 4e:23:22:77:1e:4c apfUpdateDeleteAckInMscb (apf_api.c:51702) Expiring Mobile!
*apfOpenDtlSocket: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Recevied management frame ASSOCIATION REQUEST on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Updating 11r vendor IE

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Processing assoc-req station:4e:23:22:77:1e:4c AP:70:d3:79:e3:59:a0-00 ssid : KGuest thread:1c4f74e0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Association received from mobile on BSSID 70:d3:79:e3:59:a4 AP Fwd_Corridor
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Station: 4E:23:22:77:1E:4C 11v BSS Transition not enabled on the AP 70:D3:79:E3:59:A0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Max Client Trap Threshold: 0 cur: 2

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Applying Interface(if_cguest) policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 120

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Re-applying interface policy for client

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2922)
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2942)
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2963)
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c In processSsidIE:6558 setting Central switched to TRUE
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c In processSsidIE:6561 apVapId = 5 and Split Acl Id = 65535
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Applying site-specific Local Bridging override for station 4e:23:22:77:1e:4c - vapId 5, site 'default-group', interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Applying Local Bridging Interface Policy for station 4e:23:22:77:1e:4c - vlan 120, interface id 14, interface 'if_cguest'
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c extSuppRates statusCode is 0 and gotExtSuppRatesElement is 1
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Marking this mobile as TGr capable.
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] RSNIE in Assoc. Req.: (20)

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] [0016] ac 04 0c 00

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Processing RSN IE type 48, length 20 for mobile 4e:23:22:77:1e:4c
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Selected Unicast cipher CCMP128 for client device
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c RSN Capabilities: 12
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Marking Mobile as non-11w Capable
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Deleted mobile LWAPP rule on AP [f4:7f:35:dc:43:00]
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Updated location for station old AP f4:7f:35:dc:43:00 oldSlot 0, new AP 70:d3:79:e3:59:a0 newSlot 0, AID 0 MsType 0 MobilityRole 1
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Allocate AID 3 slot 0 on AP Fwd_Corridor #clients on this slot 3
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Initializing policy
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfVapSecurity=0x40004000 L2=16384 SkipWeb=0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c AuthenticationRequired = 1
*pemReceiveTask: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Removed NPU entry.
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Encryption policy is set to 0x80000001
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:59:a0 vapId 5 apVapId 5 flex-acl-name:
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfMsAssoStateInc
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfMsWepPskStateInc
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfPemAddUser2 (apf_policy.c:416) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:59:a0 from Idle to Associated

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfPemAddUser2:session timeout forstation 4e:23:22:77:1e:4c - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Sending assoc-resp with status 0 station:4e:23:22:77:1e:4c AP:70:d3:79:e3:59:a0-00 on apVapId 5
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Including FT Mobility Domain IE (length 5) in Initial assoc Resp to mobile
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Sending R1KH-ID as c4:f7:d5:e4:55:9f
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Sending R0KH-ID as:-84.16.0.10
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Clearing the existing FT session data for mobile as we received a fresh Association.
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Sending Assoc Response (status: '0') to station on AP Fwd_Corridor on BSSID 70:d3:79:e3:59:a4 ApVapId 5 Slot 0, mobility role 1
*apfMsConnTask_0: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c apfProcessAssocReq (apf_80211.c:11039) Changing state for mobile 4e:23:22:77:1e:4c on AP 70:d3:79:e3:59:a0 from Associated to Associated

*spamApTask3: Aug 13 15:10:42.473: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:59:a0
*spamApTask3: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Received ADD_MOBILE ack - Initiating 1x to STA 4e:23:22:77:1e:4c (idx 44)
*spamApTask3: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Sent dot1x auth initiate message for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c reauth_sm state transition 0 ---> 1 for mobile 4e:23:22:77:1e:4c at 1x_reauth_sm.c:47
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Creating a PKC PMKID Cache entry for station 4e:23:22:77:1e:4c (RSN 2)
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Resetting MSCB PMK Cache Entry @index 0 for station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Created PMKID PMK Cache for BSSID 70:d3:79:e3:59:a4 at index 0 for station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] New PMKID: (16)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] [0000] 73 eb 10 4d 52 09 8f b6 ea 14 49 4d 59 ec d4 77

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Creating global PMK cache for this TGr client
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Created PMK Cache Entry for TGr AKM:PSK 4e:23:22:77:1e:4c

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c R0KH-ID:172.16.0.10 R1KH-ID:c4:f7:d5:e4:55:9f MSK Len:48
pmkValidTime:86407

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c apfCreatePmkCacheEntry: added a new pmk cache entry for 4e:23:22:77:1e:4c

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Adding Audit session ID payload in Mobility handoff

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c 0 PMK-update groupcast messages sent
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Initiating RSN PSK to mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c EAP-PARAM Debug - eap-params for Wlan-Id :5 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c dot1x - moving mobile 4e:23:22:77:1e:4c into Force Auth state
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Skipping EAP-Success to mobile 4e:23:22:77:1e:4c (encryptBit:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Found an cache entry for BSSID 70:d3:79:e3:59:a4 in PMKID cache at index 0 of station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Found an cache entry for BSSID 70:d3:79:e3:59:a4 in PMKID cache at index 0 of station 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] Including PMKID in M1 (16)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] [0000] 73 eb 10 4d 52 09 8f b6 ea 14 49 4d 59 ec d4 77

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] M1 - Key Data: (22)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] [0000] dd 14 00 0f ac 04 73 eb 10 4d 52 09 8f b6 ea 14

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] [0016] 49 4d 59 ec d4 77

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Starting key exchange to mobile 4e:23:22:77:1e:4c, data packets will be dropped
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Sending EAPOL-Key Message to mobile 4e:23:22:77:1e:4c
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.480: [SA] 4e:23:22:77:1e:4c Allocating EAP Pkt for retransmission to mobile 4e:23:22:77:1e:4c
*dot1xSocketTask: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c validating eapol pkt: key version = 3
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Received EAPOL-Key from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Received EAPOL-key in PTK_START state (message 2) from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Encryption Policy: 4, PTK Key Length: 48
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] R1KHID+S1KHID: (12)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] [0000] c4 f7 d5 e4 55 9f 4e 23 22 77 1e 4c

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Successfully computed PTK from PMK!!!
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Received valid MIC in EAPOL Key Message M2!!!!!
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Compare RSN IE in association and EAPOL-M2 frame(Skip pmkIdLen:16,and grpMgmtCipherLen:0)
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 00000010: 00 0f ac 04 0c 00 ......
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 00000010: ac 04 0c 00 01 00 b2 8b f5 b8 6e 65 63 cb 9b ac ..........nec...
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 00000020: 03 4a 16 61 5a 6d .J.aZm
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c 11r-FT: Validate MDIE info in M2 message from mobile
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Calculating PMKR0Name
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Encrypt EAPOL packets = FALSE
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Validate the 11r FTIE element received in the M2 against the assoc respnse FTIE
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Received FTIE matches with what is being sent in Assoc Response frame
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Successfully validated the FTIE in M2 with IE sent in association response
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Not Flex client. Do not distribute dot11r Key cache.
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Stopping retransmission timer for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Sending EAPOL-Key Message to mobile 4e:23:22:77:1e:4c
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.506: [SA] 4e:23:22:77:1e:4c Reusing allocated memory for EAP Pkt for retransmission to mobile 4e:23:22:77:1e:4c
*dot1xSocketTask: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c validating eapol pkt: key version = 3
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c Received EAPOL-Key from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c key Desc Version FT - 1

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c Stopping retransmission timer for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c Freeing EAP Retransmit Bufer for mobile 4e:23:22:77:1e:4c
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c Vlan while overriding the policy = -1
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 70:d3:79:e3:59:a0 vapId 5 apVapId 5 flex-acl-name:
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c apfMsRunStateInc
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Reached PLUMBFASTPATH: from line 6894, null
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Adding Fast Path rule
type = Airespace AP Client
on AP 70:d3:79:e3:59:a0, slot 0, interface = 2, QOS = 0
IPv4 ACL ID = 255, IPv6 ACL ID
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 64206, IntfId = 14 Local Bridging Vlan = 120, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.520: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Fast Path rule (contd...) AVC Ratelimit: AppID = 0 ,AppAction = 0, AppToken = 64206 AverageRate = 0, BurstRate = 0

*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.521: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255,URL ACL ID 255)
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.521: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) No 11v BTM
*Dot1x_NW_MsgTask_4: Aug 13 15:10:42.521: [SA] 4e:23:22:77:1e:4c 192.168.120.214 RUN (20) NO release MSCB
*spamApTask3: Aug 13 15:10:42.521: [SA] 4e:23:22:77:1e:4c Successful transmission of LWAPP Add-Mobile to AP 70:d3:79:e3:59:a0
*pemReceiveTask: Aug 13 15:10:42.521: [SA] 4e:23:22:77:1e:4c 192.168.120.214 Added NPU entry of type 1, dtlFlags 0x0
*pemReceiveTask: Aug 13 15:10:42.521: [SA] 4e:23:22:77:1e:4c Pushing IPv6: fdec:f400:31d6:4a26:18b0:525a:3120:cc37 , intfId:14 and MAC: 4E:23:22:77:1E:4C , Binding to Data Plane. SUCCESS !!
*apfOpenDtlSocket: Aug 13 15:10:44.556: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:45
*apfMsConnTask_0: Aug 13 15:10:44.557: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*spamApTask0: Aug 13 15:10:46.322: [SA] 4e:23:22:77:1e:4c Received DELETE mobile, reason MN_REASSOC_TIMEOUT, from AP 70:d3:79:e3:4d:a0, slot 0 ...cleaning up mscb
*spamApTask0: Aug 13 15:10:46.322: [SA] 4e:23:22:77:1e:4c Warning, ignore the DELETE_MOBILE_PAYLOAD from AP: 70:d3:79:e3:4d:a0, slot 0. STA connecting AP: 70:d3:79:e3:59:a0, slot 0
*apfOpenDtlSocket: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:4B
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_0: Aug 13 15:10:47.896: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Got action frame from the client (ActionCategory:6)
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:4D
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_0: Aug 13 15:10:48.730: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab
*apfOpenDtlSocket: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Recevied management frame ACTION on BSSID 70:d3:79:e3:59:a4 destination addr 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Doing preauth for this client over the DS
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Doing local roaming for destination address 70:d3:79:e3:59:a4
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Got 1 AKMs in RSNIE
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c RSNIE AKM matches with PMK cache entry :0x4
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c pmkRoName derived sucessfully
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Validate FTIE for R0KH-ID, Store SNonce passed
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c FT Auth over the ds. Generate the Anonce ..CD:3B...B2:52
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Removing TGr reauth entry for AP 70:d3:79:e3:4d:ab
*apfMsConnTask_0: Aug 13 15:10:52.520: [SA] 4e:23:22:77:1e:4c Created a new preauth entry for AP:70:d3:79:e3:4d:ab

marce1000 · ‎08-13-2023

- Use latest advisory release on the controller : https://software.cisco.com/download/home/286284738/type/280926587/release/8.10.185.0 , as per https://www.cisco.com/c/en/us/support/docs/wireless/wireless-lan-controller-software/200046-tac-recommended-aireos.html

Make sure the client wifi driver(s) are up to date. Have your debugging output for the particular mac address analyzed with :
https://cway.cisco.com/wireless-debug-analyzer/

M.

-- Each morning when I wake up and look into the mirror I always say ' Why am I so brilliant ? '
When the mirror will then always repond to me with ' The only thing that exceeds your brilliance is your beauty! '

View solution in original post

marce1000 · ‎08-13-2023

- Use latest advisory release on the controller : https://software.cisco.com/download/home/286284738/type/280926587/release/8.10.185.0 , as per https://www.cisco.com/c/en/us/support/docs/wireless/wireless-lan-controller-software/200046-tac-recommended-aireos.html

Make sure the client wifi driver(s) are up to date. Have your debugging output for the particular mac address analyzed with :
https://cway.cisco.com/wireless-debug-analyzer/

M.

-- Each morning when I wake up and look into the mirror I always say ' Why am I so brilliant ? '
When the mirror will then always repond to me with ' The only thing that exceeds your brilliance is your beauty! '

avitgroup · ‎08-14-2023

Hi,

Attached is debug output in event when client have issue