*apfMsConnTask_2: Mar 19 09:44:55.031: 28:16:ad:bd:1c:cb Processing assoc-req station:28:16:ad:bd:1c:cb AP:64:12:25:2b:62:10-01 ssid : Arbeitsplatz thread:18d09c80
*spamApTask2: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb Sent dot1x auth initiate message for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 1 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:47
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb EAP-PARAM Debug - eap-params for Wlan-Id :1 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb Station 28:16:ad:bd:1c:cb setting dot1x reauth timeout = 1800
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb dot1x - moving mobile 28:16:ad:bd:1c:cb into Connecting state
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.033: 28:16:ad:bd:1c:cb Sending EAP-Request/Identity to mobile 28:16:ad:bd:1c:cb (EAP Id 1)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.084: 28:16:ad:bd:1c:cb Received EAPOL START, dot1x state = 2
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.084: 28:16:ad:bd:1c:cb reauth_sm state transition 1 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.084: 28:16:ad:bd:1c:cb Received EAPOL START from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.084: 28:16:ad:bd:1c:cb dot1x - moving mobile 28:16:ad:bd:1c:cb into Connecting state
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.084: 28:16:ad:bd:1c:cb Sending EAP-Request/Identity to mobile 28:16:ad:bd:1c:cb (EAP Id 2)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.084: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.089: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.089: 28:16:ad:bd:1c:cb Received EAP Response packet with mismatching id (currentid=2, eapid=1) from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.089: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb Received Identity Response (count=1) from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb Resetting reauth count 1 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb EAP State update from Connecting to Authenticating for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb dot1x - moving mobile 28:16:ad:bd:1c:cb into Authenticating state
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.094: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.105: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.105: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.105: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=3) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.105: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 3)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.105: 28:16:ad:bd:1c:cb Allocating EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.105: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.108: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.108: 28:16:ad:bd:1c:cb Received EAP Response from mobile 28:16:ad:bd:1c:cb (EAP Id 3, EAP Type 25)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.108: 28:16:ad:bd:1c:cb Resetting reauth count 0 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.108: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.108: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.108: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.109: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.109: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=4) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.109: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 4)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.110: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.110: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.112: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.112: 28:16:ad:bd:1c:cb Received EAP Response from mobile 28:16:ad:bd:1c:cb (EAP Id 4, EAP Type 25)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.112: 28:16:ad:bd:1c:cb Resetting reauth count 0 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.112: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.112: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.113: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.113: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=5) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.113: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 5)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.113: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.113: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.125: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.125: 28:16:ad:bd:1c:cb Received EAP Response from mobile 28:16:ad:bd:1c:cb (EAP Id 5, EAP Type 25)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.125: 28:16:ad:bd:1c:cb Resetting reauth count 0 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.125: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.125: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.129: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.129: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=6) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.129: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 6)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.129: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:55.129: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Processing assoc-req station:28:16:ad:bd:1c:cb AP:64:12:25:2b:62:10-01 ssid : Arbeitsplatz thread:18d09c80
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Station:  28:16:AD:BD:1C:CB  11v BSS Transition not enabled on the AP  64:12:25:2B:62:10 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Association received from mobile on BSSID 64:12:25:2b:62:1f AP WAP_2OG_3
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Station:  28:16:AD:BD:1C:CB  11v BSS Transition not enabled on the AP  64:12:25:2B:62:10 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Global 200 Clients are allowed to AP radio
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Max Client Trap Threshold: 0  cur: 11
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Rf profile 600 Clients are allowed to AP wlan
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb override for default ap group, marking intgrp NULL
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Applying Interface(dummy) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 32
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Re-applying interface policy for client 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2711)
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2732)
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb apfApplyWlanPolicy: Apply WLAN Policy over PMIPv6 Client Mobility Type, Tunnel User - 0
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb In processSsidIE:6332 setting Central switched to TRUE
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb In processSsidIE:6335 apVapId = 1 and Split Acl Id = 65535
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Applying site-specific Local Bridging override for station 28:16:ad:bd:1c:cb - vapId 1, site 'Centris-AP-Group', interface 'clients'
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Applying Local Bridging Interface Policy for station 28:16:ad:bd:1c:cb - vlan 32, interface id 11, interface 'clients'
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb override from ap group, removing intf group from mscb
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Applying site-specific override for station 28:16:ad:bd:1c:cb - vapId 1, site 'Centris-AP-Group', interface 'clients'
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Applying Interface(clients) policy on Mobile, role Unassociated. Ms NAC State 2 Quarantine Vlan 0 Access Vlan 999
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Re-applying interface policy for client 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Changing IPv4 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2711)
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Changing IPv6 ACL 'none' (ACL ID 255) ===> 'none' (ACL ID 255) --- (caller apf_policy.c:2732)
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb processSsidIE  ssid_done_flag is 0 finish_flag is 0
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb STA - rates (8): 12 18 152 36 48 72 96 108 0 0 0 0 0 0 0 0
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb suppRates  statusCode is 0 and gotSuppRatesElement is 1 
*apfMsConnTask_2: Mar 19 09:44:56.623: RSNIE in Assoc. Req.: (22)
*apfMsConnTask_2: Mar 19 09:44:56.623:      [0000] 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f
*apfMsConnTask_2: Mar 19 09:44:56.623:      [0016] ac 01 3c 00 00 00
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Processing RSN IE type 48, length 22 for mobile 28:16:ad:bd:1c:cb
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Received 802.11i 802.1X key management suite, enabling dot1x Authentication
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb RSN Capabilities:  60
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Marking Mobile as non-11w Capable 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Received RSN IE with 0 PMKIDs from mobile 28:16:ad:bd:1c:cb
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Setting active key cache index 8 ---> 8
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb unsetting PmkIdValidatedByAp
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Initializing policy
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Change state to AUTHCHECK (2) last state 8021X_REQD (3)
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb  AuthenticationRequired = 1 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 AUTHCHECK (2) Change state to 8021X_REQD (3) last state AUTHCHECK (2)
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Encryption policy is set to 0x80000001
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Not Using WMM Compliance code qosCap 00
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Vlan while overriding the policy = -1
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb sending to spamAddMobile vlanId -1 flex aclName = , flexAclId 65535
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb 0.0.0.0 8021X_REQD (3) Plumbed mobile LWAPP rule on AP 64:12:25:2b:62:10 vapId 1 apVapId 1 flex-acl-name: 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb apfPemAddUser2 (apf_policy.c:362) Changing state for mobile 28:16:ad:bd:1c:cb on AP 64:12:25:2b:62:10 from Associated to Associated
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb apfPemAddUser2:session timeout forstation 28:16:ad:bd:1c:cb - Session Tout 0, apfMsTimeOut '0' and sessionTimerRunning flag is  0 
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Func: apfPemAddUser2, Ms Timeout = 0, Session Timeout = 0
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Sending assoc-resp with status 0 station:28:16:ad:bd:1c:cb AP:64:12:25:2b:62:10-01 on apVapId 1
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb VHT Operation IE: width 20/0 ch 48 freq0 0 freq1 0 msc0 0x3f msc1 0x3f
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb Sending Assoc Response to station on BSSID 64:12:25:2b:62:1f (status 0) ApVapId 1 Slot 1
*apfMsConnTask_2: Mar 19 09:44:56.623: 28:16:ad:bd:1c:cb apfProcessAssocReq (apf_80211.c:10552) Changing state for mobile 28:16:ad:bd:1c:cb on AP 64:12:25:2b:62:10 from Associated to Associated
*spamApTask2: Mar 19 09:44:56.625: 28:16:ad:bd:1c:cb Sent dot1x auth initiate message for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.625: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.625: 28:16:ad:bd:1c:cb EAP-PARAM Debug - eap-params for Wlan-Id :1 is disabled - applying Global eap timers and retries
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.625: 28:16:ad:bd:1c:cb Disable re-auth, use PMK lifetime.
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.625: 28:16:ad:bd:1c:cb dot1x - moving mobile 28:16:ad:bd:1c:cb into Connecting state
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.625: 28:16:ad:bd:1c:cb Sending EAP-Request/Identity to mobile 28:16:ad:bd:1c:cb (EAP Id 1)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb Received EAPOL START, dot1x state = 2
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb Reset the reauth counter since EAPOL START has been received!!!
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:53
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb Received EAPOL START from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb dot1x - moving mobile 28:16:ad:bd:1c:cb into Connecting state
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb Sending EAP-Request/Identity to mobile 28:16:ad:bd:1c:cb (EAP Id 2)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.670: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.676: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.676: 28:16:ad:bd:1c:cb Received EAP Response packet with mismatching id (currentid=2, eapid=1) from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.676: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb Received Identity Response (count=1) from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb Resetting reauth count 1 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb EAP State update from Connecting to Authenticating for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb dot1x - moving mobile 28:16:ad:bd:1c:cb into Authenticating state
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.681: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.690: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.690: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.690: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=3) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.690: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 3)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.690: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.690: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.693: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.693: 28:16:ad:bd:1c:cb Received EAP Response from mobile 28:16:ad:bd:1c:cb (EAP Id 3, EAP Type 25)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.693: 28:16:ad:bd:1c:cb Resetting reauth count 0 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.693: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.693: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.693: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.695: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.695: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.695: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=4) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.695: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 4)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.695: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.695: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.697: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.697: 28:16:ad:bd:1c:cb Received EAP Response from mobile 28:16:ad:bd:1c:cb (EAP Id 4, EAP Type 25)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.697: 28:16:ad:bd:1c:cb Resetting reauth count 0 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.697: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.697: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.697: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.698: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.698: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.698: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=5) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.698: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 5)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.698: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.698: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.709: 28:16:ad:bd:1c:cb Received EAPOL EAPPKT from mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.709: 28:16:ad:bd:1c:cb Received EAP Response from mobile 28:16:ad:bd:1c:cb (EAP Id 5, EAP Type 25)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.709: 28:16:ad:bd:1c:cb Resetting reauth count 0 to 0 for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.709: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.709: 28:16:ad:bd:1c:cb Entering Backend Auth Response state for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.709: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.714: 28:16:ad:bd:1c:cb Processing Access-Challenge for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.714: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.714: 28:16:ad:bd:1c:cb Entering Backend Auth Req state (id=6) for mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.714: 28:16:ad:bd:1c:cb Sending EAP Request from AAA to mobile 28:16:ad:bd:1c:cb (EAP Id 6)
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.714: 28:16:ad:bd:1c:cb Reusing allocated memory for  EAP Pkt for retransmission to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:44:56.714: 28:16:ad:bd:1c:cb reauth_sm state transition 0 ---> 0 for mobile 28:16:ad:bd:1c:cb at 1x_reauth_sm.c:71
*osapiBsnTimer: Mar 19 09:45:27.676: 28:16:ad:bd:1c:cb 802.1x 'timeoutEvt' Timer expired for station 28:16:ad:bd:1c:cb and for message = M0
*Dot1x_NW_MsgTask_3: Mar 19 09:45:27.676: 28:16:ad:bd:1c:cb Retransmit 1 of EAP-Request (length 73) for mobile 28:16:ad:bd:1c:cb
*osapiBsnTimer: Mar 19 09:45:58.680: 28:16:ad:bd:1c:cb 802.1x 'timeoutEvt' Timer expired for station 28:16:ad:bd:1c:cb and for message = M0
*Dot1x_NW_MsgTask_3: Mar 19 09:45:58.680: 28:16:ad:bd:1c:cb Retransmit 2 of EAP-Request (length 73) for mobile 28:16:ad:bd:1c:cb
*osapiBsnTimer: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb 802.1x 'timeoutEvt' Timer expired for station 28:16:ad:bd:1c:cb and for message = M0
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Retransmit failure for EAP-Request to mobile 28:16:ad:bd:1c:cb
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Sent Deauthenticate to mobile on BSSID 64:12:25:2b:62:1f slot 1(caller 1x_ptsm.c:602)
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Setting active key cache index 8 ---> 8
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Deleting the PMK cache when de-authenticating the client.
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Global PMK Cache deletion failed.
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Scheduling deletion of Mobile Station:  (callerId: 57) in 10 seconds
*Dot1x_NW_MsgTask_3: Mar 19 09:46:29.764: 28:16:ad:bd:1c:cb Freeing EAP Retransmit Bufer for mobile 28:16:ad:bd:1c:cb
*osapiBsnTimer: Mar 19 09:46:40.148: 28:16:ad:bd:1c:cb apfMsExpireCallback (apf_ms.c:638) Expiring Mobile!
*apfReceiveTask: Mar 19 09:46:40.148: 28:16:ad:bd:1c:cb apfMsExpireMobileStation (apf_ms.c:7442) Changing state for mobile 28:16:ad:bd:1c:cb on AP 64:12:25:2b:62:10 from Associated to Disassociated
*apfReceiveTask: Mar 19 09:46:40.148: 28:16:ad:bd:1c:cb Scheduling deletion of Mobile Station:  (callerId: 45) in 10 seconds
*osapiBsnTimer: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb apfMsExpireCallback (apf_ms.c:638) Expiring Mobile!
*apfReceiveTask: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb apfMsAssoStateDec
*apfReceiveTask: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb apfMsExpireMobileStation (apf_ms.c:7581) Changing state for mobile 28:16:ad:bd:1c:cb on AP 64:12:25:2b:62:10 from Disassociated to Idle
*apfReceiveTask: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb pemApfDeleteMobileStation2: APF_MS_PEM_WAIT_L2_AUTH_COMPLETE = 0.
*apfReceiveTask: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb 0.0.0.0 START (0) Deleted mobile LWAPP rule on AP [64:12:25:2b:62:10]
*apfReceiveTask: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb Deleting mobile on AP 64:12:25:2b:62:10(1) 
*spamApTask2: Mar 19 09:46:50.508: 28:16:ad:bd:1c:cb apfUpdateDeleteAckInMscb (apf_api.c:50821) Expiring Mobile!