cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
478
Views
3
Helpful
6
Replies

DHCP dropping packet due to ongoing mobility handshake exchange

networklearner1
Level 1
Level 1

Hello All,

we have our new mobility Anchoring setup. When we try connecting the client, the end user does not get IP.

below is the debug logs-

(Cisco Controller) >debug client c6:c7:a6:b5:2e:df

(Cisco Controller) >*emWeb: Jan 17 14:59:17.723: [PA]
Debugging session started on Jan 17 14:59:17.723 for WLC AIR-CT3504-K9 Version :8.10.185.0 SN :FCW2439K676 Hostname WLCANC-43-1
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] processIncomingMessages: Received a message from server 10.147.15.43 of len 202 with reason code 0
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] processIncomingMessages: received a frame with code 43 from 10.147.15.43 on port:57932
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] the value to be parsed subscriber:command=reauthenticate+ and the length =35

*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] Reauthenticate command = reauthenticate+
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] the value to be parsed subscriber:reauthenticate-type=last1 and the length =37

*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] the value to be parsed audit-session-id=28dde0220007d28a65a79d8e and the length =43

*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] audit session ID recieved in CoA = 28dde0220007d28a65a79d8e
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] Message Authenticator calc'ed - c1:b2:d6:ce:6b:50:65:3e:e9:48:9a:d2:7f:79:bf:65 rawlen 202
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] Request Authenticator - 36:94:64:0d:16:2f:7e:95:8e:3e:59:a8:c1:32:c9:25
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] Received a 'CoA-Request' from 10.147.15.43 port 57932
*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] Packet contains 9 AVPs:

*radiusCoASupportTransportThread: Jan 17 14:59:41.197: [PA] AVP[01] Nas-Ip-Address...........................0x28dde022 (685629474) (4 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[03] Acct-Event-Time..........................0x65a79e05 (1705483781) (4 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[04] Message-Authenticator....................DATA (16 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[05] Unknown Attribute 0......................NULL (0 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[06] Unknown Attribute 0......................NULL (0 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[07] Unknown Attribute 0......................NULL (0 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[08] Unknown Attribute 0......................NULL (0 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] AVP[09] Unknown Attribute 0......................NULL (0 bytes)

*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] Handling a valid 'CoA-Request' regarding station 00:a5:54:53:7f:55
*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] CoA - Received IP Address : 40.221.224.34, Vlan ID: (received 228)
*radiusCoASupportTransportThread: Jan 17 14:59:41.198: [PA] Sent a 'CoA-Ack' to 10.147.15.43 (port:57932)
*apfOpenDtlSocket: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df Received management frame ASSOCIATION REQUEST on BSSID bc:26:c7:f1:14:86 destination addr bc:26:c7:f1:14:86 slotid 1
*apfMsConnTask_0: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df Updating the client capabiility as 5
*apfMsConnTask_0: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df Processing assoc-req station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-01 ssid : testnew thread:257bb358
*apfMsConnTask_0: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df apfCreateMobileStationEntryWrapper (apf_ms.c:4510) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Idle

*apfMsConnTask_0: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df Adding mobile on LWAPP AP bc:26:c7:f1:14:80(1)
*apfMsConnTask_0: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df Created Acct-Session-ID (65a79e06/c6:c7:a6:b5:2e:df/523050) for the mobile
*apfMsConnTask_0: Jan 17 14:59:42.882: [PA] c6:c7:a6:b5:2e:df Setting hasApChnaged Flag as true. It is a fresh assoc request.

*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df req rcv on open Wlan
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Setting RTTS enabled to 0
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Association received from mobile on BSSID bc:26:c7:f1:14:86 AP bgxly-7ar-lap-4
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df 11v BSS Transition not enabled on the AP BC:26:C7:F1:14:80
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Max Client Trap Threshold: 0 cur: 51

*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Updated local bridging VLAN to 228 while applying WLAN policy
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Updated session timeout to 0 and Sleep timeout to 720 while applying WLAN policy
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df Re-applying interface (management) policy for client
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df After applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 228

*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3498)
*apfMsConnTask_0: Jan 17 14:59:42.883: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255),Default action is '0' --- (caller apf_policy.c:3518)
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3539)
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Values before applying NASID - interfacetype:0, ovrd:0, mscb nasid:, interface nasid:, APgrpset:0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Check before Setting the NAS Id to WLAN specific Id ''
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df apf_policy.c:2783 Assigning the SGT 0 to mobile (earlier sgt 0)
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Increment the SGT 0 policy count reference by the clients 1976
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Check the client SGT 0 policy and push it to AP bc:26:c7:f1:14:80
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7634 setting Central switched to TRUE
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7637 apVapId = 10 and Split Acl Id = 65535
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Applying site-specific Local Bridging override for station c6:c7:a6:b5:2e:df - vapId 10, site 'default-group', interface 'management'
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Applying Local Bridging Interface Policy for station c6:c7:a6:b5:2e:df - vlan 228, interface id 0, interface 'management', nasId:''
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df STA - rates (4): 48 72 224 108 0 0 0 0 0 0 0 0 0 0 0 0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Assigning flex webauth IPv4-ACL ID :65535, IPv6-ACL ID:65535 for AP WLAN ID : 10
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Allocate AID 177 slot 1 on AP bgxly-7ar-lap-4 #clients on this slot 52
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Assoc Req BSSID bc:26:c7:f1:14:86 AP(bgxly-7ar-lap-4) slot 1 ssid (testnew) Tmstmp 3465551 AID 177 stCode 0/0 apChngd 0 oldAp 00:00:00:00:00:00
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Initializing policy
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df apfVapSecurity=0x2040 L2=0 SkipWeb=0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df AuthenticationRequired = 0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Not Using WMM Compliance code qosCap 00
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df flex webauth ipv4 & ipv6 acl ids to be sent when fabric is disabled:65535/65535
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df flex webauth acl id to be sent :65535 name : client acl id :65535 name :
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df flex webauth ipv6 acl id to be sent :65535 name : client acl id :65535 name :
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Vlan while overriding the policy = -1
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535

*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP bc:26:c7:f1:14:80 vapId 10 apVapId 10 flex acl-name: v6acl-name
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Not sending Registration for Fabric client. Primary and Secondary MS IP is zero
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df apfMsAssoStateInc
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2 (apf_policy.c:465) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Associated

*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2:session timeout forstation c6:c7:a6:b5:2e:df - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Sending assoc-resp with status 0 station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-01 on apVapId 10
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df VHT Operation IE: width 40/0 ch 36 freq0 0 freq1 0 msc0 0xff msc1 0xff
*apfMsConnTask_0: Jan 17 14:59:42.884: [PA] c6:c7:a6:b5:2e:df Sending Assoc Response (status: '0') to station on AP bgxly-7ar-lap-4 on BSSID bc:26:c7:f1:14:86 ApVapId 10 Slot 1, mobility role 0
*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df apfProcessAssocReq (apf_80211.c:12998) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Associated

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Mobility query, PEM State: DHCP_REQD

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) NO release MSCB
*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Building Mobile Announce :

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Client Ip: 0.0.0.0

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Client Vlan Ip: 40.221.224.34, Vlan mask : 255.255.224.0

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Client Vap Security: 8256

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Virtual Ip: 10.0.0.1

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df ssid: testnew

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Profile Name: testnew

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Building VlanIpPayload.

*apfMsConnTask_0: Jan 17 14:59:42.885: [PA] c6:c7:a6:b5:2e:df Mobile Announce sent to 1 members of the local group.
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df Add SGT:0 to AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df Add CTS mobile SGT - Encoded the capwap payload for the mobile with SGT 0
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df MS IP NULL during AddMobile, not sending IP Distribution
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df Flex Ipv6 pre-auth acl is not present, not Encoding Flex Ipv6 acl for add mobile Payload
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df Flex Ipv6 post auth acl is not present, not updating add mobile Payload
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df Successful transmission of LWAPP Add-Mobile to AP bc:26:c7:f1:14:80 slotId 1 idx@22
*spamApTask7: Jan 17 14:59:42.886: [PA] c6:c7:a6:b5:2e:df Setting ADD_MOBILE (idx 23, action 0, last count 0) ack state for STA on AP bc:26:c7:f1:14:80
*apfOpenDtlSocket: Jan 17 14:59:42.911: [PA] c6:c7:a6:b5:2e:df Received management frame ACTION on BSSID bc:26:c7:f1:14:86 destination addr bc:26:c7:f1:14:86 slotid 1
*apfMsConnTask_0: Jan 17 14:59:42.911: [PA] c6:c7:a6:b5:2e:df Got action frame from the client (ActionCategory:5), payloadLen:12
*apfMsConnTask_0: Jan 17 14:59:42.911: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df sent 802.11K neighbor request to AP BC:26:C7:F1:14:80
*apfMsConnTask_0: Jan 17 14:59:42.911: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df requested neighbors on non XOR roam capable AP BC:26:C7:F1:14:80 Slot 1
*DHCP Socket Task: Jan 17 14:59:43.072: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:43.072: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:43.072: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*DHCP Socket Task: Jan 17 14:59:43.571: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:43.571: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:43.571: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*DHCP Socket Task: Jan 17 14:59:44.099: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:44.099: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:44.099: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*DHCP Socket Task: Jan 17 14:59:44.598: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:44.599: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:44.599: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*DHCP Socket Task: Jan 17 14:59:45.598: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:45.598: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:45.598: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Attempting anchor export for mobile c6:c7:a6:b5:2e:df

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Client Ip: 0.0.0.0

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Client Vlan Ip: 40.221.224.34, Vlan mask : 255.255.224.0

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Client Vap Security: 8256

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Virtual Ip: 10.0.0.1

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df ssid: testnew

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Building Client profile name Payload:

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Profile Name: testnew

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Building policy payload

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df mmAnchorExportSend: Building ExportForeignLradMac Payload Lrad Mac: bc:26:c7:f1:14:80

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Created Cisco-Audit-Session-ID for the mobile: 28dde0220007d2ce65a79e09 type: local
*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Building Audit session ID (28dde0220007d2ce65a79e09) payload in Mobility handoff
*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Building IPv6 Client Payload:
*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df mmBuildIPv6ClientPayload: IPv6: Built IPv6 client payload vlan =228, numIPv6Addr =0
*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Building IPv6 Client Payload

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df SGT Payload buiding in handoff 0 (len 6)

*apfReceiveTask: Jan 17 14:59:45.788: [PA] c6:c7:a6:b5:2e:df Export Anchor request sent to 10.208.134.212

*DHCP Socket Task: Jan 17 14:59:45.991: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:45.991: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:45.991: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmAnchorExportRequested'
*DHCP Socket Task: Jan 17 14:59:46.491: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:46.491: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:46.491: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmAnchorExportRequested'
*DHCP Socket Task: Jan 17 14:59:46.492: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:46.492: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:46.492: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmAnchorExportRequested'
*mmMobility: Jan 17 14:59:46.780: [PA] c6:c7:a6:b5:2e:df Mobility Anchor packet retry: Peer IP: 10.208.134.212, Anchor IP: 0.0.0.0

*DHCP Socket Task: Jan 17 14:59:47.492: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:47.492: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:47.492: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmAnchorExportRequested'
*mmMobility: Jan 17 14:59:47.771: [PA] c6:c7:a6:b5:2e:df Mobility Anchor packet retry: Peer IP: 10.208.134.212, Anchor IP: 0.0.0.0

*apfReceiveTask: Jan 17 14:59:48.763: [PA] c6:c7:a6:b5:2e:df apfMmProcessResponse: Got Anchor Close from mmMipAnchorResendTimeoutHandler for mobile c6:c7:a6:b5:2e:df, delete mobile. reason code = 2 MmRole : 0
*apfReceiveTask: Jan 17 14:59:48.763: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) mobility role update request from Unassociated to Handoff
Peer = 10.208.134.212, Old Anchor = 10.208.134.212, New Anchor = 0.0.0.0
*apfReceiveTask: Jan 17 14:59:48.763: [PA] c6:c7:a6:b5:2e:df IP Addr Clear. AP MAC[bc:26:c7:f1:14:80] Role[Unassociated] Pem State [DHCP_REQD] Connected Time[6]
*apfReceiveTask: Jan 17 14:59:48.763: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) Reached ERROR: from line 7760
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df apfMmProcessCloseResponse (apf_mm.c:723) Expiring Mobile!
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df apfMsExpireMobileStation (apf_ms.c:8142) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Disassociated

*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df apfMsExpireMobileStation (apf_ms.c:8294) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Disassociated to Idle

*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df the value of url acl preserve flag is 0 for mobile c6:c7:a6:b5:2e:df (caller pem_api.c:5292)
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [bc:26:c7:f1:14:80]
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df Succesfully freed AID 177, slot 1 on AP bc:26:c7:f1:14:80, #client on this slot 50
*spamApTask7: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df Setting DEL_MOBILE (seqno 0, action 6) ack state for STA on AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df Setting ADD_MOBILE (idx 26, seqno 115, action 6, count 115, last count 115) ack state for STA on AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df Delete Mobile request with reason 'MN_DEL_PEM_CLEANUP' on slot 1 sent to the AP bc:26:c7:f1:14:80 IP: 40.221.225.47:5256.
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df Deleting mobile on AP bc:26:c7:f1:14:80(1)
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df apf_ms.c:5636 Clearing the SGT 0 of mobile
*apfReceiveTask: Jan 17 14:59:48.764: [PA] c6:c7:a6:b5:2e:df Decrement the SGT 0 policy count reference by the clients 1976
*spamApTask7: Jan 17 14:59:48.767: [PA] c6:c7:a6:b5:2e:df Received add/del ack packet with sequence number: got 115 expected 115 action = 6
*spamApTask7: Jan 17 14:59:48.767: [PA] c6:c7:a6:b5:2e:df Received DELETE mobile, reasonCode UNKNOWN, deleteReason 15 from AP bc:26:c7:f1:14:80, slot 1 ...cleaning up mscb
*apfOpenDtlSocket: Jan 17 14:59:50.635: [PA] c6:c7:a6:b5:2e:df Received management frame REASSOCIATION REQUEST on BSSID bc:26:c7:f1:14:89 destination addr bc:26:c7:f1:14:89 slotid 0
*apfMsConnTask_0: Jan 17 14:59:50.635: [PA] c6:c7:a6:b5:2e:df Updating the client capabiility as 5
*apfMsConnTask_0: Jan 17 14:59:50.635: [PA] c6:c7:a6:b5:2e:df Processing assoc-req station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-00 ssid : testnew thread:257bb358
*apfMsConnTask_0: Jan 17 14:59:50.635: [PA] c6:c7:a6:b5:2e:df apfCreateMobileStationEntryWrapper (apf_ms.c:4510) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Idle

*apfMsConnTask_0: Jan 17 14:59:50.635: [PA] c6:c7:a6:b5:2e:df Adding mobile on LWAPP AP bc:26:c7:f1:14:80(0)
*apfMsConnTask_0: Jan 17 14:59:50.636: [PA] c6:c7:a6:b5:2e:df Created Acct-Session-ID (65a79e0e/c6:c7:a6:b5:2e:df/523056) for the mobile
*apfMsConnTask_0: Jan 17 14:59:50.636: [PA] c6:c7:a6:b5:2e:df Setting hasApChnaged Flag as true. It is a fresh assoc request.

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df req rcv on open Wlan
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Setting RTTS enabled to 0
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Reassociation received from mobile on BSSID bc:26:c7:f1:14:89 AP bgxly-7ar-lap-4
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df 11v BSS Transition not enabled on the AP BC:26:C7:F1:14:80
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Max Client Trap Threshold: 0 cur: 4

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Updated local bridging VLAN to 228 while applying WLAN policy
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Updated session timeout to 0 and Sleep timeout to 720 while applying WLAN policy
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Re-applying interface (management) policy for client
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df After applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 228

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3498)
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255),Default action is '0' --- (caller apf_policy.c:3518)
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3539)
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Values before applying NASID - interfacetype:0, ovrd:0, mscb nasid:, interface nasid:, APgrpset:0
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Check before Setting the NAS Id to WLAN specific Id ''
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df apf_policy.c:2783 Assigning the SGT 0 to mobile (earlier sgt 0)
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Increment the SGT 0 policy count reference by the clients 1976
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Check the client SGT 0 policy and push it to AP bc:26:c7:f1:14:80
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7634 setting Central switched to TRUE
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7637 apVapId = 10 and Split Acl Id = 65535
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Applying site-specific Local Bridging override for station c6:c7:a6:b5:2e:df - vapId 10, site 'default-group', interface 'management'
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Applying Local Bridging Interface Policy for station c6:c7:a6:b5:2e:df - vlan 228, interface id 0, interface 'management', nasId:''
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df STA - rates (4): 176 72 96 108 0 0 0 0 0 0 0 0 0 0 0 0
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Assigning flex webauth IPv4-ACL ID :65535, IPv6-ACL ID:65535 for AP WLAN ID : 10
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Allocate AID 21 slot 0 on AP bgxly-7ar-lap-4 #clients on this slot 5
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df Reassoc Req BSSID bc:26:c7:f1:14:89 AP(bgxly-7ar-lap-4) slot 0 ssid (testnew) Tmstmp 3465558 AID 21 stCode 0/0 apChngd 0 oldAp 00:00:00:00:00:00
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Initializing policy
*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_0: Jan 17 14:59:50.637: [PA] c6:c7:a6:b5:2e:df apfVapSecurity=0x2040 L2=0 SkipWeb=0
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Not Using WMM Compliance code qosCap 00
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df flex webauth ipv4 & ipv6 acl ids to be sent when fabric is disabled:65535/65535
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df flex webauth ipv6 acl id to be sent :65535 name : client acl id :65535 name :
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Vlan while overriding the policy = -1
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP bc:26:c7:f1:14:80 vapId 10 apVapId 10 flex acl-name: v6acl-name
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Not sending Registration for Fabric client. Primary and Secondary MS IP is zero
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df apfMsAssoStateInc
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2 (apf_policy.c:465) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Associated

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2:session timeout forstation c6:c7:a6:b5:2e:df - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Sending assoc-resp with status 0 station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-00 on apVapId 10
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Sending Assoc Response (status: '0') to station on AP bgxly-7ar-lap-4 on BSSID bc:26:c7:f1:14:89 ApVapId 10 Slot 0, mobility role 0
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df apfProcessAssocReq (apf_80211.c:12998) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Associated

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Mobility query, PEM State: DHCP_REQD

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) NO release MSCB
*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Building Mobile Announce :

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfMsConnTask_0: Jan 17 14:59:50.638: [PA] c6:c7:a6:b5:2e*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Attempting anchor export for mobile c6:c7:a6:b5:2e:df

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Client Ip: 0.0.0.0

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Client Vap Security: 8256

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Virtual Ip: 10.0.0.1

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df ssid: testnew

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Building Client profile name Payload:

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Profile Name: testnew

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Building policy payload

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df mmAnchorExportSend: Building ExportForeignLradMac Payload Lrad Mac: bc:26:c7:f1:14:80

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Created Cisco-Audit-Session-ID for the mobile: 28dde0220007d2d465a79e11 type: local
*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Building Audit session ID (28dde0220007d2d465a79e11) payload in Mobility handoff
*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Building IPv6 Client Payload:
*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df mmBuildIPv6ClientPayload: IPv6: Built IPv6 client payload vlan =228, numIPv6Addr =0
*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Building IPv6 Client Payload

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df SGT Payload buiding in handoff 0 (len 6)

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df SGT Payload built in Export Anchor Req 0

*apfReceiveTask: Jan 17 14:59:53.524: [PA] c6:c7:a6:b5:2e:df Export Anchor request sent to 10.208.134.212

*mmMobility: Jan 17 14:59:54.516: [PA] c6:c7:a6:b5:2e:df Mobility Anchor packet retry: Peer IP: 10.208.134.212, Anchor IP: 0.0.0.0

*mmMobility: Jan 17 14:59:55.508: [PA] c6:c7:a6:b5:2e:df Mobility Anchor packet retry: Peer IP: 10.208.134.212, Anchor IP: 0.0.0.0

*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df apfMmProcessResponse: Got Anchor Close from mmMipAnchorResendTimeoutHandler for mobile c6:c7:a6:b5:2e:df, delete mobile. reason code = 2 MmRole : 0
*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) mobility role update request from Unassociated to Handoff
Peer = 10.208.134.212, Old Anchor = 10.208.134.212, New Anchor = 0.0.0.0
*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df IP Addr Clear. AP MAC[bc:26:c7:f1:14:80] Role[Unassociated] Pem State [DHCP_REQD] Connected Time[6]
*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) Reached ERROR: from line 7760
*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df apfMmProcessCloseResponse (apf_mm.c:723) Expiring Mobile!
*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df apfMsExpireMobileStation (apf_ms.c:8142) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Disassociated

*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df apfMsAssoStateDec
*apfReceiveTask: Jan 17 14:59:56.499: [PA] c6:c7:a6:b5:2e:df apfMsExpireMobileStation (apf_ms.c:8294) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Disassociated to Idle

*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df the value of url acl preserve flag is 0 for mobile c6:c7:a6:b5:2e:df (caller pem_api.c:5292)
*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [bc:26:c7:f1:14:80]
*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df Succesfully freed AID 21, slot 0 on AP bc:26:c7:f1:14:80, #client on this slot 3
*spamApTask7: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df Setting DEL_MOBILE (seqno 0, action 6) ack state for STA on AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df Setting ADD_MOBILE (idx 33, seqno 122, action 6, count 122, last count 122) ack state for STA on AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df Delete Mobile request with reason 'MN_DEL_PEM_CLEANUP' on slot 0 sent to the AP bc:26:c7:f1:14:80 IP: 40.221.225.47:5256.
*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df Deleting mobile on AP bc:26:c7:f1:14:80(0)
*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df apf_ms.c:5636 Clearing the SGT 0 of mobile
*apfReceiveTask: Jan 17 14:59:56.500: [PA] c6:c7:a6:b5:2e:df Decrement the SGT 0 policy count reference by the clients 1971
*spamApTask7: Jan 17 14:59:56.502: [PA] c6:c7:a6:b5:2e:df Received add/del ack packet with sequence number: got 122 expected 122 action = 6
*spamApTask7: Jan 17 14:59:56.502: [PA] c6:c7:a6:b5:2e:df Received DELETE mobile, reasonCode UNKNOWN, deleteReason 15 from AP bc:26:c7:f1:14:80, slot 0 ...cleaning up mscb
*apfOpenDtlSocket: Jan 17 14:59:57.982: [PA] c6:c7:a6:b5:2e:df Received management frame REASSOCIATION REQUEST on BSSID bc:26:c7:f1:14:86 destination addr bc:26:c7:f1:14:86 slotid 1
*apfMsConnTask_0: Jan 17 14:59:57.983: [PA] c6:c7:a6:b5:2e:df Updating the client capabiility as 5
*apfMsConnTask_0: Jan 17 14:59:57.983: [PA] c6:c7:a6:b5:2e:df Processing assoc-req station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-01 ssid : testnew thread:257bb358
*apfMsConnTask_0: Jan 17 14:59:57.983: [PA] c6:c7:a6:b5:2e:df apfCreateMobileStationEntryWrapper (apf_ms.c:4510) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Idle

*apfMsConnTask_0: Jan 17 14:59:57.983: [PA] c6:c7:a6:b5:2e:df Adding mobile on LWAPP AP bc:26:c7:f1:14:80(1)
*apfMsConnTask_0: Jan 17 14:59:57.983: [PA] c6:c7:a6:b5:2e:df Created Acct-Session-ID (65a79e15/c6:c7:a6:b5:2e:df/523057) for the mobile
*apfMsConnTask_0: Jan 17 14:59:57.983: [PA] c6:c7:a6:b5:2e:df Setting hasApChnaged Flag as true. It is a fresh assoc request.

*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df req rcv on open Wlan
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Setting RTTS enabled to 0
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Reassociation received from mobile on BSSID bc:26:c7:f1:14:86 AP bgxly-7ar-lap-4
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df 11v BSS Transition not enabled on the AP BC:26:C7:F1:14:80
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Max Client Trap Threshold: 0 cur: 50

*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Updated local bridging VLAN to 228 while applying WLAN policy
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df override for default ap group, marking intgrp NULL
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Re-applying interface (management) policy for client
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df After applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 228

*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255),Default action is '0' --- (caller apf_policy.c:3518)
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3539)
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Values before applying NASID - interfacetype:0, ovrd:0, mscb nasid:, interface nasid:, APgrpset:0
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Check before Setting the NAS Id to WLAN specific Id ''
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df apf_policy.c:2783 Assigning the SGT 0 to mobile (earlier sgt 0)
*apfMsConnTask_0: Jan 17 14:59:57.984: [PA] c6:c7:a6:b5:2e:df Increment the SGT 0 policy count reference by the clients 1971
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7634 setting Central switched to TRUE
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7637 apVapId = 10 and Split Acl Id = 65535
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Applying site-specific Local Bridging override for station c6:c7:a6:b5:2e:df - vapId 10, site 'default-group', interface 'management'
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Applying Local Bridging Interface Policy for station c6:c7:a6:b5:2e:df - vlan 228, interface id 0, interface 'management', nasId:''
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df STA - rates (4): 48 72 224 108 0 0 0 0 0 0 0 0 0 0 0 0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Assigning flex webauth IPv4-ACL ID :65535, IPv6-ACL ID:65535 for AP WLAN ID : 10
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Allocate AID 176 slot 1 on AP bgxly-7ar-lap-4 #clients on this slot 51
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Reassoc Req BSSID bc:26:c7:f1:14:86 AP(bgxly-7ar-lap-4) slot 1 ssid (testnew) Tmstmp 3465566 AID 176 stCode 0/0 apChngd 0 oldAp 00:00:00:00:00:00
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Initializing policy
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df apfVapSecurity=0x2040 L2=0 SkipWeb=0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df AuthenticationRequired = 0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Not Using WMM Compliance code qosCap 00
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df flex webauth ipv4 & ipv6 acl ids to be sent when fabric is disabled:65535/65535
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df flex webauth acl id to be sent :65535 name : client acl id :65535 name :
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df flex webauth ipv6 acl id to be sent :65535 name : client acl id :65535 name :
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Vlan while overriding the policy = -1
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535

*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP bc:26:c7:f1:14:80 vapId 10 apVapId 10 flex acl-name: v6acl-name
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Not sending Registration for Fabric client. Primary and Secondary MS IP is zero
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df apfMsAssoStateInc
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2 (apf_policy.c:465) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Associated

*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2:session timeout forstation c6:c7:a6:b5:2e:df - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Sending assoc-resp with status 0 station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-01 on apVapId 10
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df VHT Operation IE: width 40/0 ch 36 freq0 0 freq1 0 msc0 0xff msc1 0xff
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df Sending Assoc Response (status: '0') to station on AP bgxly-7ar-lap-4 on BSSID bc:26:c7:f1:14:86 ApVapId 10 Slot 1, mobility role 0
*apfMsConnTask_0: Jan 17 14:59:57.985: [PA] c6:c7:a6:b5:2e:df apfProcessAssocReq (apf_80211.c:12998) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Associated

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Mobility query, PEM State: DHCP_REQD

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) NO release MSCB
*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Building Mobile Announce :

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Client Ip: 0.0.0.0

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Client Vap Security: 8256

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Virtual Ip: 10.0.0.1

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df ssid: testnew

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Building Client profile name Payload:

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Profile Name: testnew

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Building VlanIpPayload.

*apfMsConnTask_0: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Mobile Announce sent to 1 members of the local group.
*spamApTask7: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Add SGT:0 to AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Add CTS mobile SGT - Encoded the capwap payload for the mobile with SGT 0
*spamApTask7: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df MS IP NULL during AddMobile, not sending IP Distribution
*spamApTask7: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Flex Ipv6 pre-auth acl is not present, not Encoding Flex Ipv6 acl for add mobile Payload
*spamApTask7: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Successful transmission of LWAPP Add-Mobile to AP bc:26:c7:f1:14:80 slotId 1 idx@34
*spamApTask7: Jan 17 14:59:57.986: [PA] c6:c7:a6:b5:2e:df Setting ADD_MOBILE (idx 35, action 0, last count 0) ack state for STA on AP bc:26:c7:f1:14:80
*DHCP Socket Task: Jan 17 14:59:58.056: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 312,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:58.056: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:58.056: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*DHCP Socket Task: Jan 17 14:59:58.556: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 312,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:58.556: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:58.556: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*DHCP Socket Task: Jan 17 14:59:59.557: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 312,vlan 228, port 8, encap 0xec03, xid 0x667c0103)
*DHCP Socket Task: Jan 17 14:59:59.557: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 14:59:59.557: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Attempting anchor export for mobile c6:c7:a6:b5:2e:df

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Client Ip: 0.0.0.0

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Client Vap Security: 8256

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Virtual Ip: 10.0.0.1

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df ssid: testnew

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Building Client profile name Payload:

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Profile Name: testnew

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Building policy payload

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df mmAnchorExportSend: Building ExportForeignLradMac Payload Lrad Mac: bc:26:c7:f1:14:80

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Created Cisco-Audit-Session-ID for the mobile: 28dde0220007d2d665a79e18 type: local
*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Building Audit session ID (28dde0220007d2d665a79e18) payload in Mobility handoff
*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df Building IPv6 Client Payload:
*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df mmBuildIPv6ClientPayload: IPv6: Built IPv6 client payload vlan =228, numIPv6Addr =0
*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df SGT Payload buiding in handoff 0 (len 6)

*apfReceiveTask: Jan 17 15:00:00.863: [PA] c6:c7:a6:b5:2e:df SGT Payload built in Export Anchor Req 0

*apfReceiveTask: Jan 17 15:00:00.864: [PA] c6:c7:a6:b5:2e:df Export Anchor request sent to 10.208.134.212

*mmMobility: Jan 17 15:00:01.855: [PA] c6:c7:a6:b5:2e:df Mobility Anchor packet retry: Peer IP: 10.208.134.212, Anchor IP: 0.0.0.0

*mmMobility: Jan 17 15:00:02.847: [PA] c6:c7:a6:b5:2e:df Mobility Anchor packet retry: Peer IP: 10.208.134.212, Anchor IP: 0.0.0.0

*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df apfMmProcessResponse: Got Anchor Close from mmMipAnchorResendTimeoutHandler for mobile c6:c7:a6:b5:2e:df, delete mobile. reason code = 2 MmRole : 0
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) mobility role update request from Unassociated to Handoff
Peer = 10.208.134.212, Old Anchor = 10.208.134.212, New Anchor = 0.0.0.0
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df IP Addr Clear. AP MAC[bc:26:c7:f1:14:80] Role[Unassociated] Pem State [DHCP_REQD] Connected Time[6]
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) Reached ERROR: from line 7760
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df apfMmProcessCloseResponse (apf_mm.c:723) Expiring Mobile!
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df apfMsExpireMobileStation (apf_ms.c:8142) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Disassociated

*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df apfMsAssoStateDec
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df apfMsExpireMobileStation (apf_ms.c:8294) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Disassociated to Idle

*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df the value of url acl preserve flag is 0 for mobile c6:c7:a6:b5:2e:df (caller pem_api.c:5292)
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [bc:26:c7:f1:14:80]
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df Succesfully freed AID 176, slot 1 on AP bc:26:c7:f1:14:80, #client on this slot 51
*spamApTask7: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df Setting DEL_MOBILE (seqno 0, action 6) ack state for STA on AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df Setting ADD_MOBILE (idx 38, seqno 127, action 6, count 127, last count 127) ack state for STA on AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df Delete Mobile request with reason 'MN_DEL_PEM_CLEANUP' on slot 1 sent to the AP bc:26:c7:f1:14:80 IP: 40.221.225.47:5256.
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df Deleting mobile on AP bc:26:c7:f1:14:80(1)
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df apf_ms.c:5636 Clearing the SGT 0 of mobile
*apfReceiveTask: Jan 17 15:00:03.839: [PA] c6:c7:a6:b5:2e:df Decrement the SGT 0 policy count reference by the clients 1974
*spamApTask7: Jan 17 15:00:03.842: [PA] c6:c7:a6:b5:2e:df Received add/del ack packet with sequence number: got 127 expected 127 action = 6
*spamApTask7: Jan 17 15:00:03.842: [PA] c6:c7:a6:b5:2e:df Received DELETE mobile, reasonCode UNKNOWN, deleteReason 15 from AP bc:26:c7:f1:14:80, slot 1 ...cleaning up mscb
*Dot1x_NW_MsgTask_2: Jan 17 15:00:07.288: [PA] 1x: EAPOL frame with dst MAC 5c:5a:c7:cc:75:60 and BSSID 24:36:da:5d:f9:e0 discarded

(Cisco Controller) >
(Cisco Controller) >debug *apfOpenDtlSocket: Jan 17 15:00:28.192: [PA] c6:c7:a6:b5:2e:df Received management frame ASSOCIATION REQUEST on BSSID bc:26:c7:f1:14:86 destination addr bc:26:c7:f1:14:86 slotid 1
*apfMsConnTask_0: Jan 17 15:00:28.192: [PA] c6:c7:a6:b5:2e:df Updating the client capabiility as 5
*apfMsConnTask_0: Jan 17 15:00:28.192: [PA] c6:c7:a6:b5:2e:df Processing assoc-req station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-01 ssid : testnew thread:257bb358
*apfMsConnTask_0: Jan 17 15:00:28.193: [PA] c6:c7:a6:b5:2e:df apfCreateMobileStationEntryWrapper (apf_ms.c:4510) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Idle

*apfMsConnTask_0: Jan 17 15:00:28.193: [PA] c6:c7:a6:b5:2e:df Adding mobile on LWAPP AP bc:26:c7:f1:14:80(1)
*apfMsConnTask_0: Jan 17 15:00:28.193: [PA] c6:c7:a6:b5:2e:df Created Acct-Session-ID (65a79e34/c6:c7:a6:b5:2e:df/523078) for the mobile
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Setting hasApChnaged Flag as true. It is a fresh assoc request.

*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df req rcv on open Wlan
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Setting RTTS enabled to 0
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Association received from mobile on BSSID bc:26:c7:f1:14:86 AP bgxly-7ar-lap-4
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df 11v BSS Transition not enabled on the AP BC:26:C7:F1:14:80
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Global 200 Clients are allowed to AP radio

*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Max Client Trap Threshold: 0 cur: 50

*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Updated local bridging VLAN to 228 while applying WLAN policy
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Updated session timeout to 0 and Sleep timeout to 720 while applying WLAN policy
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Re-applying interface (management) policy for client
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df After applying Interface(management) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 228

*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3498)
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing Url ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255),Default action is '0' --- (caller apf_policy.c:3518)
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:3539)
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Values before applying NASID - interfacetype:0, ovrd:0, mscb nasid:, interface nasid:, APgrpset:0
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Check before Setting the NAS Id to WLAN specific Id ''
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df apf_policy.c:2783 Assigning the SGT 0 to mobile (earlier sgt 0)
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Increment the SGT 0 policy count reference by the clients 1982
*apfMsConnTask_0: Jan 17 15:00:28.194: [PA] c6:c7:a6:b5:2e:df Check the client SGT 0 policy and push it to AP bc:26:c7:f1:14:80
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7634 setting Central switched to TRUE
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df In processSsidIE:7637 apVapId = 10 and Split Acl Id = 65535
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Applying site-specific Local Bridging override for station c6:c7:a6:b5:2e:df - vapId 10, site 'default-group', interface 'management'
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Applying Local Bridging Interface Policy for station c6:c7:a6:b5:2e:df - vlan 228, interface id 0, interface 'management', nasId:''
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df STA - rates (4): 48 72 224 108 0 0 0 0 0 0 0 0 0 0 0 0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Assigning flex webauth IPv4-ACL ID :65535, IPv6-ACL ID:65535 for AP WLAN ID : 10
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Allocate AID 177 slot 1 on AP bgxly-7ar-lap-4 #clients on this slot 51
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Assoc Req BSSID bc:26:c7:f1:14:86 AP(bgxly-7ar-lap-4) slot 1 ssid (testnew) Tmstmp 3465596 AID 177 stCode 0/0 apChngd 0 oldAp 00:00:00:00:00:00
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Initializing policy
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df apfVapSecurity=0x2040 L2=0 SkipWeb=0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df AuthenticationRequired = 0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state AUTHCHECK (2)

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Not Using WMM Compliance code qosCap 00
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df flex webauth ipv4 & ipv6 acl ids to be sent when fabric is disabled:65535/65535
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df flex webauth acl id to be sent :65535 name : client acl id :65535 name :
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Vlan while overriding the policy = -1
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df sending to spamAddMobile vlanId -1 aclName = , flexAclId 65535

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP bc:26:c7:f1:14:80 vapId 10 apVapId 10 flex acl-name: v6acl-name
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Not sending Registration for Fabric client. Primary and Secondary MS IP is zero
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df apfMsAssoStateInc
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2 (apf_policy.c:465) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Idle to Associated

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df apfPemAddUser2:session timeout forstation c6:c7:a6:b5:2e:df - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Sending assoc-resp with status 0 station:c6:c7:a6:b5:2e:df AP:bc:26:c7:f1:14:80-01 on apVapId 10
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df VHT Operation IE: width 40/0 ch 36 freq0 0 freq1 0 msc0 0xff msc1 0xff
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Sending Assoc Response (status: '0') to station on AP bgxly-7ar-lap-4 on BSSID bc:26:c7:f1:14:86 ApVapId 10 Slot 1, mobility role 0
*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df apfProcessAssocReq (apf_80211.c:12998) Changing state for mobile c6:c7:a6:b5:2e:df on AP bc:26:c7:f1:14:80 from Associated to Associated

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df Mobility query, PEM State: DHCP_REQD

*apfMsConnTask_0: Jan 17 15:00:28.195: [PA] c6:c7:a6:b5:2e:df 0.0.0.0 DHCP_REQD (7) NO release MSCB
*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Building Mobile Announce :

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Building Client Payload:

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Client Ip: 0.0.0.0

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Client Vlan Ip: 40.221.224.34, Vlan mask : 255.255.224.0

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Client Vap Security: 8256

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Virtual Ip: 10.0.0.1

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df ssid: testnew

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Building Client profile name Payload:

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Profile Name: testnew

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Building VlanIpPayload.

*apfMsConnTask_0: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Mobile Announce sent to 1 members of the local group.
*spamApTask7: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Add SGT:0 to AP bc:26:c7:f1:14:80
*spamApTask7: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Add CTS mobile SGT - Encoded the capwap payload for the mobile with SGT 0
*spamApTask7: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df MS IP NULL during AddMobile, not sending IP Distribution
*spamApTask7: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Flex Ipv6 post auth acl is not present, not updating add mobile Payload
*spamApTask7: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Successful transmission of LWAPP Add-Mobile to AP bc:26:c7:f1:14:80 slotId 1 idx@44
*spamApTask7: Jan 17 15:00:28.196: [PA] c6:c7:a6:b5:2e:df Setting ADD_MOBILE (idx 45, action 0, last count 0) ack state for STA on AP bc:26:c7:f1:14:80
*apfOpenDtlSocket: Jan 17 15:00:28.226: [PA] c6:c7:a6:b5:2e:df Received management frame ACTION on BSSID bc:26:c7:f1:14:86 destination addr bc:26:c7:f1:14:86 slotid 1
*apfMsConnTask_0: Jan 17 15:00:28.226: [PA] c6:c7:a6:b5:2e:df Got action frame from the client (ActionCategory:5), payloadLen:12
*apfMsConnTask_0: Jan 17 15:00:28.226: [PA] c6:c7:a6:b5:2e:df Found RM action category code
*apfMsConnTask_0: Jan 17 15:00:28.226: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df sent 802.11K neighbor request to AP BC:26:C7:F1:14:80
*apfMsConnTask_0: Jan 17 15:00:28.226: [PA] c6:c7:a6:b5:2e:df Station: c6:c7:a6:b5:2e:df requested neighbors on non XOR roam capable AP BC:26:C7:F1:14:80 Slot 1
d*DHCP Socket Task: Jan 17 15:00:28.469: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0xb8f0badc)
*DHCP Socket Task: Jan 17 15:00:28.469: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 15:00:28.469: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
is*DHCP Socket Task: Jan 17 15:00:28.968: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0xb8f0badc)
*DHCP Socket Task: Jan 17 15:00:28.968: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 15:00:28.968: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'
able-all *DHCP Socket Task: Jan 17 15:00:29.474: [PA] c6:c7:a6:b5:2e:df DHCP received op BOOTREQUEST (1) (len 314,vlan 228, port 8, encap 0xec03, xid 0xb8f0badc)
*DHCP Socket Task: Jan 17 15:00:29.474: [PA] c6:c7:a6:b5:2e:df DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Jan 17 15:00:29.474: [PA] c6:c7:a6:b5:2e:df DHCP dropping packet due to ongoing mobility handshake exchange, (siaddr 0.0.0.0, mobility state = 'apfMsMmQueryRequested'

 

6 Replies 6

marce1000
VIP
VIP

 

  - Review these https://bst.cloudapps.cisco.com/bugsearch?pf=prdNm&kw=dropping%20packet%20due%20to%20ongoing%20mobility%20handshake&bt=custV&sb=anfr
       
       -  You are not mentioning the controller models involved but look into :
                  https://www.cisco.com/c/en/us/support/docs/wireless/wireless-lan-controller-software/200046-tac-recommended-aireos.html

                    - Have the debugging output which you posted  analyzed with : 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! '

networklearner1
Level 1
Level 1

Hello @marce1000 the foreign WLC is 3504 model running on 8.10.185.0 version and the Anchor is 5508 running on 8.5.161.7

The mobility profile gets created on the anchor. When I see the DHCP (Infoblox), it is offering the IP but then nothing happens.

 

 - For 5508 go for     https://software.cisco.com/download/specialrelease/2702eede2b47a5c3bb40795bbe836af6 , 
   (Added) 3504 ;->  https://software.cisco.com/download/home/286312601/type/280926587/release/8.10.190.0
                    For the rest take care of the items mention in my initial reply!

 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! '

marce1000
VIP
VIP

 

 - (Added2 reply) : Below is the output from the debug session which you posted when analyzed with Wireless Debug Analyzer
                             Show All flag was checked

   It would also be advisable to have a checkup of the configuration of both controllers according to :
            WirelessAnalyzer input (procedure) for AireOs controllers
                                            Have the output analyzed with : Wireless Config Analyzer
 


TimeTaskTranslated

Connection attempt #1
Connection attempt #2
Jan 17 14:59:42.883 *apfMsConnTask_0 Client made new Association to AP/BSSID BSSID bc:26:c7:f1:14:86 AP bgxly-7ar-lap-4
Jan 17 14:59:42.884 *apfMsConnTask_0 Client has entered DHCP Required state
Jan 17 14:59:42.884 *apfMsConnTask_0 Client has successfully cleared AP association phase
Jan 17 14:59:42.884 *apfMsConnTask_0 WLC/AP is sending an Association Response to the client with status code 0 = Successful association
Jan 17 14:59:43.072 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:43.571 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:44.099 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:44.598 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:45.598 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:45.991 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:46.491 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:46.492 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:47.492 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:48.764 *apfReceiveTask Client session has timed out
Jan 17 14:59:48.764 *apfReceiveTask Client session has timed out
Connection attempt #3
Jan 17 14:59:50.637 *apfMsConnTask_0 Client roamed to AP/BSSID BSSID bc:26:c7:f1:14:89 AP bgxly-7ar-lap-4
Jan 17 14:59:50.638 *apfMsConnTask_0 Client has entered DHCP Required state
Jan 17 14:59:50.638 *apfMsConnTask_0 Client has successfully cleared AP association phase
Jan 17 14:59:50.638 *apfMsConnTask_0 WLC/AP is sending an Association Response to the client with status code 0 = Successful association
Jan 17 14:59:56.499 *apfReceiveTask Client session has timed out
Jan 17 14:59:56.499 *apfReceiveTask Client session has timed out
Connection attempt #4
Jan 17 14:59:57.984 *apfMsConnTask_0 Client roamed to AP/BSSID BSSID bc:26:c7:f1:14:86 AP bgxly-7ar-lap-4
Jan 17 14:59:57.985 *apfMsConnTask_0 Client has entered DHCP Required state
Jan 17 14:59:57.985 *apfMsConnTask_0 Client has successfully cleared AP association phase
Jan 17 14:59:57.985 *apfMsConnTask_0 WLC/AP is sending an Association Response to the client with status code 0 = Successful association
Jan 17 14:59:58.056 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:58.556 *DHCP Socket Task Received DHCP request from client
Jan 17 14:59:59.557 *DHCP Socket Task Received DHCP request from client
Jan 17 15:00:03.839 *apfReceiveTask Client session has timed out
Jan 17 15:00:03.839 *apfReceiveTask Client session has timed out
Connection attempt #5
Jan 17 15:00:28.194 *apfMsConnTask_0 Client made new Association to AP/BSSID BSSID bc:26:c7:f1:14:86 AP bgxly-7ar-lap-4
Jan 17 15:00:28.195 *apfMsConnTask_0 Client has entered DHCP Required state
Jan 17 15:00:28.195 *apfMsConnTask_0 Client has successfully cleared AP association phase
Jan 17 15:00:28.195 *apfMsConnTask_0 WLC/AP is sending an Association Response to the client with status code 0 = Successful association
Jan 17 15:00:28.469 *DHCP Socket Task Received DHCP request from client
Jan 17 15:00:28.968 *DHCP Socket Task Received DHCP request from client
Jan 17 15:00:29.474 *DHCP Socket Task Received DHCP request from client


-- 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! '

networklearner1
Level 1
Level 1

Thank you, will check the configs in both WLCs.

 

                                                       >...Thank you, will check the configs in both WLCs.
  - Good work , upgrading to latest advisory release(s) becomes important too because the aireos based platforms are getting outdated , it becomes more important to run the latest/last release that they can run , because TAC support is diminishing (e.g.) , 

 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! '
Review Cisco Networking for a $25 gift card