01-17-2024 01:53 AM
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'
01-17-2024 03:41 AM
- 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.
01-17-2024 03:46 AM
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.
01-17-2024 03:51 AM - edited 01-17-2024 03:53 AM
- 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.
01-17-2024 04:01 AM
- (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 |
01-17-2024 04:16 AM
Thank you, will check the configs in both WLCs.
01-17-2024 04:31 AM
>...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.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide