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

Issues with one particular client

All,

Can you please check through the following debug?  I believe this to be a client issue.

 

Thank You

 

ocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:48:56.995: 00:1e:b3:02:c7:36 Updating AID for REAP AP Client 00:18:74:c4:a2:10 - AID ===> 1

*dot1xMsgTask: Nov 05 08:48:56.996: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:48:56.996: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:48:56.996: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:48:56.996: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:48:56.996: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:48:56.996: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.090: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.090: 00:1e:b3:02:c7:36 Received EAPOL-key in PTK_START state (message 2) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.090: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.090: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 apfMs1xStateInc

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state L2AUTHCOMPLETE (4)

 

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) pemAdvanceState2 4817, Adding TMP rule

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.034: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c4:a2:10, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jum

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

 

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4833, Adding TMP rule

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c4:a2:10, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jumbo

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 Key exchange done, data packets from mobile 00:1e:b3:02:c7:36 should be forwarded shortly

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                                    state PTKINITDONE (message 5 - group), replay counter 00.00.00.00.00.00.00.02

*pemReceiveTask: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*pemReceiveTask: Nov 05 08:48:57.100: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*spamApTask5: Nov 05 08:48:57.105: 00:1e:b3:02:c7:36 Sent EAPOL-Key M5 for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.112: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.112: 00:1e:b3:02:c7:36 Received EAPOL-key in REKEYNEGOTIATING state (message 6) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:48:57.112: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c7:bf:00

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Complete to Mobility-Incomplete

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Reached FAILURE: from line 5154

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 9) in 10 seconds

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:18:74:c4:a2:10]

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 13, site 'AHC_Standard', interface 'management'

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_4: Nov 05 08:49:50.676: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 13, site 'AHC_Standard', interface 'management'

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:18:74:c4:a2:10]

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 Updated location for station old AP 00:18:74:c4:a2:10-0, new AP 00:18:74:c7:bf:00-0

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 apfMs1xStateDec

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) DHCP Not required on AP 00:18:74:c7:bf:00 vapId 13 apVapId 4for this client

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:18:74:c7:bf:00 vapId 13 apVapId 4

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c7:bf:00 from Associated to Associated

 

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 65535 seconds

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c7:bf:00 (status 0) ApVapId 4 Slot 0

*apfMsConnTask_4: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c7:bf:00 from Associated to Associated

 

*pemReceiveTask: Nov 05 08:49:50.677: 00:1e:b3:02:c7:36 0.0.0.0 Removed NPU entry.

*apfMsConnTask_4: Nov 05 08:49:50.680: 00:1e:b3:02:c7:36 Updating AID for REAP AP Client 00:18:74:c7:bf:00 - AID ===> 2

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c7:bf:00

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 13, site 'AHC_Standard', interface 'management'

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 13, site 'AHC_Standard', interface 'management'

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 apfMs1xStateDec

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to L2AUTHCOMPLETE (4) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) DHCP Not required on AP 00:18:74:c7:bf:00 vapId 13 apVapId 4for this client

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_4: Nov 05 08:49:51.589: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:18:74:c7:bf:00 vapId 13 apVapId 4

*apfMsConnTask_4: Nov 05 08:49:51.590: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

 

*apfMsConnTask_4: Nov 05 08:49:51.590: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c7:bf:00 from Associated to Associated

 

*apfMsConnTask_4: Nov 05 08:49:51.590: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 65535 seconds

*apfMsConnTask_4: Nov 05 08:49:51.590: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c7:bf:00 (status 0) ApVapId 4 Slot 0

*apfMsConnTask_4: Nov 05 08:49:51.590: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c7:bf:00 from Associated to Associated

 

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=ExpForeign, client state=APF_MS_STATE_ASSOCIATED

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 apfMsRunStateInc

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to RUN (20) last state RUN (20)

 

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 RUN (20) Reached PLUMBFASTPATH: from line 4558

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 RUN (20) Adding Fast Path rule

  type = Airespace AP Client

  on AP 00:18:74:c7:bf:00, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jumbo Frames = NO

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*apfReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 RUN (20) Successfully plumbed mobile rule (ACL ID 255)

*pemReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 Set bi-dir guest tunnel for 00:1e:b3:02:c7:36 as in Export Foreign role

*pemReceiveTask: Nov 05 08:49:53.554: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 1, dtlFlags 0x4

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 RUN (20) State Update from Mobility-Complete to Mobility-Incomplete

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 apfMsRunStateDec

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 RUN (20) Change state to DHCP_REQD (7) last state RUN (20)

 

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to DHCP_REQD (7) last state RUN (20)

 

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c7:bf:00, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jumbo

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:18:74:c7:bf:00]

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Deleted mobile LWAPP rule on AP [00:18:74:c7:bf:00]

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Updated location for station old AP 00:18:74:c7:bf:00-0, new AP 00:18:74:c4:a2:10-0

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 apfMs1xStateDec

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*pemReceiveTask: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*pemReceiveTask: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 Sent an XID frame

*pemReceiveTask: Nov 05 08:49:56.593: 00:1e:b3:02:c7:36 0.0.0.0 Removed NPU entry.

*apfMsConnTask_2: Nov 05 08:49:56.596: 00:1e:b3:02:c7:36 Updating AID for REAP AP Client 00:18:74:c4:a2:10 - AID ===> 1

*dot1xMsgTask: Nov 05 08:49:56.597: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:49:56.598: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:49:56.598: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:49:56.598: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:49:56.598: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:49:56.598: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.616: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.616: 00:1e:b3:02:c7:36 Received EAPOL-key in PTK_START state (message 2) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.616: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.616: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 apfMs1xStateInc

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state DHCP_REQD (7)

 

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) State Update from Mobility-Incomplete to Mobility-Complete, mobility role=Local, client state=APF_MS_STATE_ASSOCIATED

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) pemAdvanceState2 4817, Adding TMP rule

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.032: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c4:a2:10, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jum

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

 

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4833, Adding TMP rule

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c4:a2:10, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jumbo

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 Key exchange done, data packets from mobile 00:1e:b3:02:c7:36 should be forwarded shortly

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                                    state PTKINITDONE (message 5 - group), replay counter 00.00.00.00.00.00.00.02

*pemReceiveTask: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*pemReceiveTask: Nov 05 08:49:56.622: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*spamApTask5: Nov 05 08:49:56.627: 00:1e:b3:02:c7:36 Sent EAPOL-Key M5 for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.634: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.634: 00:1e:b3:02:c7:36 Received EAPOL-key in REKEYNEGOTIATING state (message 6) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:49:56.634: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 apfMs1xStateDec

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*pemReceiveTask: Nov 05 08:50:01.611: 00:1e:b3:02:c7:36 0.0.0.0 Removed NPU entry.

*dot1xMsgTask: Nov 05 08:50:01.615: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:50:01.615: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:01.615: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:50:01.615: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:01.615: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:50:01.615: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.634: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.634: 00:1e:b3:02:c7:36 Received EAPOL-key in PTK_START state (message 2) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.634: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.634: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                                    state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 apfMs1xStateInc

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state DHCP_REQD (7)

 

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) pemAdvanceState2 4817, Adding TMP rule

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.052: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Adding Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c4:a2:10, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jum

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 L2AUTHCOMPLETE (4) Change state to DHCP_REQD (7) last state DHCP_REQD (7)

 

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) pemAdvanceState2 4833, Adding TMP rule

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Replacing Fast Path rule

  type = Airespace AP - Learn IP address

  on AP 00:18:74:c4:a2:10, slot 0, interface = 1, QOS = 0

  ACL Id = 255, Jumbo

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 20006  IPv6 Vlan = 0, IPv6 intf id = 0

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Successfully plumbed mobile rule (ACL ID 255)

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 Key exchange done, data packets from mobile 00:1e:b3:02:c7:36 should be forwarded shortly

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                                    state PTKINITDONE (message 5 - group), replay counter 00.00.00.00.00.00.00.02

*pemReceiveTask: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*pemReceiveTask: Nov 05 08:50:01.642: 00:1e:b3:02:c7:36 0.0.0.0 Added NPU entry of type 9, dtlFlags 0x0

*spamApTask5: Nov 05 08:50:01.647: 00:1e:b3:02:c7:36 Sent EAPOL-Key M5 for mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.655: 00:1e:b3:02:c7:36 Received EAPOL-Key from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.655: 00:1e:b3:02:c7:36 Received EAPOL-key in REKEYNEGOTIATING state (message 6) from mobile 00:1e:b3:02:c7:36

*Dot1x_NW_MsgTask_6: Nov 05 08:50:01.655: 00:1e:b3:02:c7:36 Stopping retransmission timer for mobile 00:1e:b3:02:c7:36

*spamApTask4: Nov 05 08:50:16.171: 00:1e:b3:02:c7:36 Received Idle-Timeout from AP 00:18:74:c7:bf:00, slot 0 for STA 00:1e:b3:02:c7:36

*spamApTask4: Nov 05 08:50:16.171: 00:1e:b3:02:c7:36 Warning, ignore the DELETE_MOBILE_PAYLOAD from AP: 00:18:74:c7:bf:00, slot 0. STA connecting AP: 00:18:74:c4:a2:10, slot 0

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 apfMs1xStateDec

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 DHCP_REQD (7) Change state to START (0) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:50:33.267: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*pemReceiveTask: Nov 05 08:50:33.268: 00:1e:b3:02:c7:36 0.0.0.0 Removed NPU entry.

*dot1xMsgTask: Nov 05 08:50:33.272: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:50:33.272: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:33.272: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:50:33.272: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:33.272: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:50:33.272: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*osapiBsnTimer: Nov 05 08:50:34.355: 00:1e:b3:02:c7:36 802.1x 'timeoutEvt' Timer expired for station 00:1e:b3:02:c7:36 and for message = M2

*dot1xMsgTask: Nov 05 08:50:34.355: 00:1e:b3:02:c7:36 Retransmit 1 of EAPOL-Key M1 (length 99) for mobile 00:1e:b3:02:c7:36

*osapiBsnTimer: Nov 05 08:50:35.375: 00:1e:b3:02:c7:36 802.1x 'timeoutEvt' Timer expired for station 00:1e:b3:02:c7:36 and for message = M2

*dot1xMsgTask: Nov 05 08:50:35.375: 00:1e:b3:02:c7:36 Retransmit 2 of EAPOL-Key M1 (length 99) for mobile 00:1e:b3:02:c7:36

*osapiBsnTimer: Nov 05 08:50:36.395: 00:1e:b3:02:c7:36 802.1x 'timeoutEvt' Timer expired for station 00:1e:b3:02:c7:36 and for message = M2

*dot1xMsgTask: Nov 05 08:50:36.395: 00:1e:b3:02:c7:36 Retransmit failure for EAPOL-Key M1 to mobile 00:1e:b3:02:c7:36, retransmit count 3, mscb deauth count 0

*dot1xMsgTask: Nov 05 08:50:36.395: 00:1e:b3:02:c7:36 Sent Deauthenticate to mobile on BSSID 00:18:74:c4:a2:10 slot 0(caller 1x_ptsm.c:534)

Retransmit failure for EAPOL-Key M1 to mobile 00:1e:b3:02:c7:36, retransmit count 3, mscb deauth count 0*dot1xMsgTask: Nov 05 08:50:36.395: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 57) in 10 seconds

*osapiBsnTimer: Nov 05 08:50:46.595: 00:1e:b3:02:c7:36 apfMsExpireCallback (apf_ms.c:609) Expiring Mobile!

*apfReceiveTask: Nov 05 08:50:46.595: 00:1e:b3:02:c7:36 apfMsExpireMobileStation (apf_ms.c:5021) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Disassociated

 

*apfReceiveTask: Nov 05 08:50:46.595: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 45) in 10 seconds

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 apfMsAssoStateInc

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Disassociated to Associated

 

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:50:49.292: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*dot1xMsgTask: Nov 05 08:50:49.297: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:50:49.297: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.297: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:50:49.297: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.297: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:50:49.297: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:50:49.383: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*dot1xMsgTask: Nov 05 08:50:49.387: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:50:49.387: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.387: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:50:49.387: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.387: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:50:49.387: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:50:49.710: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*dot1xMsgTask: Nov 05 08:50:49.714: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:50:49.714: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.714: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:50:49.714: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.714: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:50:49.714: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Association received from mobile on AP 00:18:74:c4:a2:10

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Applying site-specific IPv6 override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Applying IPv6 Interface Policy for station 00:1e:b3:02:c7:36 - vlan 0, interface id 0, interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Applying site-specific override for station 00:1e:b3:02:c7:36 - vapId 7, site 'AHC_Standard', interface 'management'

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Changing ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:1633)

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 STA - rates (8): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 STA - rates (11): 132 139 12 18 150 24 36 48 72 96 108 0 0 0 0 0

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Processing WPA IE type 221, length 22 for mobile 00:1e:b3:02:c7:36

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state DHCP_REQD (7)

 

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) DHCP Not required on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7for this client

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Not Using WMM Compliance code qosCap 00

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 00:18:74:c4:a2:10 vapId 7 apVapId 7

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 apfPemAddUser2 (apf_policy.c:223) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 49) in 36000 seconds

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 Sending Assoc Response to station on BSSID 00:18:74:c4:a2:10 (status 0) ApVapId 7 Slot 0

*apfMsConnTask_2: Nov 05 08:50:49.815: 00:1e:b3:02:c7:36 apfProcessAssocReq (apf_80211.c:5272) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Associated

 

*dot1xMsgTask: Nov 05 08:50:49.819: 00:1e:b3:02:c7:36 Creating a PKC PMKID Cache entry for station 00:1e:b3:02:c7:36 (RSN 0)

*dot1xMsgTask: Nov 05 08:50:49.819: 00:1e:b3:02:c7:36 Initiating WPA PSK to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.819: 00:1e:b3:02:c7:36 dot1x - moving mobile 00:1e:b3:02:c7:36 into Force Auth state

*dot1xMsgTask: Nov 05 08:50:49.819: 00:1e:b3:02:c7:36 Skipping EAP-Success to mobile 00:1e:b3:02:c7:36

*dot1xMsgTask: Nov 05 08:50:49.819: 00:1e:b3:02:c7:36 Starting key exchange to mobile 00:1e:b3:02:c7:36, data packets will be dropped

*dot1xMsgTask: Nov 05 08:50:49.819: 00:1e:b3:02:c7:36 Sending EAPOL-Key Message to mobile 00:1e:b3:02:c7:36

                              state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*osapiBsnTimer: Nov 05 08:50:50.879: 00:1e:b3:02:c7:36 802.1x 'timeoutEvt' Timer expired for station 00:1e:b3:02:c7:36 and for message = M2

*dot1xMsgTask: Nov 05 08:50:50.879: 00:1e:b3:02:c7:36 Retransmit 1 of EAPOL-Key M1 (length 99) for mobile 00:1e:b3:02:c7:36

*osapiBsnTimer: Nov 05 08:50:51.899: 00:1e:b3:02:c7:36 802.1x 'timeoutEvt' Timer expired for station 00:1e:b3:02:c7:36 and for message = M2

*dot1xMsgTask: Nov 05 08:50:51.899: 00:1e:b3:02:c7:36 Retransmit 2 of EAPOL-Key M1 (length 99) for mobile 00:1e:b3:02:c7:36

*osapiBsnTimer: Nov 05 08:50:52.919: 00:1e:b3:02:c7:36 802.1x 'timeoutEvt' Timer expired for station 00:1e:b3:02:c7:36 and for message = M2

*dot1xMsgTask: Nov 05 08:50:52.919: 00:1e:b3:02:c7:36 Retransmit failure for EAPOL-Key M1 to mobile 00:1e:b3:02:c7:36, retransmit count 3, mscb deauth count 1

*dot1xMsgTask: Nov 05 08:50:52.919: 00:1e:b3:02:c7:36 Sent Deauthenticate to mobile on BSSID 00:18:74:c4:a2:10 slot 0(caller 1x_ptsm.c:534)

*dot1xMsgTask: Nov 05 08:50:52.919: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 57) in 10 seconds

*osapiBsnTimer: Nov 05 08:51:03.120: 00:1e:b3:02:c7:36 apfMsExpireCallback (apf_ms.c:609) Expiring Mobile!

*apfReceiveTask: Nov 05 08:51:03.120: 00:1e:b3:02:c7:36 apfMsExpireMobileStation (apf_ms.c:5021) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Associated to Disassociated

 

*apfReceiveTask: Nov 05 08:51:03.120: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 45) in 10 seconds

*osapiBsnTimer: Nov 05 08:51:13.320: 00:1e:b3:02:c7:36 apfMsExpireCallback (apf_ms.c:609) Expiring Mobile!

*apfReceiveTask: Nov 05 08:51:13.320: 00:1e:b3:02:c7:36 Sent Deauthenticate to mobile on BSSID 00:18:74:c4:a2:10 slot 0(caller apf_ms.c:5113)

*apfReceiveTask: Nov 05 08:51:13.320: 00:1e:b3:02:c7:36 apfMsAssoStateDec

*apfReceiveTask: Nov 05 08:51:13.320: 00:1e:b3:02:c7:36 apfMsExpireMobileStation (apf_ms.c:5151) Changing state for mobile 00:1e:b3:02:c7:36 on AP 00:18:74:c4:a2:10 from Disassociated to Idle

 

*apfReceiveTask: Nov 05 08:51:13.320: 00:1e:b3:02:c7:36 Scheduling deletion of Mobile Station:  (callerId: 47) in 10 seconds

*osapiBsnTimer: Nov 05 08:51:23.520: 00:1e:b3:02:c7:36 apfMsExpireCallback (apf_ms.c:609) Expiring Mobile!

*apfReceiveTask: Nov 05 08:51:23.520: 00:1e:b3:02:c7:36 pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfReceiveTask: Nov 05 08:51:23.520: 00:1e:b3:02:c7:36 0.0.0.0 8021X_REQD (3) Deleted mobile LWAPP rule on AP [00:18:74:c4:a2:10]

3 REPLIES

what problem is the client

what problem is the client having?

 

--

Steve

HTH, Steve ------------------------------------------------------------------------------------------------ Please remember to rate useful posts, and mark questions as answered
New Member

They are authenticating and

They are authenticating and associating to the AP but not picking up a DHCP address, but then they get disassociated.  It happens in a loop.  I know for sure there isn't a problem with DHCP as other clients connected to the SSID pick up an address just fine.

 


 

So from what I'm seeing in

So from what I'm seeing in the debugs, this client is anchoring in a guest LAN on the internal WLC.

Set bi-dir guest tunnel for 00:1e:b3:02:c7:36 as in Export Foreign role

 

the DHCP would come from the Anchor not the foreign.

If this client is supposed to be on an internal SSID, make sure you have Fast SSID switch enabled.

 

HTH,

Steve

HTH, Steve ------------------------------------------------------------------------------------------------ Please remember to rate useful posts, and mark questions as answered
106
Views
0
Helpful
3
Replies
CreatePlease to create content