Cisco Support Community
cancel
Showing results for 
Search instead for 
Did you mean: 
Announcements

Welcome to Cisco Support Community. We would love to have your feedback.

For an introduction to the new site, click here. If you'd prefer to explore, try our test area to get started. And see here for current known issues.

New Member

Sony xperia mobile is getting associated and dissociated to cisco ap regularly

Hello Tech,

 

As we have cisco wlc 5508 controller in that we have 150 ap adopted all mobile is working properly but some sony xperia c mobile  are   

able to connect but in moment it get disconnected this is happening regularly  security setting in cisco wlc is wpa2 aes only

 

 

mobile mac address :1c:7b:21:59:4d:54

 


(MPTP-WIFI-CTRL) > debug client 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Skipping EAP-Success to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 EAPOL Header:
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 00000000: 02 03 00 5f                                       ..._
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Found an cache entry for BSSID 50:67:ae:b4:11:21 in PMKID cache at index 0 of station 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Found an cache entry for BSSID 50:67:ae:b4:11:21 in PMKID cache at index 0 of station 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: Including PMKID in M1  (16)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374:      [0000] 3f ed 80 70 8d a8 b9 cf 54 60 8c 7b 42 1c 57 cf

*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Starting key exchange to mobile 1c:7b:21:59:4d:54, data packets will be dropped
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 Reusing allocated memory for  EAP Pkt for retransmission to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54 mscb->apfMsLwappLradNhMac = 50:67:ae:b5:e4:60 mscb->apfMsLradSlotId = 0 mscb->apfMsLradJumbo = 0 mscb->apfMsintIfNum = 1
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54  mscb->apfMsBssid = 50:67:ae:b4:11:20 mscb->apfMsAddress = 1c:7b:21:59:4d:54 mscb->apfMsApVapId = 2
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.374: 1c:7b:21:59:4d:54  dot1xcb->snapOrg = 00 00 00 dot1xcb->eapolWepBit = 0 mscb->apfMsLwappLradVlanId = 12 mscb->apfMsLwappMwarInet.ipv4.addr = -1062730747
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.375: 1c:7b:21:59:4d:54  mscb->apfMsLwappMwarPort = 5246 mscb->apfMsLwappLradInet.ipv4.addr = -1062730734 mscb->apfMsLwappLradPort = 24136
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.432: 1c:7b:21:59:4d:54 Received EAPOL-Key from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.432: 1c:7b:21:59:4d:54 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.432: 1c:7b:21:59:4d:54 Received EAPOL-key in PTK_START state (message 2) from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.432: 1c:7b:21:59:4d:54 Stopping retransmission timer for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.432: 1c:7b:21:59:4d:54 EAPOL Header:
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 00000000: 02 03 00 5f                                       ..._
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54 Reusing allocated memory for  EAP Pkt for retransmission to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54 mscb->apfMsLwappLradNhMac = 50:67:ae:b5:e4:60 mscb->apfMsLradSlotId = 0 mscb->apfMsLradJumbo = 0 mscb->apfMsintIfNum = 1
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54  mscb->apfMsBssid = 50:67:ae:b4:11:20 mscb->apfMsAddress = 1c:7b:21:59:4d:54 mscb->apfMsApVapId = 2
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54  dot1xcb->snapOrg = 00 00 00 dot1xcb->eapolWepBit = 0 mscb->apfMsLwappLradVlanId = 12 mscb->apfMsLwappMwarInet.ipv4.addr = -1062730747
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.433: 1c:7b:21:59:4d:54  mscb->apfMsLwappMwarPort = 5246 mscb->apfMsLwappLradInet.ipv4.addr = -1062730734 mscb->apfMsLwappLradPort = 24136
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.437: 1c:7b:21:59:4d:54 Received EAPOL-Key from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.437: 1c:7b:21:59:4d:54 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.437: 1c:7b:21:59:4d:54 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.437: 1c:7b:21:59:4d:54 Stopping retransmission timer for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.437: 1c:7b:21:59:4d:54 Freeing EAP Retransmit Bufer for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 Central switch is TRUE
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 50:67:ae:b4:11:20 vapId 2 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 6178, Adding TMP rule
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule
  type = Airespace AP - Learn IP address
  on AP 50:67:ae:b4:11:20, slot 0, interface = 1, QOS = 0
  IPv4 ACL ID = 255, IPv
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206  Local Bridging Vlan = 137, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255)
*apfReceiveTask: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED
*apfReceiveTask: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 5773, Adding TMP rule
*apfReceiveTask: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule
  type = Airespace AP - Learn IP address
  on AP 50:67:ae:b4:11:20, slot 0, interface = 1, QOS = 0
  IPv4 ACL ID = 255,
*apfReceiveTask: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206  Local Bridging Vlan = 137, Local Bridging intf id = 14
*apfReceiveTask: Nov 19 15:31:21.438: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255)
*pemReceiveTask: Nov 19 15:31:21.439: 1c:7b:21:59:4d:54 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Nov 19 15:31:21.439: 1c:7b:21:59:4d:54 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Nov 19 15:31:21.439: 1c:7b:21:59:4d:54 Sent an XID frame
*dtlArpTask: Nov 19 15:31:22.063: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 84) in 1 seconds
*dtlArpTask: Nov 19 15:31:22.063: 1c:7b:21:59:4d:54 In apfRegisterIpAddrOnMscb_debug ---  Duplicate static IP detected for the client. IP is used by the client - 1c:7b:21:59:4d:54
*dtlArpTask: Nov 19 15:31:22.063: 1c:7b:21:59:4d:54 IPv4 Addr: 192:168:144:5

*apfOrphanSocketTask: Nov 19 15:31:22.066: 1c:7b:21:59:4d:54 Orphan Packet from DS - IP 192.168.144.5
*apfOrphanSocketTask: Nov 19 15:31:22.066: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 84) in 1 seconds
*apfOrphanSocketTask: Nov 19 15:31:22.066: 1c:7b:21:59:4d:54 In apfRegisterIpAddrOnMscb_debug ---  Duplicate static IP detected for the client. IP is used by the client - 1c:7b:21:59:4d:54
*apfOrphanSocketTask: Nov 19 15:31:22.066: 1c:7b:21:59:4d:54 IPv4 Addr: 192:168:144:5

*pemReceiveTask: Nov 19 15:31:22.066: 1c:7b:21:59:4d:54 0.0.0.0 Removed NPU entry.
*osapiBsnTimer: Nov 19 15:31:22.932: 1c:7b:21:59:4d:54 apfMsExpireCallback (apf_ms.c:626) Expiring Mobile!
*apfReceiveTask: Nov 19 15:31:22.933: 1c:7b:21:59:4d:54 apfMsExpireMobileStation (apf_ms.c:6655) Changing state for mobile 1c:7b:21:59:4d:54 on AP 50:67:ae:b4:11:20 from Associated to Disassociated

*apfReceiveTask: Nov 19 15:31:22.933: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 45) in 10 seconds

(MPTP-WIFI-CTRL) >
(MPTP-WIFI-CTRL) >
(MPTP-WIFI-CTRL) >
(MPTP-WIFI-CTRL) >*osapiBsnTimer: Nov 19 15:31:32.932: 1c:7b:21:59:4d:54 apfMsExpireCallback (apf_ms.c:626) Expiring Mobile!
*apfReceiveTask: Nov 19 15:31:32.932: 1c:7b:21:59:4d:54 Sent Deauthenticate to mobile on BSSID 50:67:ae:b4:11:20 slot 0(caller apf_ms.c:6749)
*apfReceiveTask: Nov 19 15:31:32.932: 1c:7b:21:59:4d:54 Found an cache entry for BSSID 50:67:ae:b4:11:21 in PMKID cache at index 0 of station 1c:7b:21:59:4d:54
*apfReceiveTask: Nov 19 15:31:32.932: 1c:7b:21:59:4d:54 Removing BSSID 50:67:ae:b4:11:21 from PMKID cache of station 1c:7b:21:59:4d:54
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 Resetting MSCB PMK Cache Entry 0 for station 1c:7b:21:59:4d:54
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 Setting active key cache index 0 ---> 8
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 Deleting the PMK cache when de-authenticating the client.
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 Global PMK Cache deletion failed.
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 apfMsAssoStateDec
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 apfMsExpireMobileStation (apf_ms.c:6787) Changing state for mobile 1c:7b:21:59:4d:54 on AP 50:67:ae:b4:11:20 from Disassociated to Idle

*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Deleted mobile LWAPP rule on AP [50:67:ae:b4:11:20]
*apfReceiveTask: Nov 19 15:31:32.933: 1c:7b:21:59:4d:54 Deleting mobile on AP 50:67:ae:b4:11:20(0)
*apfMsConnTask_4: Nov 19 15:31:36.272: 1c:7b:21:59:4d:54 Adding mobile on LWAPP AP 50:67:ae:b4:11:20(1)
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Association received from mobile on BSSID 50:67:ae:b4:11:2e
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Rf profile 200 Clients are allowed to AP radio

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Max Client Trap Threshold: 50  cur: 0

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Rf profile 600 Clients are allowed to AP wlan

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 override for default ap group, marking intgrp NULL
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Re-applying interface policy for client

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2219)
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2240)
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 In processSsidIE:4796 setting Central switched to TRUE
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 In processSsidIE:4799 apVapId = 2 and Split Acl Id = 65535
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Applying site-specific Local Bridging override for station 1c:7b:21:59:4d:54 - vapId 2, site 'Testing', interface 'psk'
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Applying Local Bridging Interface Policy for station 1c:7b:21:59:4d:54 - vlan 137, interface id 14, interface 'psk'
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 override from ap group, removing intf group from mscb
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Applying site-specific override for station 1c:7b:21:59:4d:54 - vapId 2, site 'Testing', interface 'psk'
*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 137

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 Re-applying interface policy for client

*apfMsConnTask_4: Nov 19 15:31:36.273: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2219)
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2240)
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 processSsidIE  statusCode is 0 and status is 0
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 processSsidIE  ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 STA - rates (8): 140 18 152 36 176 72 96 108 0 0 0 0 0 0 0 0
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 suppRates  statusCode is 0 and gotSuppRatesElement is 1
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 Processing RSN IE type 48, length 20 for mobile 1c:7b:21:59:4d:54
*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 apfProcessAssocReq (apf_80211.c:8161) Changing state for mobile 1c:7b:21:59:4d:54 on AP 50:67:ae:b4:11:20 from Idle to AAA Pending

*apfMsConnTask_4: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 20) in 10 seconds
*apfReceiveTask: Nov 19 15:31:36.274: 1c:7b:21:59:4d:54 Received SGT for this Client.
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Resetting web IPv4 acl from 255 to 255

*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Resetting web IPv4 Flex acl from 65535 to 65535

*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Initializing policy
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Encryption policy is set to 0x80000001
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Central switch is TRUE
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Not Using WMM Compliance code qosCap 00
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 50:67:ae:b4:11:20 vapId 2 apVapId 2 flex-acl-name:
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 apfMsAssoStateInc
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 apfPemAddUser2 (apf_policy.c:333) Changing state for mobile 1c:7b:21:59:4d:54 on AP 50:67:ae:b4:11:20 from AAA Pending to Associated

*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 apfPemAddUser2:session timeout forstation 1c:7b:21:59:4d:54 - Session Tout 1800, apfMsTimeOut '1800' and sessionTimerRunning flag is  0
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 49) in 1800 seconds
*apfReceiveTask: Nov 19 15:31:36.275: 1c:7b:21:59:4d:54 Func: apfPemAddUser2, Ms Timeout = 1800, Session Timeout = 1800

*apfReceiveTask: Nov 19 15:31:36.276: 1c:7b:21:59:4d:54 Sending Assoc Response to station on BSSID 50:67:ae:b4:11:2e (status 0) ApVapId 2 Slot 1
*apfReceiveTask: Nov 19 15:31:36.276: 1c:7b:21:59:4d:54 apfProcessRadiusAssocResp (apf_80211.c:3213) Changing state for mobile 1c:7b:21:59:4d:54 on AP 50:67:ae:b4:11:20 from Associated to Associated

*spamApTask2: Nov 19 15:31:36.282: 1c:7b:21:59:4d:54 Sent 1x initiate message to multi thread task for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Creating a PKC PMKID Cache entry for station 1c:7b:21:59:4d:54 (RSN 2)
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Resetting MSCB PMK Cache Entry 0 for station 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Setting active key cache index 8 ---> 0
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Adding BSSID 50:67:ae:b4:11:2e to PMKID cache at index 0 for station 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: New PMKID: (16)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283:      [0000] b8 3e 64 ca c5 f9 e9 23 4c 40 92 fb f4 57 6e 2a

*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Initiating RSN PSK to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 EAP-PARAM Debug - eap-params for Wlan-Id :2 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 dot1x - moving mobile 1c:7b:21:59:4d:54 into Force Auth state
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Skipping EAP-Success to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 EAPOL Header:
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 00000000: 02 03 00 5f                                       ..._
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Found an cache entry for BSSID 50:67:ae:b4:11:2e in PMKID cache at index 0 of station 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Found an cache entry for BSSID 50:67:ae:b4:11:2e in PMKID cache at index 0 of station 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: Including PMKID in M1  (16)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283:      [0000] b8 3e 64 ca c5 f9 e9 23 4c 40 92 fb f4 57 6e 2a

*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Starting key exchange to mobile 1c:7b:21:59:4d:54, data packets will be dropped
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.283: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.284: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.284: 1c:7b:21:59:4d:54 Allocating EAP Pkt for retransmission to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.284: 1c:7b:21:59:4d:54 mscb->apfMsLwappLradNhMac = 50:67:ae:b5:e4:60 mscb->apfMsLradSlotId = 1 mscb->apfMsLradJumbo = 0 mscb->apfMsintIfNum = 1
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.284: 1c:7b:21:59:4d:54  mscb->apfMsBssid = 50:67:ae:b4:11:20 mscb->apfMsAddress = 1c:7b:21:59:4d:54 mscb->apfMsApVapId = 2
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.284: 1c:7b:21:59:4d:54  dot1xcb->snapOrg = 00 00 00 dot1xcb->eapolWepBit = 0 mscb->apfMsLwappLradVlanId = 12 mscb->apfMsLwappMwarInet.ipv4.addr = -1062730747
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.284: 1c:7b:21:59:4d:54  mscb->apfMsLwappMwarPort = 5246 mscb->apfMsLwappLradInet.ipv4.addr = -1062730734 mscb->apfMsLwappLradPort = 24136
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Received EAPOL-Key from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Received EAPOL-key in PTK_START state (message 2) from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Stopping retransmission timer for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 EAPOL Header:
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 00000000: 02 03 00 5f                                       ..._
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Sending EAPOL-Key Message to mobile 1c:7b:21:59:4d:54
                                                                                                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 Reusing allocated memory for  EAP Pkt for retransmission to mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54 mscb->apfMsLwappLradNhMac = 50:67:ae:b5:e4:60 mscb->apfMsLradSlotId = 1 mscb->apfMsLradJumbo = 0 mscb->apfMsintIfNum = 1
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54  mscb->apfMsBssid = 50:67:ae:b4:11:20 mscb->apfMsAddress = 1c:7b:21:59:4d:54 mscb->apfMsApVapId = 2
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54  dot1xcb->snapOrg = 00 00 00 dot1xcb->eapolWepBit = 0 mscb->apfMsLwappLradVlanId = 12 mscb->apfMsLwappMwarInet.ipv4.addr = -1062730747
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.317: 1c:7b:21:59:4d:54  mscb->apfMsLwappMwarPort = 5246 mscb->apfMsLwappLradInet.ipv4.addr = -1062730734 mscb->apfMsLwappLradPort = 24136
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Received EAPOL-Key from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Stopping retransmission timer for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Freeing EAP Retransmit Bufer for mobile 1c:7b:21:59:4d:54
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 apfMs1xStateInc
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Central switch is TRUE
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 Not Using WMM Compliance code qosCap 00
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 50:67:ae:b4:11:20 vapId 2 apVapId 2 flex-acl-name:
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 6178, Adding TMP rule
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Adding Fast Path rule
  type = Airespace AP - Learn IP address
  on AP 50:67:ae:b4:11:20, slot 1, interface = 1, QOS = 0
  IPv4 ACL ID = 255, IPv
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206  Local Bridging Vlan = 137, Local Bridging intf id = 14
*Dot1x_NW_MsgTask_4: Nov 19 15:31:36.319: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255)
*apfReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED
*apfReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 5773, Adding TMP rule
*apfReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule
  type = Airespace AP - Learn IP address
  on AP 50:67:ae:b4:11:20, slot 1, interface = 1, QOS = 0
  IPv4 ACL ID = 255,
*apfReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206  Local Bridging Vlan = 137, Local Bridging intf id = 14
*apfReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255, L2 ACL ID 255)
*pemReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0
*pemReceiveTask: Nov 19 15:31:36.320: 1c:7b:21:59:4d:54 Sent an XID frame
*dtlArpTask: Nov 19 15:31:36.488: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 84) in 1 seconds
*dtlArpTask: Nov 19 15:31:36.488: 1c:7b:21:59:4d:54 In apfRegisterIpAddrOnMscb_debug ---  Duplicate static IP detected for the client. IP is used by the client - 1c:7b:21:59:4d:54
*dtlArpTask: Nov 19 15:31:36.488: 1c:7b:21:59:4d:54 IPv4 Addr: 192:168:144:5

*apfOrphanSocketTask: Nov 19 15:31:36.490: 1c:7b:21:59:4d:54 Orphan Packet from DS - IP 192.168.144.5
*apfOrphanSocketTask: Nov 19 15:31:36.491: 1c:7b:21:59:4d:54 Scheduling deletion of Mobile Station:  (callerId: 84) in 1 seconds
*apfOrphanSocketTask: Nov 19 15:31:36.491: 1c:7b:21:59:4d:54 In apfRegisterIpAddrOnMscb_debug ---  Duplicate static IP detected for the client. IP is used by the client - 1c:7b:21:59:4d:54
*apfOrphanSocketTask: Nov 19 15:31:36.491: 1c:7b:21:59:4d:54 IPv4 Addr: 192:168:144:5

*pemReceiveTask: Nov 19 15:31:36.491: 1c:7b:21:59:4d:54 0.0.0.0 Removed NPU entry.
*osapiBsnTimer: Nov 19 15:31:37.333: 1c:7b:21:59:4d:54 apfMsExpireCallback (apf_ms.c:626) Expiring Mobile!
*apfReceiveTask: Nov 19 15:31:37.333: 1c:7b:21:59:4d:54 apfMsExpireMobileStation (apf_ms.c:6655) Changing state for mobile 1c:7b:21:59:4d:54 on AP 50:67:ae:b4:11:20 from Associated to Disassociated

1 REPLY
Hall of Fame Super Gold

After the client associates

After the client associates/authenticates, post the output to the command "sh client detail 1c:7b:21:59:4d:54".

113
Views
0
Helpful
1
Replies