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

5508 wlc with 1140 APs and NPS cant connect

Hi.. let me go off with the equipment setup that I have first..

I have 3 sites each site has  2x 5508 WLC and a few 1140 AP's per site.

I also have 3 NPS running on those 3 sites..

I have NPS and dhcp running on the same server...

I have a seperate CA root Authority server.

3 seperate DC ... one per site..

I followed everything accoriding to  this guide

http://araihan.wordpress.com/2010/04/30/complete-guide-to-build-a-cisco-wireless-infrastructure-using-cisco-wlc-5500-cisco-1142-ap-and-microsoft-radius-server/

and also the guide that is on the CISCO site

http://www.cisco.com/en/US/products/ps10315/products_configuration_example09186a0080bfb19a.shtml

I did a debug on the wlc for the wireless client that I was using to try and connect.. I get the following   I hope someone can help me figure out whats wrong.

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2013.09.18 13:50:17 =~=~=~=~=~=~=~=~=~=~=~=

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.000: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.000: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Aborting state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.001: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.001: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 4)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.001: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.003: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.003: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.003: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:40.003: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:52:40.004: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:52:42.098: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:52:42.101: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:52:42.102: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:52:42.103: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:52:42.108: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*dot1xMsgTask: Sep 18 13:52:42.108: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:52:42.108: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.133: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.133: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.133: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.150: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.150: 00:14:a5:4b:cf:ef Received EAP Response packet with mismatching id (currentid=2, eapid=1) from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.165: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.165: 00:14:a5:4b:cf:ef Received Identity Response (count=2) from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.165: 00:14:a5:4b:cf:ef EAP State update from Connecting to Authenticating for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.165: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Authenticating state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.165: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.172: 00:14:a5:4b:cf:ef Processing Access-Reject for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.173: 00:14:a5:4b:cf:ef Removing PMK cache due to EAP-Failure for mobile 00:14:a5:4b:cf:ef (EAP Id -1)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.173: 00:14:a5:4b:cf:ef Sending EAP-Failure to mobile 00:14:a5:4b:cf:ef (EAP Id -1)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.173: 00:14:a5:4b:cf:ef Entering Backend Auth Failure state (id=-1) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.173: 00:14:a5:4b:cf:ef Setting quiet timer for 5 seconds for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.173: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Unknown state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.211: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*osapiBsnTimer: Sep 18 13:52:47.076: 00:14:a5:4b:cf:ef 802.1x 'quiteWhile' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:52:47.076: 00:14:a5:4b:cf:ef quiet timer completed for mobile 00:14:a5:4b:cf:ef

*dot1xMsgTask: Sep 18 13:52:47.076: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:52:47.076: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*apfMsConnTask_7: Sep 18 13:52:55.606: Stats update: Non Zero value

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.228: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.228: 00:14:a5:4b:cf:ef Received Identity Response (count=3) from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.228: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.231: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.231: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.231: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*Dot1x_NW_MsgTask_7: Sep 18 13:52:59.231: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:52:59.231: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:53:01.348: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:53:01.351: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:53:01.351: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_7: Sep 18 13:53:01.352: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:53:01.353: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:53:01.356: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*dot1xMsgTask: Sep 18 13:53:01.356: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:53:01.356: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:53:01.401: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:53:01.401: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:53:01.401: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*osapiBsnTimer: Sep 18 13:53:31.276: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:53:31.276: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:53:31.276: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 3)

*osapiBsnTimer: Sep 18 13:54:01.276: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:54:01.276: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*dot1xMsgTask: Sep 18 13:54:01.279: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*dot1xMsgTask: Sep 18 13:54:01.279: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*dot1xMsgTask: Sep 18 13:54:01.279: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*dot1xMsgTask: Sep 18 13:54:01.279: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:54:01.280: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:54:03.414: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:54:03.417: Stats update: Non Zero value

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:54:03.418: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:54:03.419: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:54:03.421: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*dot1xMsgTask: Sep 18 13:54:03.421: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:54:03.421: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:54:03.465: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:54:03.465: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:54:03.465: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*apfMsConnTask_7: Sep 18 13:54:26.151: Stats update: Non Zero value

*osapiBsnTimer: Sep 18 13:54:33.276: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:54:33.276: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:54:33.276: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 3)

*osapiBsnTimer: Sep 18 13:55:03.276: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:55:03.276: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*dot1xMsgTask: Sep 18 13:55:03.279: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*dot1xMsgTask: Sep 18 13:55:03.279: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*dot1xMsgTask: Sep 18 13:55:03.279: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*dot1xMsgTask: Sep 18 13:55:03.279: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:55:05.371: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:55:05.372: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:55:05.374: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:55:05.376: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*dot1xMsgTask: Sep 18 13:55:05.376: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:55:05.376: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:55:05.427: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:55:05.428: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:55:05.428: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*osapiBsnTimer: Sep 18 13:55:35.276: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:55:35.276: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:55:35.276: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 3)

*apfMsConnTask_7: Sep 18 13:55:56.698: Stats update: Non Zero value

*osapiBsnTimer: Sep 18 13:56:05.276: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:56:05.276: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*dot1xMsgTask: Sep 18 13:56:05.279: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*dot1xMsgTask: Sep 18 13:56:05.279: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*dot1xMsgTask: Sep 18 13:56:05.279: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*dot1xMsgTask: Sep 18 13:56:05.279: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:43:62

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 0

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_4: Sep 18 13:56:07.381: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Deleted mobile LWAPP rule on AP [24:01:c7:ed:48:70]

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Updated location for station old AP 24:01:c7:ed:48:70-0, new AP 24:01:c7:ed:43:60-0

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:43:60 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:43:60 from Associated to Associated

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_4: Sep 18 13:56:07.382: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_4: Sep 18 13:56:07.383: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:43:62 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_4: Sep 18 13:56:07.383: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:43:60 from Associated to Associated

*dot1xMsgTask: Sep 18 13:56:07.385: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*dot1xMsgTask: Sep 18 13:56:07.385: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:56:07.385: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:56:07.439: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:56:07.439: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:56:07.439: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 0

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:56:11.016: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Deleted mobile LWAPP rule on AP [24:01:c7:ed:43:60]

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Updated location for station old AP 24:01:c7:ed:43:60-0, new AP 24:01:c7:ed:48:70-0

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:56:11.017: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:56:11.018: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:56:11.018: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:56:11.020: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:56:11.020: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*apfMsConnTask_7: Sep 18 13:56:19.692: 00:14:a5:4b:cf:ef Reassociation received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:56:19.692: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:56:19.692: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_7: Sep 18 13:56:19.692: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:56:19.693: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Initializing policy

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:56:19.694: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:56:19.696: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:56:19.696: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:56:19.751: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:56:19.751: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:56:19.752: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*osapiBsnTimer: Sep 18 13:56:49.676: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:56:49.676: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:56:49.676: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 3)

*osapiBsnTimer: Sep 18 13:57:19.676: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:57:19.676: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*dot1xMsgTask: Sep 18 13:57:19.679: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*dot1xMsgTask: Sep 18 13:57:19.679: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*dot1xMsgTask: Sep 18 13:57:19.679: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*dot1xMsgTask: Sep 18 13:57:19.679: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:57:19.679: Stats update: Non Zero value

*osapiBsnTimer: Sep 18 13:57:29.676: 00:14:a5:4b:cf:ef apfMsExpireCallback (apf_ms.c:614) Expiring Mobile!

*apfReceiveTask: Sep 18 13:57:29.676: 00:14:a5:4b:cf:ef apfMsExpireMobileStation (apf_ms.c:5803) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Disassociated

*apfReceiveTask: Sep 18 13:57:29.676: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 45) in 10 seconds

*osapiBsnTimer: Sep 18 13:57:39.676: 00:14:a5:4b:cf:ef apfMsExpireCallback (apf_ms.c:614) Expiring Mobile!

*apfReceiveTask: Sep 18 13:57:39.677: 00:14:a5:4b:cf:ef apfMsAssoStateDec

*apfReceiveTask: Sep 18 13:57:39.677: 00:14:a5:4b:cf:ef apfMsExpireMobileStation (apf_ms.c:5935) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Disassociated to Idle

*apfReceiveTask: Sep 18 13:57:39.677: 00:14:a5:4b:cf:ef pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfReceiveTask: Sep 18 13:57:39.677: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Deleted mobile LWAPP rule on AP [24:01:c7:ed:48:70]

*apfReceiveTask: Sep 18 13:57:39.677: 00:14:a5:4b:cf:ef Deleting mobile on AP 24:01:c7:ed:48:70(0)

*apfMsConnTask_7: Sep 18 13:58:04.863: 00:14:a5:4b:cf:ef Adding mobile on LWAPP AP 24:01:c7:ed:48:70(0)

*apfMsConnTask_7: Sep 18 13:58:04.863: 00:14:a5:4b:cf:ef Association received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 18 13:58:04.863: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 18 13:58:04.863: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 0

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 0 Quarantine Vlan 0 Access Vlan 0

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 0 0 0 0 0 0 0 0

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 18 13:58:04.864: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef apfMsAssoStateInc

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Idle to Associated

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 18 13:58:04.865: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 18 13:58:04.868: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*dot1xMsgTask: Sep 18 13:58:04.868: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:58:04.868: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 18 13:58:04.897: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 18 13:58:04.897: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 18 13:58:04.897: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*osapiBsnTimer: Sep 18 13:58:34.876: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:58:34.876: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 18 13:58:34.876: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 3)

*apfMsConnTask_7: Sep 18 13:58:57.792: Stats update: Non Zero value

*osapiBsnTimer: Sep 18 13:59:04.876: 00:14:a5:4b:cf:ef 802.1x 'txWhen' Timer expired for station 00:14:a5:4b:cf:ef and for message = M0

*dot1xMsgTask: Sep 18 13:59:04.876: 00:14:a5:4b:cf:ef Reached Max EAP-Identity Request retries (3) for STA 00:14:a5:4b:cf:ef

*dot1xMsgTask: Sep 18 13:59:04.879: 00:14:a5:4b:cf:ef Sent Deauthenticate to mobile on BSSID 24:01:c7:ed:48:70 slot 0(caller 1x_auth_pae.c:3241)

*dot1xMsgTask: Sep 18 13:59:04.879: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 6) in 10 seconds

*dot1xMsgTask: Sep 18 13:59:04.879: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Disconnected state

*dot1xMsgTask: Sep 18 13:59:04.879: 00:14:a5:4b:cf:ef Not sending EAP-Failure for STA 00:14:a5:4b:cf:ef

*osapiBsnTimer: Sep 18 13:59:14.876: 00:14:a5:4b:cf:ef apfMsExpireCallback (apf_ms.c:614) Expiring Mobile!

*apfReceiveTask: Sep 18 13:59:14.876: 00:14:a5:4b:cf:ef apfMsExpireMobileStation (apf_ms.c:5803) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Disassociated

*apfReceiveTask: Sep 18 13:59:14.876: 00:14:a5:4b:cf:ef Scheduling deletion of Mobile Station:  (callerId: 45) in 10 seconds

*osapiBsnTimer: Sep 18 13:59:24.876: 00:14:a5:4b:cf:ef apfMsExpireCallback (apf_ms.c:614) Expiring Mobile!

*apfReceiveTask: Sep 18 13:59:24.876: 00:14:a5:4b:cf:ef apfMsAssoStateDec

*apfReceiveTask: Sep 18 13:59:24.876: 00:14:a5:4b:cf:ef apfMsExpireMobileStation (apf_ms.c:5935) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Disassociated to Idle

*apfReceiveTask: Sep 18 13:59:24.876: 00:14:a5:4b:cf:ef pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfReceiveTask: Sep 18 13:59:24.876: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Deleted mobile LWAPP rule on AP [24:01:c7:ed:48:70]

*apfReceiveTask: Sep 18 13:59:24.877: 00:14:a5:4b:cf:ef Deleting mobile on AP 24:01:c7:ed:48:70(0)

it pretty much keep asking me for user name and password which is my domain user account.

any clue ?

Thanks.

4 REPLIES

Re: 5508 wlc with 1140 APs and NPS cant connect

I see that there is
Processing Access-Reject in the debug output.

What does the eventvwr for the NPS process say is the reason?

Steve

Sent from Cisco Technical Support iPhone App

HTH, Steve ------------------------------------------------------------------------------------------------ Please remember to rate useful posts, and mark questions as answered
Cisco Employee

Re: 5508 wlc with 1140 APs and NPS cant connect

since you are getting access reject you need to check NP server.

Microsoft support would be the best place

Hall of Fame Super Gold

5508 wlc with 1140 APs and NPS cant connect

*Dot1x_NW_MsgTask_7: Sep 18 13:52:42.173: 00:14:a5:4b:cf:ef Removing PMK cache due to EAP-Failure for mobile 00:14:a5:4b:cf:ef (EAP Id -1)

Your NPS is rejecting the mobile credential.

What logs can you find in your NPS server?

New Member

5508 wlc with 1140 APs and NPS cant connect

hello everyone.. thanks for the reply... I changed a few things around..  instead of having 3 NPS server.. I disabled 2 of them and only using 1 for now.. I am trying to keep everything simple and geting working first..  after doing this.. looks like the message from event view is success   I got 2 events int he log..   1. Network policy server granded access to a user.   2.Network Policy server granted full acess to a user because of the host met the defined health policy.

both sucess...

I am not getting a DHCP address... for some reason ... here is the log... from the debug.. hopefully someone can help me figure this out..  Thanks again for helping and posting your comments.

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2013.09.19 09:47:17 =~=~=~=~=~=~=~=~=~=~=~=

*apfMsConnTask_7: Sep 19 09:48:07.590: Stats update: Non Zero value

*apfMsConnTask_7: Sep 19 09:49:38.135: Stats update: Non Zero value

*apfMsConnTask_7: Sep 19 09:50:14.516: Stats update: Non Zero value

*apfMsConnTask_7: Sep 19 09:50:14.525: Stats update: Non Zero value

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Association received from mobile on BSSID 24:01:c7:ed:48:72

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Global 200 Clients are allowed to AP radio

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Max Client Trap Threshold: 0  cur: 1

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Rf profile 200 Clients are allowed to AP wlan

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef In processSsidIE:4202 setting Central switched to TRUE

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef In processSsidIE:4205 apVapId = 3 and Split Acl Id = 65535

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Applying site-specific Local Bridging override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Applying Local Bridging Interface Policy for station 00:14:a5:4b:cf:ef - vlan 232, interface id 13, interface 'aginternet'

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*apfMsConnTask_7: Sep 19 09:50:14.526: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef processSsidIE  statusCode is 0 and status is 0

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef processSsidIE  ssid_done_flag is 0 finish_flag is 0

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef STA - rates (8): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef suppRates  statusCode is 0 and gotSuppRatesElement is 1

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef STA - rates (12): 130 132 139 150 36 48 72 108 12 18 24 96 0 0 0 0

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef extSuppRates  statusCode is 0 and gotExtSuppRatesElement is 1

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Processing RSN IE type 48, length 20 for mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Received RSN IE with 0 PMKIDs from mobile 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Found an cache entry for BSSID 24:01:c7:ed:48:72 in PMKID cache at index 0 of station 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Removing BSSID 24:01:c7:ed:48:72 from PMKID cache of station 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Resetting MSCB PMK Cache Entry 0 for station 00:14:a5:4b:cf:ef

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Setting active key cache index 0 ---> 8

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef apfMsRunStateDec

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef apfMs1xStateDec

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Change state to START (0) last state RUN (20)

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef pemApfAddMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Initializing policy

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 START (0) Change state to AUTHCHECK (2) last state START (0)

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)

*pemReceiveTask: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 Removed NPU entry.

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*apfMsConnTask_7: Sep 19 09:50:14.527: 00:14:a5:4b:cf:ef apfPemAddUser2 (apf_policy.c:276) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*apfMsConnTask_7: Sep 19 09:50:14.528: 00:14:a5:4b:cf:ef apfPemAddUser2:session timeout forstation 00:14:a5:4b:cf:ef - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0

*apfMsConnTask_7: Sep 19 09:50:14.528: 00:14:a5:4b:cf:ef Stopping deletion of Mobile Station: (callerId: 48)

*apfMsConnTask_7: Sep 19 09:50:14.528: 00:14:a5:4b:cf:ef Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0

*apfMsConnTask_7: Sep 19 09:50:14.528: 00:14:a5:4b:cf:ef Sending Assoc Response to station on BSSID 24:01:c7:ed:48:72 (status 0) ApVapId 3 Slot 0

*apfMsConnTask_7: Sep 19 09:50:14.528: 00:14:a5:4b:cf:ef apfProcessAssocReq (apf_80211.c:7391) Changing state for mobile 00:14:a5:4b:cf:ef on AP 24:01:c7:ed:48:70 from Associated to Associated

*dot1xMsgTask: Sep 19 09:50:14.531: 00:14:a5:4b:cf:ef Disable re-auth, use PMK lifetime.

*dot1xMsgTask: Sep 19 09:50:14.531: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*dot1xMsgTask: Sep 19 09:50:14.531: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 1)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:14.551: 00:14:a5:4b:cf:ef Received EAPOL START from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:14.552: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Connecting state

*Dot1x_NW_MsgTask_7: Sep 19 09:50:14.552: 00:14:a5:4b:cf:ef Sending EAP-Request/Identity to mobile 00:14:a5:4b:cf:ef (EAP Id 2)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.443: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.443: 00:14:a5:4b:cf:ef Received Identity Response (count=2) from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.443: 00:14:a5:4b:cf:ef EAP State update from Connecting to Authenticating for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.443: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Authenticating state

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.443: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.473: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.473: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=3) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.473: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 3)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.491: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.491: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 3, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.491: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.493: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.493: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=4) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.493: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 4)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.505: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.505: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 4, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.505: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.509: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.509: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=7) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.509: 00:14:a5:4b:cf:ef WARNING: updated EAP-Identifier 4 ===> 7 for STA 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.509: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 7)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.522: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.522: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 7, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.522: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.524: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.524: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=8) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.524: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 8)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.536: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.536: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 8, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.536: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.538: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.538: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=9) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.538: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 9)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.552: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.552: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 9, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.552: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.554: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.554: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=10) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.554: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 10)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.568: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.568: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 10, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.568: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.581: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.581: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=11) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.581: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 11)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.599: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.599: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 11, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.599: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.602: 00:14:a5:4b:cf:ef Processing Access-Challenge for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.602: 00:14:a5:4b:cf:ef Entering Backend Auth Req state (id=13) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.602: 00:14:a5:4b:cf:ef WARNING: updated EAP-Identifier 11 ===> 13 for STA 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.602: 00:14:a5:4b:cf:ef Sending EAP Request from AAA to mobile 00:14:a5:4b:cf:ef (EAP Id 13)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.615: 00:14:a5:4b:cf:ef Received EAPOL EAPPKT from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.615: 00:14:a5:4b:cf:ef Received EAP Response from mobile 00:14:a5:4b:cf:ef (EAP Id 13, EAP Type 25)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.615: 00:14:a5:4b:cf:ef Entering Backend Auth Response state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Processing Access-Accept for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Resetting web IPv4 acl from 255 to 255

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Resetting web IPv4 Flex acl from 65535 to 65535

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Applying site-specific override for station 00:14:a5:4b:cf:ef - vapId 3, site 'CoreSites', interface 'aginternet'

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Applying Interface policy on Mobile, role Local. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 232

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Re-applying interface policy for client

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2018)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2246)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Inserting AAA Override struct for mobile

    MAC: 00:14:a5:4b:cf:ef, source 4

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Setting re-auth timeout to 1800 seconds, got from WLAN config.

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.619: 00:14:a5:4b:cf:ef Station 00:14:a5:4b:cf:ef setting dot1x reauth timeout = 1800

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.622: 00:14:a5:4b:cf:ef Creating a PKC PMKID Cache entry for station 00:14:a5:4b:cf:ef (RSN 2)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.622: 00:14:a5:4b:cf:ef Resetting MSCB PMK Cache Entry 0 for station 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.622: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 8

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.622: 00:14:a5:4b:cf:ef Setting active key cache index 8 ---> 0

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.622: 00:14:a5:4b:cf:ef Adding BSSID 24:01:c7:ed:48:72 to PMKID cache at index 0 for station 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.622: New PMKID: (16)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623:      [0000] 0e 97 09 a5 6c f9 2a 28 58 03 74 d2 8c 51 b7 ba

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Disabling re-auth since PMK lifetime can take care of same.

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef unsetting PmkIdValidatedByAp

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef PMK sent to mobility group

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Sending EAP-Success to mobile 00:14:a5:4b:cf:ef (EAP Id 13)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Found an cache entry for BSSID 24:01:c7:ed:48:72 in PMKID cache at index 0 of station 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Found an cache entry for BSSID 24:01:c7:ed:48:72 in PMKID cache at index 0 of station 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: Including PMKID in M1  (16)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623:      [0000] 0e 97 09 a5 6c f9 2a 28 58 03 74 d2 8c 51 b7 ba

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Starting key exchange to mobile 00:14:a5:4b:cf:ef, data packets will be dropped

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Sending EAPOL-Key Message to mobile 00:14:a5:4b:cf:ef

   state INITPMK (message 1), replay counter 00.00.00.00.00.00.00.00

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Entering Backend Auth Success state (id=13) for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.623: 00:14:a5:4b:cf:ef Received Auth Success while in Authenticating state for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.624: 00:14:a5:4b:cf:ef dot1x - moving mobile 00:14:a5:4b:cf:ef into Authenticated state

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.647: 00:14:a5:4b:cf:ef Received EAPOL-Key from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.647: 00:14:a5:4b:cf:ef Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.647: 00:14:a5:4b:cf:ef Received EAPOL-key in PTK_START state (message 2) from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.647: 00:14:a5:4b:cf:ef PMK: Sending cache add

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.648: 00:14:a5:4b:cf:ef Stopping retransmission timer for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.648: 00:14:a5:4b:cf:ef Sending EAPOL-Key Message to mobile 00:14:a5:4b:cf:ef

   state PTKINITNEGOTIATING (message 3), replay counter 00.00.00.00.00.00.00.01

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.653: 00:14:a5:4b:cf:ef Received EAPOL-Key from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.653: 00:14:a5:4b:cf:ef Ignoring invalid EAPOL version (1) in EAPOL-key message from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.653: 00:14:a5:4b:cf:ef Received EAPOL-key in PTKINITNEGOTIATING state (message 4) from mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.653: 00:14:a5:4b:cf:ef Stopping retransmission timer for mobile 00:14:a5:4b:cf:ef

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.654: 00:14:a5:4b:cf:ef apfMs1xStateInc

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.654: 00:14:a5:4b:cf:ef 0.0.0.0 8021X_REQD (3) Change state to L2AUTHCOMPLETE (4) last state 8021X_REQD (3)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.654: 00:14:a5:4b:cf:ef Not Using WMM Compliance code qosCap 00

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.654: 00:14:a5:4b:cf:ef 0.0.0.0 L2AUTHCOMPLETE (4) Plumbed mobile LWAPP rule on AP 24:01:c7:ed:48:70 vapId 3 apVapId 3 flex-acl-name:

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.654: 00:14:a5:4b:cf:ef apfMsRunStateInc

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.654: 00:14:a5:4b:cf:ef 0.0.0.0 L2AUTHCOMPLETE (4) Change state to RUN (20) last state L2AUTHCOMPLETE (4)

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.656: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Reached PLUMBFASTPATH: from line 6075

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.656: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Adding Fast Path rule

  type = Airespace AP Client

  on AP 24:01:c7:ed:48:70, slot 0, interface = 13, QOS = 0

  IPv4 ACL ID = 255, IPv6 ACL ID = 255,

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.656: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Fast Path rule (contd...) 802.1P = 0, DSCP = 0, TokenID = 15206  Local Bridging Vlan = 232, Local Bridging intf id = 13

*Dot1x_NW_MsgTask_7: Sep 19 09:50:21.658: 00:14:a5:4b:cf:ef 0.0.0.0 RUN (20) Successfully plumbed mobile rule (IPv4 ACL ID 255, IPv6 ACL ID 255)

*pemReceiveTask: Sep 19 09:50:21.658: 00:14:a5:4b:cf:ef 0.0.0.0 Added NPU entry of type 1, dtlFlags 0x0

*pemReceiveTask: Sep 19 09:50:21.658: 00:14:a5:4b:cf:ef Pushing IPv6: fe80:0000:0000:0000: 290f:8f76:9221:b33d , and MAC: 00:14:A5:4B:CF:EF , Binding to Data Plane. SUCCESS !!

*DHCP Socket Task: Sep 19 09:50:21.740: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 0, flags: 0

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 0, flags: 0

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:21.741: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:21.742: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*apfOrphanSocketTask: Sep 19 09:50:22.740: 00:14:a5:4b:cf:ef Orphan Packet from STA - IP 169.254.179.61

*apfOrphanSocketTask: Sep 19 09:50:22.740: 00:14:a5:4b:cf:ef In apfRegisterIpAddrOnMscb_debug: regType=2  Invalid src IP address, 169.254.179.61 is part of reserved ip address range (caller apf_broff.c:229)

*apfOrphanSocketTask: Sep 19 09:50:22.740: 00:14:a5:4b:cf:ef IPv4 Addr: 169:254:179:61

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 0.0.0.0  VLAN: 0

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 1280, flags: 0

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:26.303: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:26.304: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:50:26.304: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 1280, flags: 0

*DHCP Socket Task: Sep 19 09:50:26.304: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:26.304: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:26.304: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:26.304: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:34.146: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 3072, flags: 0

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 3072, flags: 0

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:34.147: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 6912, flags: 0

*DHCP Socket Task: Sep 19 09:50:49.037: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   xid: 0x57b5e60d (1471538701), secs: 6912, flags: 0

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:50:49.038: 00:14:a5:4b:cf:ef Failed to get response for 3 dhcp attempts from client.Total DHCP failed count for the interface aginternet : 2

*apfMsConnTask_7: Sep 19 09:51:08.682: Stats update: Non Zero value

*DHCP Socket Task: Sep 19 09:51:20.248: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 0, flags: 8000

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 0, flags: 8000

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:20.249: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:20.250: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:24.351: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 1024, flags: 8000

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 1024, flags: 8000

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:24.352: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:24.353: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 2816, flags: 8000

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:51:32.041: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 2816, flags: 8000

*DHCP Socket Task: Sep 19 09:51:32.042: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:32.042: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:32.042: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:32.042: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP received op BOOTREQUEST (1) (len 308,vlan 230, port 13, encap 0xec03)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selecting relay 1 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selected relay 1 - 172.17.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 1

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 6656, flags: 8000

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*DHCP Socket Task: Sep 19 09:51:47.040: 00:14:a5:4b:cf:ef DHCP selecting relay 2 - control block settings:

            dhcpServer: 0.0.0.0, dhcpNetmask: 0.0.0.0,

            dhcpGateway: 0.0.0.0, dhcpRelay: 172.17.232.8  VLAN: 232

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP selected relay 2 - 172.16.16.50 (local address 172.17.232.8, gateway 172.17.232.1, VLAN 232, port 13)

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP transmitting DHCP DISCOVER (1)

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP   op: BOOTREQUEST, htype: Ethernet, hlen: 6, hops: 2

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP   xid: 0xd07dc597 (3497903511), secs: 6656, flags: 8000

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP   chaddr: 00:14:a5:4b:cf:ef

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP   ciaddr: 0.0.0.0,  yiaddr: 0.0.0.0

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP   siaddr: 0.0.0.0,  giaddr: 172.17.232.8

*DHCP Socket Task: Sep 19 09:51:47.041: 00:14:a5:4b:cf:ef DHCP sending REQUEST to 172.17.232.1 (len 350, port 13, vlan 232)

*apfMsConnTask_7: Sep 19 09:52:39.228: Stats update: Non Zero value

315
Views
0
Helpful
4
Replies
CreatePlease to create content