ā11-16-2015 10:44 PM - edited ā07-05-2021 04:14 AM
Hi,
we have setup with 2 WLC and client are IPad are getting connected to it.
but we are facing a strange problem some client we are unable to ping some times.
i have attached debug from WLC also the client status from WLC, please anyone can let me what is the issue.
Regards
DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP mscbVapLocalAddr=10.100.152.20 mscbVapLocalNetMask= 255.255.248.0 mscbdhcpRelay=10.100.152.20
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selected relay 1 - 10.100.100.10 (local address 10.100.152.20, gateway 10.100.152.1, VLAN 949, port 4)
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 2 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selected relay 2 - NONE
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 1 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP mscbVapLocalAddr=10.100.152.20 mscbVapLocalNetMask= 255.255.248.0 mscbdhcpRelay=10.100.152.20
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selected relay 1 - 10.100.100.10 (local address 10.100.152.20, gateway 10.100.152.1, VLAN 949, port 4)
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP transmitting DHCP DISCOVER (1)
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP xid: 0xc53b9d58 (3309018456), secs: 2048, flags: 0
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP chaddr: 54:e4:bd:74:00:43
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP siaddr: 0.0.0.0, giaddr: 10.100.152.20
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP sending REQUEST to 10.100.152.1 (len 350, port 4, vlan 949)
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 2 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:20.409: [PA] 54:e4:bd:74:00:43 DHCP selected relay 2 - NONE
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP received op BOOTREPLY (2) (len 308,vlan 949, port 4, encap 0xec00)
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP sending REPLY to STA (len 418, port 4, vlan 948)
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP transmitting DHCP OFFER (2)
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP xid: 0xc53b9d58 (3309018456), secs: 2048, flags: 0
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP chaddr: 54:e4:bd:74:00:43
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP ciaddr: 0.0.0.0, yiaddr: 10.100.159.201
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0
*DHCP Socket Task: Nov 17 10:07:20.410: [PA] 54:e4:bd:74:00:43 DHCP server id: 2.2.2.2 rcvd server id: 10.100.100.10
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP received op BOOTREQUEST (1) (len 308,vlan 948, port 4, encap 0xec03)
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP (encap type 0xec03) mstype 0ff:ff:ff:ff:ff:ff
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 1 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP mscbVapLocalAddr=10.100.152.20 mscbVapLocalNetMask= 255.255.248.0 mscbdhcpRelay=10.100.152.20
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selected relay 1 - 10.100.100.10 (local address 10.100.152.20, gateway 10.100.152.1, VLAN 949, port 4)
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 2 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selected relay 2 - NONE
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 1 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP mscbVapLocalAddr=10.100.152.20 mscbVapLocalNetMask= 255.255.248.0 mscbdhcpRelay=10.100.152.20
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selected relay 1 - 10.100.100.10 (local address 10.100.152.20, gateway 10.100.152.1, VLAN 949, port 4)
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP transmitting DHCP DISCOVER (1)
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP xid: 0xc53b9d58 (3309018456), secs: 4352, flags: 0
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP chaddr: 54:e4:bd:74:00:43
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP ciaddr: 0.0.0.0, yiaddr: 0.0.0.0
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP siaddr: 0.0.0.0, giaddr: 10.100.152.20
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP sending REQUEST to 10.100.152.1 (len 350, port 4, vlan 949)
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selecting relay 2 - control block settings:
dhcpServer: 10.100.100.10, dhcpNetmask: 0.0.0.0,
dhcpGateway: 0.0.0.0, dhcpRelay: 10.100.152.20 VLAN: 949
*DHCP Socket Task: Nov 17 10:07:29.335: [PA] 54:e4:bd:74:00:43 DHCP selected relay 2 - NONE
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP received op BOOTREPLY (2) (len 308,vlan 949, port 4, encap 0xec00)
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP sending REPLY to STA (len 418, port 4, vlan 948)
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP transmitting DHCP OFFER (2)
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP op: BOOTREPLY, htype: Ethernet, hlen: 6, hops: 0
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP xid: 0xc53b9d58 (3309018456), secs: 4352, flags: 0
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP chaddr: 54:e4:bd:74:00:43
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP ciaddr: 0.0.0.0, yiaddr: 10.100.159.201
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP siaddr: 0.0.0.0, giaddr: 0.0.0.0
*DHCP Socket Task: Nov 17 10:07:29.337: [PA] 54:e4:bd:74:00:43 DHCP server id: 2.2.2.2 rcvd server id: 10.100.100.10
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Processing assoc-req station:54:e4:bd:74:00:43 AP:f0:7f:06:3a:a2:40-00 thread:9d78200
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Association received from mobile on BSSID f0:7f:06:3a:a2:45 AP WAP-RT-ASATEER-04
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Global 200 Clients are allowed to AP radio
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Max Client Trap Threshold: 0 cur: 35
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Rf profile 600 Clients are allowed to AP wlan
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 override for default ap group, marking intgrp NULL
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Applying Interface policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 949
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Re-applying interface policy for client
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2399)
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2420)
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 In processSsidIE:5682 setting Central switched to TRUE
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 In processSsidIE:5685 apVapId = 6 and Split Acl Id = 65535
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Setting the NAS Id to WLAN specific Id 'WLC-DC-TENG-01-A'
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Applying site-specific Local Bridging override for station 54:e4:bd:74:00:43 - vapId 6, site 'default-group', interface 'int949'
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Applying Local Bridging Interface Policy for station 54:e4:bd:74:00:43 - vlan 949, interface id 10, interface 'int949'
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 STA - rates (7): 150 24 36 48 72 96 108 0 0 0 0 0 0 0 0 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] RSNIE in Assoc. Req.: (20)
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] [0016] ac 02 00 00
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Processing RSN IE type 48, length 20 for mobile 54:e4:bd:74:00:43
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 RSN Capabilities: 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfValidateDot11iCapabilities:1286 Received RSNIE with Capabilities with STA MFPC: 0, STA MFPR:0, & AP MFPC:0MFPR:0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Marking Mobile as non-11w Capable
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfValidateDot11wGroupMgmtCipher:1716, Received NULL 11w Group Mgmt Cipher Suite for STA, hence returning
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfMsRunStateDec
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfMs1xStateDec
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 RUN (20) Change state to START (0) last state RUN (20)
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 START (0) Initializing policy
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 START (0) Change state to AUTHCHECK (2) last state START (0)
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)
*pemReceiveTask: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 Removed NPU entry.
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Sending 11w Flag 0 for Client 54:E4:BD:74:00:43
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 10.100.159.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f0:7f:06:3a:a2:40 vapId 6 apVapId 6 flex-acl-name:
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfPemAddUser2 (apf_policy.c:352) Changing state for mobile 54:e4:bd:74:00:43 on AP f0:7f:06:3a:a2:40 from Associated to Associated
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfPemAddUser2:session timeout forstation 54:e4:bd:74:00:43 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Sending assoc-resp with status 0 station:54:e4:bd:74:00:43 AP:f0:7f:06:3a:a2:40-00 on apVapId 6
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 Sending Assoc Response to station on BSSID f0:7f:06:3a:a2:45 (status 0) ApVapId 6 Slot 0
*apfMsConnTask_1: Nov 17 10:07:44.063: [PA] 54:e4:bd:74:00:43 apfProcessAssocReq (apf_80211.c:9463) Changing state for mobile 54:e4:bd:74:00:43 on AP f0:7f:06:3a:a2:40 from Associated to Associated
*spamApTask5: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Sent 1x initiate message to multi thread task for mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 reauth_sm state transition 1 ---> 0 for mobile 54:e4:bd:74:00:43 at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Creating a PKC PMKID Cache entry for station 54:e4:bd:74:00:43 (RSN 2)
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Resetting MSCB PMK Cache Entry 0 for station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Removing BSSID f0:7f:06:3a:a2:45 from PMKID cache of station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Setting active key cache index 0 ---> 8
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Adding BSSID f0:7f:06:3a:a2:45 to PMKID cache at index 0 for station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] New PMKID: (16)
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] [0000] 54 20 52 f4 d8 5c 83 5b 99 ce f9 73 4d ef 6a f9
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Initiating RSN PSK to mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 EAP-PARAM Debug - eap-params for Wlan-Id :6 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 dot1x - moving mobile 54:e4:bd:74:00:43 into Force Auth state
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Skipping EAP-Success to mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Found an cache entry for BSSID f0:7f:06:3a:a2:45 in PMKID cache at index 0 of station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Found an cache entry for BSSID f0:7f:06:3a:a2:45 in PMKID cache at index 0 of station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] Including PMKID in M1 (16)
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] [0000] 54 20 52 f4 d8 5c 83 5b 99 ce f9 73 4d ef 6a f9
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Starting key exchange to mobile 54:e4:bd:74:00:43, data packets will be dropped
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Sending EAPOL-Key Message to mobile 54:e4:bd:74:00:43
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.065: [PA] 54:e4:bd:74:00:43 Allocating EAP Pkt for retransmission to mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Received EAPOL-Key from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Received EAPOL-key in PTK_START state (message 2) from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Dumping RSNIE received in Association request:
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 00000000: 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 0...............
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 00000010: 00 0f ac 02 00 00 ......
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Dumping RSNIE received in EAPOL M2 :
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 00000000: 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ................
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 00000010: ac 02 00 00 ....
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Stopping retransmission timer for mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Sending EAPOL-Key Message to mobile 54:e4:bd:74:00:43
state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_3: Nov 17 10:07:44.117: [PA] 54:e4:bd:74:00:43 Reusing allocated memory for EAP Pkt for retransmission to mobile 54:e4:bd:74:00:43
*osapiBsnTimer: Nov 17 10:07:45.198: [PA] 54:e4:bd:74:00:43 802.1x 'timeoutEvt' Timer expired for station 54:e4:bd:74:00:43 and for message = M3
*dot1xMsgTask: Nov 17 10:07:45.198: [PA] 54:e4:bd:74:00:43 Retransmit 1 of EAPOL-Key M3 (length 155) for mobile 54:e4:bd:74:00:43
*osapiBsnTimer: Nov 17 10:07:46.219: [PA] 54:e4:bd:74:00:43 802.1x 'timeoutEvt' Timer expired for station 54:e4:bd:74:00:43 and for message = M3
*dot1xMsgTask: Nov 17 10:07:46.219: [PA] 54:e4:bd:74:00:43 Retransmit 2 of EAPOL-Key M3 (length 155) for mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:46.498: [PA] 54:e4:bd:74:00:43 Received EAPOL-Key from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:46.498: [PA] 54:e4:bd:74:00:43 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:46.498: [PA] 54:e4:bd:74:00:43 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:46.498: [PA] 54:e4:bd:74:00:43 EAPOL Key message with invalid authenticator replay counter
got 00 00 00 00 00 00 00 01
expected 00 00 00 00 00 00 00 03
from mobile 54:e4:bd:74:00:43
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy AP LOCP - mode:0 slotId:128, apMac 0xf0:7f:6:3a:a2:40
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy WLAN LOCP EssIndex:6 aid:7 ssid:ATP-In-Room
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy Security LOCP ecypher:0x0 ptype:0x2, p:0x1, eaptype:0x6 w:0x1 aalg:0x0, PMState: 8021X_REQD
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy 802.11 LOCP a:0x0 b:0x0 c:0x0 d:0x0 e:0x1 protocol1:0x0 protocol2:0x6 statuscode 0, reasoncode 1, status 3
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy IP LOCP: 0xa649fc9
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy MobilityData LOCP status:1, anchorip:0x0
*apfLbsTask: Nov 17 10:07:46.831: [PA] 54:e4:bd:74:00:43 Copy IPv6 LOCP: fe80::56e4:bdff:fe74:43
*osapiBsnTimer: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 802.1x 'timeoutEvt' Timer expired for station 54:e4:bd:74:00:43 and for message = M3
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Retransmit failure for EAPOL-Key M3 to mobile 54:e4:bd:74:00:43, retransmit count 3, mscb deauth count 0
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Resetting MSCB PMK Cache Entry 0 for station 54:e4:bd:74:00:43
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Removing BSSID f0:7f:06:3a:a2:45 from PMKID cache of station 54:e4:bd:74:00:43
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Setting active key cache index 0 ---> 8
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Sent Deauthenticate to mobile on BSSID f0:7f:06:3a:a2:40 slot 0(caller 1x_ptsm.c:599)
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Setting active key cache index 8 ---> 8
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Deleting the PMK cache when de-authenticating the client.
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Global PMK Cache deletion failed.
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Scheduling deletion of Mobile Station: (callerId: 57) in 10 seconds
*dot1xMsgTask: Nov 17 10:07:47.239: [PA] 54:e4:bd:74:00:43 Freeing EAP Retransmit Bufer for mobile 54:e4:bd:74:00:43
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Processing assoc-req station:54:e4:bd:74:00:43 AP:f0:7f:06:3a:a2:40-00 thread:9d78200
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Association received from mobile on BSSID f0:7f:06:3a:a2:45 AP WAP-RT-ASATEER-04
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Global 200 Clients are allowed to AP radio
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Max Client Trap Threshold: 0 cur: 35
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Rf profile 600 Clients are allowed to AP wlan
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 override for default ap group, marking intgrp NULL
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Applying Interface policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 949
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Re-applying interface policy for client
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 10.100.159.201 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2399)
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 10.100.159.201 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2420)
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 In processSsidIE:5682 setting Central switched to TRUE
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 In processSsidIE:5685 apVapId = 6 and Split Acl Id = 65535
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Setting the NAS Id to WLAN specific Id 'WLC-DC-TENG-01-A'
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Applying site-specific Local Bridging override for station 54:e4:bd:74:00:43 - vapId 6, site 'default-group', interface 'int949'
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Applying Local Bridging Interface Policy for station 54:e4:bd:74:00:43 - vlan 949, interface id 10, interface 'int949'
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 processSsidIE statusCode is 0 and status is 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 processSsidIE ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 STA - rates (7): 150 24 36 48 72 96 108 0 0 0 0 0 0 0 0 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 suppRates statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] RSNIE in Assoc. Req.: (20)
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] [0016] ac 02 00 00
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Received 802.11i PSK key management suite, enabling Authentication
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 RSN Capabilities: 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 apfValidateDot11iCapabilities:1286 Received RSNIE with Capabilities with STA MFPC: 0, STA MFPR:0, & AP MFPC:0MFPR:0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Marking Mobile as non-11w Capable
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 apfValidateDot11wGroupMgmtCipher:1716, Received NULL 11w Group Mgmt Cipher Suite for STA, hence returning
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 10.100.159.201 START (0) Initializing policy
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 10.100.159.201 START (0) Change state to AUTHCHECK (2) last state START (0)
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 10.100.159.201 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Encryption policy is set to 0x80000001
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Not Using WMM Compliance code qosCap 00
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Sending 11w Flag 0 for Client 54:E4:BD:74:00:43
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 10.100.159.201 8021X_REQD (3) Plumbed mobile LWAPP rule on AP f0:7f:06:3a:a2:40 vapId 6 apVapId 6 flex-acl-name:
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 apfPemAddUser2 (apf_policy.c:352) Changing state for mobile 54:e4:bd:74:00:43 on AP f0:7f:06:3a:a2:40 from Associated to Associated
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 apfPemAddUser2:session timeout forstation 54:e4:bd:74:00:43 - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Stopping deletion of Mobile Station: (callerId: 48)
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Sending assoc-resp with status 0 station:54:e4:bd:74:00:43 AP:f0:7f:06:3a:a2:40-00 on apVapId 6
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 Sending Assoc Response to station on BSSID f0:7f:06:3a:a2:45 (status 0) ApVapId 6 Slot 0
*apfMsConnTask_1: Nov 17 10:07:49.703: [PA] 54:e4:bd:74:00:43 apfProcessAssocReq (apf_80211.c:9463) Changing state for mobile 54:e4:bd:74:00:43 on AP f0:7f:06:3a:a2:40 from Associated to Associated
*spamApTask5: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Sent 1x initiate message to multi thread task for mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 reauth_sm state transition 0 ---> 1 for mobile 54:e4:bd:74:00:43 at 1x_reauth_sm.c:47
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Creating a PKC PMKID Cache entry for station 54:e4:bd:74:00:43 (RSN 2)
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Resetting MSCB PMK Cache Entry 0 for station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Adding BSSID f0:7f:06:3a:a2:45 to PMKID cache at index 0 for station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] New PMKID: (16)
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] [0000] 54 20 52 f4 d8 5c 83 5b 99 ce f9 73 4d ef 6a f9
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Initiating RSN PSK to mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 EAP-PARAM Debug - eap-params for Wlan-Id :6 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 dot1x - moving mobile 54:e4:bd:74:00:43 into Force Auth state
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Skipping EAP-Success to mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Found an cache entry for BSSID f0:7f:06:3a:a2:45 in PMKID cache at index 0 of station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Found an cache entry for BSSID f0:7f:06:3a:a2:45 in PMKID cache at index 0 of station 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] Including PMKID in M1 (16)
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] [0000] 54 20 52 f4 d8 5c 83 5b 99 ce f9 73 4d ef 6a f9
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Starting key exchange to mobile 54:e4:bd:74:00:43, data packets will be dropped
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Sending EAPOL-Key Message to mobile 54:e4:bd:74:00:43
state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.705: [PA] 54:e4:bd:74:00:43 Allocating EAP Pkt for retransmission to mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.728: [PA] 54:e4:bd:74:00:43 Received EAPOL-Key from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.728: [PA] 54:e4:bd:74:00:43 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.728: [PA] 54:e4:bd:74:00:43 Received EAPOL-key in PTK_START state (message 2) from mobile 54:e4:bd:74:00:43
*Dot1x_NW_MsgTask_3: Nov 17 10:07:49.728: [PA] 54:e4:bd:74:00:43 Dumping RSNIE received in Association request:
ā11-17-2015 11:20 AM
What's the behavour client see, does his connection get dropped ?
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: