9800-40#more bootflash:ra_trace_MAC_ { client mac }_144130108_UTC_Wed_Nov_13_2019.log
2019/11/13 14:40:17.904 {wncd_x_R0-1}{1}: [radius] [20567]: (ERR): RADIUS/DECODE: No response from radius-server; parse response; FAIL
2019/11/13 14:40:17.904 {wncd_x_R0-1}{1}: [radius] [20567]: (ERR): RADIUS/DECODE: Case error(no response/ bad packet/ op decode);parse response; FAIL
2019/11/13 14:40:18.606 {wncd_x_R0-1}{1}: [radius] [20567]: (ERR): RADIUS/DECODE: No response from radius-server; parse response; FAIL
2019/11/13 14:40:18.606 {wncd_x_R0-1}{1}: [radius] [20567]: (ERR): RADIUS/DECODE: Case error(no response/ bad packet/ op decode);parse response; FAIL
2019/11/13 14:40:27.832 {mobilityd_R0-0}{1}: [mm-client] [22478]: (debug): MAC:  { client mac }  Received mobile_announce, sub type: 0 of XID (115422) from (ipv4: [5508-IP] )
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [mm-client] [20567]: (debug): MAC:  { client mac }  Received mobile_announce, sub type: 0 of XID (115422) from (MobilityD[0])
2019/11/13 14:40:27.833 {mobilityd_R0-0}{1}: [mm-transition] [22478]: (info): MAC:  { client mac }  MMFSM transition: S_MC_RUN -> S_MC_ANNOUNCE_PROCESSED_ROAMING_CLIENT_TR on E_MC_ANNOUNCE_RCVD from ipv4: [5508-IP]
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [mm-transition] [20567]: (info): MAC:  { client mac }  MMIF FSM transition: S_MA_LOCAL -> S_MA_ANNOUNCE_PROCESSED_TR on E_MA_ANNOUNCE_RCVD
2019/11/13 14:40:27.833 {mobilityd_R0-0}{1}: [mm-client] [22478]: (info): MAC:  { client mac }  Forwarding mobile_announce, sub type: 0 of XID (115422) from (ipv4: [5508-IP] ) to (WNCD[1])
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [mm-client] [20567]: (info): MAC:  { client mac }  Roam type changed - None -> L3 Forced
2019/11/13 14:40:27.833 {mobilityd_R0-0}{1}: [mm-client] [22478]: (debug): MAC:  { client mac }  Sending mobile_announce of XID (115422) to (WNCD[1])
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [mm-client] [20567]: (info): MAC:  { client mac }  Sub Roam type changed - MM_SUB_ROAM_TYPE_NONE -> MM_SUB_ROAM_TYPE_INTER_CONTROLLER
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [client-orch-sm] [20567]: (ERR): WLAN Profile Mismatch, Local:WP_[9800-SSID], Remote:[5508-SSID]
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [client-orch-sm] [20567]: (ERR): MAC:  { client mac }  Handoff Deny: Profile Mismatch
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [ewlc-infra-evq] [20567]: (ERR): < { client mac } >:mobile_announce_nak:MM_HANDOFF_FAILURE
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [mm-client] [20567]: (debug): MAC:  { client mac }  Cannot handoff client, sending handoff failure. Subtype: MM_HANDOFF_PROFILE
2019/11/13 14:40:27.833 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.834 {mobilityd_R0-0}{1}: [mm-client] [22478]: (debug): MAC:  { client mac }  Received handoff, sub type: 6 of XID (115422) from (WNCD[1])
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  vlan_id payload: 1 vlan ( 2109 )
2019/11/13 14:40:27.834 {mobilityd_R0-0}{1}: [mm-transition] [22478]: (info): MAC:  { client mac }  MMFSM transition: S_MC_WAIT_ANNOUNCE_RSP -> S_MC_HANDOFF_PROCESSED_TR on E_MC_HANDOFF_RCVD from WNCD[1]
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  policy payload: elapsed time: 50 seconds
2019/11/13 14:40:27.834 {mobilityd_R0-0}{1}: [mm-client] [22478]: (info): MAC:  { client mac }  Forwarding handoff, sub type: 6 of XID (115422) from (WNCD[1]) to (ipv4: [5508-IP] )
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  policy payload: session timeout: 18000 seconds
2019/11/13 14:40:27.834 {mobilityd_R0-0}{1}: [mm-client] [22478]: (debug): MAC:  { client mac }  Sending handoff of XID (115422) to (ipv4: [5508-IP] )
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  policy payload: terminate action not set
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  setting wlan session timeout: 18000 seconds
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  policy payload: qos level 5
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  policy payload: avg data 0, avg rt 0, burst data 0, burst rt 0
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  setting override bits 0x00000800
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  setting Interface name : [9800-client-vlan]
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [sanet-shim-miscellaneous] [20567]: (info): MAC:  { client mac }  setting User name: C0306985.hosting.corp and Length: 21
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [ewlc-qos-voice] [20567]: (info): MAC:  { client mac }  Encoding callinfo payload parameters for mobility handoff
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [ewlc-qos-voice] [20567]: (info): MAC:  { client mac }  VoIP call snoop not enabled on the Controller
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [mdns] [20567]: (debug): MDNS_CACHE: Cache record(mac_to_ptrid) not found
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [mdns] [20567]: (info): [MDNS_INTERROAM] HANDOFF encode for client : { client mac }
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [mm-client] [20567]: (debug): MAC:  { client mac }  Sending handoff of XID (115422) to (MobilityD[0])
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [ewlc-infra-evq] [20567]: (ERR):  { client mac } CLIENT_MOBILITY_CLEANUP Reason = MMIF_MM_MSG_DECODE_FAILURE WLAN profile = WP_[9800-SSID], Policy profile = PP_achnlape01v2114, AP name = APA453.0EDD.6258
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [ { client mac }:capwap_90400003] periodic acct timer timer is not running to stop
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [ { client mac }:capwap_90400003] Getting active filter list
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [client-orch-sm] [20567]: (info): MAC:  { client mac }  Deleting the client, reason: 23, CO_CLIENT_DELETE_REASON_MOBILITY_FAILURE, Client state S_CO_RUN
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [client-orch-sm] [20567]: (note): MAC:  { client mac }  Client delete initiated. Reason: CO_CLIENT_DELETE_REASON_MOBILITY_FAILURE, fsm-state transition 00|00|00|00|00|00|00|00|00|00|00|00|00|00|00|00|01|07|13|17|18|25|30|3f|40|42|43|48|55|57|67|80|
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [client-orch-state] [20567]: (note): MAC:  { client mac }  Client state transition: S_CO_RUN -> S_CO_DELETE_IN_PROGRESS
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [ewlc-qos-qosdb] [20567]: (info): Found Policy-map refcount record for pm_name: client-default
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [ewlc-qos-qosdb] [20567]: (info): Found Policy-map refcount record for pm_name: client-default
2019/11/13 14:40:27.834 {wncd_x_R0-1}{1}: [ewlc-qos-voice] [20567]: (info): MAC:  { client mac }  Successfully freed the bw for sip client
2019/11/13 14:40:27.835 {mobilityd_R0-0}{1}: [mm-client] [22478]: (debug): MAC:  { client mac }  Received handoff_end, sub type: 0 of XID (0) from (WNCD[1])
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [multicast-main] [20567]: (info): MAC:  { client mac }  No Flex/Fabric main record exists for client
2019/11/13 14:40:27.835 {mobilityd_R0-0}{1}: [mm-transition] [22478]: (info): MAC:  { client mac }  MMFSM transition: S_MC_RUN -> S_MC_HANDOFF_END_RCVD_TR on E_MC_HANDOFF_END_RCVD from WNCD[1]
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [rog-proxy-capwap] [20567]: (debug): Managed client state notification: client left run state:  { client mac }
2019/11/13 14:40:27.836 {fman_fp_F0-0}{1}: [wireless-client] [26849]:  UUID: 10000002419e6, ra: 5 (note):  WLCLIENT 0xa0000027 AOM delete succeeded
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [mdns] [20567]: (debug): MDNS_CACHE: Cache record(mac_to_ptrid) not found
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [client-iplearn] [20567]: (info): MAC:  { client mac }  IP-learn state transition: S_IPLEARN_COMPLETE -> S_IPLEARN_DELETE_IN_PROGRESS
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 2 cur hdl 0xfa000146 withclient name EPM
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [dpath_svc] [20567]: (note): MAC:  { client mac }  Client datapath entry deleted for ifid 0xa0000027
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [mm-transition] [20567]: (info): MAC:  { client mac }  MMIF FSM transition: S_MA_WAIT_DELETE -> S_MA_DELETE_PROCESSED_TR on E_MA_CO_DELETE_RCVD
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [mm-client] [20567]: (debug): MAC:  { client mac }  Sending handoff_end of XID (0) to (MobilityD[0])
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [ { client mac }:capwap_90400003] Disconnect request from SANET-SHIM (14) for  { client mac } / 0x2a000146 - term: reauth-failure, abort: Unknown, disc: (default)
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [aaa-attr-inf] [20567]: (info): [ Applied attribute :bsn-vlan-interface-name   0 "[9800-client-vlan]" ]
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [aaa-attr-inf] [20567]: (info): [ Applied attribute :             timeout   0 18000 (0x4650) ]
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [client-auth] [20567]: (info): MAC:  { client mac }  Client auth-interface state transition: S_AUTHIF_DOT1XAUTH_DONE -> S_SANET_DELETE_IN_PROGRESS
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [dot11] [20567]: (info): MAC:  { client mac }  Sent disassoc to client, disassoc reason: 252, CLIENT_DEAUTH_REASON_ADMIN_RESET delete reason: 23, CO_CLIENT_DELETE_REASON_MOBILITY_FAILURE.
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [dot11] [20567]: (info): MAC:  { client mac }  Sent deauth to client, deauth reason: 252, CLIENT_DEAUTH_REASON_ADMIN_RESET delete reason: 23, CO_CLIENT_DELETE_REASON_MOBILITY_FAILURE.
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [dot11] [20567]: (info): MAC:  { client mac }  DOT11 state transition: S_DOT11_ASSOCIATED -> S_DOT11_DELETED
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS: Send Accounting-Request to [ISE]:1813 id 0/52, len 335
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  authenticator { MAC } - { MAC }
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Framed-IP-Address   [8]      6  10.20.201.195
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Framed-IPv6-Address [168]   18  ...
2019/11/13 14:40:27.835 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  User-Name           [1]     23  "C0306985.hosting.corp"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:   Cisco AVpair       [1]     43  "audit-session-id=0B08C30A000001406587616A"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:   Cisco AVpair       [1]     14  "vlan-id=2109"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:   Cisco AVpair       [1]     14  "method=dot1x"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Called-Station-Id   [30]    19  "{ 9800-MAC }"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Calling-Station-Id  [31]    19  "{ client mac }"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  NAS-IP-Address      [4]      6  [9800-IP]
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  NAS-Port-Id         [87]    17  "capwap_90400003"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  NAS-Port-Type       [61]     6  802.11 wireless           [19]
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:   Airespace-WLAN-ID  [1]      6  21
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:   Cisco AVpair       [1]     27  "cisco-wlan-ssid=[9800-SSID]"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Nas-Identifier      [32]    15  "9800-40"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Acct-Session-Id     [44]    10  "00000ffa"
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Acct-Authentic      [45]     6  Remote                    [3]
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Acct-Terminate-Cause[49]     6  none                      [0]
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Acct-Status-Type    [40]     6  Stop                      [2]
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Event-Timestamp     [55]     6  1573656027
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Acct-Session-Time   [46]     6  49
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS:  Acct-Delay-Time     [41]     6  0
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [radius] [20567]: (info): RADIUS: Started 5 sec timeout
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [ { client mac }:capwap_90400003] periodic acct timer timer is not running to stop
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [caaa-acct] [20567]: (info): [CAAA:ACCT] Not a webauth call
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [ { client mac }:capwap_90400003] Stopped Accounting (0) timer for client  { client mac }
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 1 cur hdl 0x0 withclient name DTM
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 0 cur hdl 0xb4000146 withclient name BM
2019/11/13 14:40:27.836 {wncd_x_R0-1}{1}: [auth-mgr] [20567]: (info): [0000.0000.0000:unknown] Session info 0x5561d0326ed8 hdl 0x2a000146 client hdl 1 cur hdl 0x0 withclient name DTM