*************************************************************************************************** 7/5/2017 10:38:23 AM Device IP: 10.145.248.4 Device Name: MIDET005-FER-LWC-01 Command: show log user-debug all | include f0:99:bf:87:62:b6 *************************************************************************************************** Jul 5 09:49:17 :522308: |authmgr| Device Type index derivation for f0:99:bf:87:62:b6 : dhcp (0,0,0) oui (0,0) ua (4,6,35) derived iPhone(6) Jul 5 09:49:17 :522299: |authmgr| Auth GSM : DEV_ID_CACHE publish for mac f0:99:bf:87:62:b6 dev-id iPhone index 6 Jul 5 09:49:17 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 09:49:17 :522138: |authmgr| Sibyte-f0:99:bf:87:62:b6/10.145.162.152 : User-Agent: iPhone7,2/10.3.2 (14F89), final=1, index=4 stringindex=6. Jul 5 09:49:17 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:34:29 :501109: |AP 005-S1C-AP-S1-13@10.145.250.64 stm| Auth request: f0:99:bf:87:62:b6: AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 auth_alg 2 Jul 5 10:34:29 :501093: |AP 005-S1C-AP-S1-13@10.145.250.64 stm| Auth success: f0:99:bf:87:62:b6: AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 Jul 5 10:34:29 :501095: |AP 005-S1C-AP-S1-13@10.145.250.64 stm| Assoc request @ 10:34:29.752530: f0:99:bf:87:62:b6 (SN 2083): AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 Jul 5 10:34:29 :522296: |authmgr| Auth GSM : USER_STA delete event for user f0:99:bf:87:62:b6 age 0 deauth_reason 62 Jul 5 10:34:29 :522036: |authmgr| MAC=f0:99:bf:87:62:b6 Station DN: BSSID=04:bd:88:19:ca:f0 ESSID=TEST VLAN=351 AP-name=005-S1C-AP-S1-14 Jul 5 10:34:29 :522234: |authmgr| Setting idle timer for user f0:99:bf:87:62:b6 to 1200 seconds (idle timeout: 1200 ageout: 0). Jul 5 10:34:29 :501080: |stm| Deauth to sta: f0:99:bf:87:62:b6: Ageout AP 10.145.250.10-04:bd:88:19:ca:f0-005-S1C-AP-S1-14 STA has roamed to another AP Jul 5 10:34:29 :501100: |stm| Assoc success @ 10:34:29.759999: f0:99:bf:87:62:b6: AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 Jul 5 10:34:29 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:18:00 GSM: Successfully published PMK-cache object. Jul 5 10:34:29 :524140: |authmgr| add_pmkcache_ft():950: MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:18:00 Update: Jul 5 10:34:29 :501105: |AP 005-S1C-AP-S1-14@10.145.250.10 stm| Deauth from sta: f0:99:bf:87:62:b6: AP 10.145.250.10-04:bd:88:19:ca:f0-005-S1C-AP-S1-14 Reason STA has roamed to another AP Jul 5 10:34:29 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:18:00 GSM: Successfully published PMK-cache object. Jul 5 10:34:29 :501000: |AP 005-S1C-AP-S1-14@10.145.250.10 stm| Station f0:99:bf:87:62:b6: Clearing state Jul 5 10:34:29 :522295: |authmgr| Auth GSM : USER_STA event 0 for user f0:99:bf:87:62:b6 Jul 5 10:34:29 :522035: |authmgr| MAC=f0:99:bf:87:62:b6 Station UP: BSSID=94:b4:0f:0e:18:00 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-13 Jul 5 10:34:29 :522077: |authmgr| MAC=f0:99:bf:87:62:b6 ingress 0x0x12fc8 (tunnel 12232), u_encr 64, m_encr 64, slotport 0x0x2100 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 1 ft_complete 1 Jul 5 10:34:29 :522078: |authmgr| MAC=f0:99:bf:87:62:b6, wired: 0, vlan:998 ingress:0x0x12fc8 (tunnel 12232), ingress:0x0x12fc8 new_aaa_prof: AIA-dot1x-aaa_prof, stored profile: AIA-dot1x-aaa_prof stored wired: 0 stored essid: TEST, stored-ingress: 0x0x113b5 Jul 5 10:34:29 :501100: |AP 005-S1C-AP-S1-13@10.145.250.64 stm| Assoc success @ 10:34:29.756495: f0:99:bf:87:62:b6: AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 Jul 5 10:34:29 :522247: |authmgr| User idle timer removed for user with MAC f0:99:bf:87:62:b6. Jul 5 10:34:29 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset VLANs for Station up index 7. Jul 5 10:34:29 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Default VLAN. Jul 5 10:34:29 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Default VLAN index 8. Jul 5 10:34:29 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Current VLAN updated. Jul 5 10:34:29 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Current VLAN updated index 9. Jul 5 10:34:29 :522246: |authmgr| Idle timeout should be driven by STM for MAC f0:99:bf:87:62:b6. Jul 5 10:34:29 :522287: |authmgr| Auth GSM : MAC_USER publish for mac f0:99:bf:87:62:b6 bssid 94:b4:0f:0e:18:00 vlan 998 type 1 data-ready 0 Jul 5 10:34:29 :522254: |authmgr| VDR - mac f0:99:bf:87:62:b6 rolename logon fwdmode 0 derivation_type Initial Role Contained vp not present. Jul 5 10:34:29 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset Role Based VLANs index 10. Jul 5 10:34:29 :524124: |authmgr| dot1x_supplicant_up(): MAC:f0:99:bf:87:62:b6, pmkid_present:True, pmkid:78 c8 0c 9a db b9 86 36 5a 68 5a a6 9f 60 30 a2 Jul 5 10:34:29 :522243: |authmgr| MAC=f0:99:bf:87:62:b6 Station Updated Update MMS: BSSID=94:b4:0f:0e:18:00 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-13 Jul 5 10:34:29 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:34:31 :527000: |mdns| mdns_parse_auth_userapname_message 448 Auth->MDNS User APNAME: MAC:f0:99:bf:87:62:b6, NEW AP NAME:005-S1C-AP-S1-13 Jul 5 10:34:35 :522026: |authmgr| MAC=f0:99:bf:87:62:b6 IP=10.61.101.250 User miss: ingress=0x12fc8, VLAN=998 flags=0xc040 Jul 5 10:34:35 :522006: |authmgr| MAC=f0:99:bf:87:62:b6 IP=10.61.101.250 User entry added: reason=Sibtye Jul 5 10:34:35 :522050: |authmgr| MAC=f0:99:bf:87:62:b6,IP=10.61.101.250 User data downloaded to datapath, new Role=TEST-user/71, bw Contract=0/0, reason=New user IP processing, idle-timeout=1200 Jul 5 10:34:35 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:34:36 :527004: |mdns| mdns_parse_auth_useradd_message 226 Auth User ADD: MAC:f0:99:bf:87:62:b6, IP:10.61.101.250, VLAN:998, Role:TEST-user Name:testuser APName:005-S1C-AP-S1-13 Type:1. Groups: Jul 5 10:34:36 :527000: |mdns| mdns_auth_userinfo_req_message 341 mac(f0:99:bf:87:62:b6), ip(10.61.101.250) Jul 5 10:34:41 :527000: |mdns| mdns_parse_userinfo 372 UserInfo resp=1 ip=10.61.101.250, mac=f0:99:bf:87:62:b6, apname=005-S1C-AP-S1-13, role=TEST-user, username=testuser, vlan=998 Jul 5 10:34:41 :527000: |mdns| mdns_parse_auth_userinfo_resp_message 397 UserInfo response completed for ip=10.61.101.250 mac=f0:99:bf:87:62:b6 Jul 5 10:34:48 :501109: |AP 005-S1C-AP-S1-09@10.145.250.54 stm| Auth request: f0:99:bf:87:62:b6: AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 auth_alg 2 Jul 5 10:34:48 :501093: |AP 005-S1C-AP-S1-09@10.145.250.54 stm| Auth success: f0:99:bf:87:62:b6: AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 Jul 5 10:34:48 :501095: |AP 005-S1C-AP-S1-09@10.145.250.54 stm| Assoc request @ 10:34:48.157912: f0:99:bf:87:62:b6 (SN 2164): AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 Jul 5 10:34:48 :522296: |authmgr| Auth GSM : USER_STA delete event for user f0:99:bf:87:62:b6 age 0 deauth_reason 62 Jul 5 10:34:48 :522036: |authmgr| MAC=f0:99:bf:87:62:b6 Station DN: BSSID=94:b4:0f:0e:18:00 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-13 Jul 5 10:34:48 :522234: |authmgr| Setting idle timer for user f0:99:bf:87:62:b6 to 1200 seconds (idle timeout: 1200 ageout: 0). Jul 5 10:34:48 :501080: |stm| Deauth to sta: f0:99:bf:87:62:b6: Ageout AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 STA has roamed to another AP Jul 5 10:34:48 :501100: |stm| Assoc success @ 10:34:48.162446: f0:99:bf:87:62:b6: AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 Jul 5 10:34:48 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:04:bd:88:17:ab:90 GSM: Successfully published PMK-cache object. Jul 5 10:34:48 :524140: |authmgr| add_pmkcache_ft():950: MAC:f0:99:bf:87:62:b6 BSS:04:bd:88:17:ab:90 Update: Jul 5 10:34:48 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:04:bd:88:17:ab:90 GSM: Successfully published PMK-cache object. Jul 5 10:34:48 :522295: |authmgr| Auth GSM : USER_STA event 0 for user f0:99:bf:87:62:b6 Jul 5 10:34:48 :501105: |AP 005-S1C-AP-S1-13@10.145.250.64 stm| Deauth from sta: f0:99:bf:87:62:b6: AP 10.145.250.64-94:b4:0f:0e:18:00-005-S1C-AP-S1-13 Reason STA has roamed to another AP Jul 5 10:34:48 :522035: |authmgr| MAC=f0:99:bf:87:62:b6 Station UP: BSSID=04:bd:88:17:ab:90 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-09 Jul 5 10:34:48 :501000: |AP 005-S1C-AP-S1-13@10.145.250.64 stm| Station f0:99:bf:87:62:b6: Clearing state Jul 5 10:34:48 :522077: |authmgr| MAC=f0:99:bf:87:62:b6 ingress 0x0x1185f (tunnel 6239), u_encr 64, m_encr 64, slotport 0x0x2100 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 1 ft_complete 1 Jul 5 10:34:48 :522078: |authmgr| MAC=f0:99:bf:87:62:b6, wired: 0, vlan:998 ingress:0x0x1185f (tunnel 6239), ingress:0x0x1185f new_aaa_prof: AIA-dot1x-aaa_prof, stored profile: AIA-dot1x-aaa_prof stored wired: 0 stored essid: TEST, stored-ingress: 0x0x12fc8 Jul 5 10:34:48 :501100: |AP 005-S1C-AP-S1-09@10.145.250.54 stm| Assoc success @ 10:34:48.161817: f0:99:bf:87:62:b6: AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 Jul 5 10:34:48 :522247: |authmgr| User idle timer removed for user with MAC f0:99:bf:87:62:b6. Jul 5 10:34:48 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset VLANs for Station up index 11. Jul 5 10:34:48 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Default VLAN. Jul 5 10:34:48 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Default VLAN index 12. Jul 5 10:34:48 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Current VLAN updated. Jul 5 10:34:48 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Current VLAN updated index 13. Jul 5 10:34:48 :522246: |authmgr| Idle timeout should be driven by STM for MAC f0:99:bf:87:62:b6. Jul 5 10:34:48 :522287: |authmgr| Auth GSM : MAC_USER publish for mac f0:99:bf:87:62:b6 bssid 04:bd:88:17:ab:90 vlan 998 type 1 data-ready 0 Jul 5 10:34:48 :522254: |authmgr| VDR - mac f0:99:bf:87:62:b6 rolename logon fwdmode 0 derivation_type Initial Role Contained vp not present. Jul 5 10:34:48 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset Role Based VLANs index 14. Jul 5 10:34:48 :524124: |authmgr| dot1x_supplicant_up(): MAC:f0:99:bf:87:62:b6, pmkid_present:True, pmkid:09 ef f4 11 3a 8c 6c 22 5d 49 f5 1d 70 84 bd 6e Jul 5 10:34:48 :522243: |authmgr| MAC=f0:99:bf:87:62:b6 Station Updated Update MMS: BSSID=04:bd:88:17:ab:90 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-09 Jul 5 10:34:48 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:34:51 :527000: |mdns| mdns_parse_auth_userapname_message 448 Auth->MDNS User APNAME: MAC:f0:99:bf:87:62:b6, NEW AP NAME:005-S1C-AP-S1-09 Jul 5 10:34:59 :501109: |AP 005-S1C-AP-S1-01@10.145.250.22 stm| Auth request: f0:99:bf:87:62:b6: AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 auth_alg 2 Jul 5 10:34:59 :501093: |AP 005-S1C-AP-S1-01@10.145.250.22 stm| Auth success: f0:99:bf:87:62:b6: AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 Jul 5 10:34:59 :501095: |AP 005-S1C-AP-S1-01@10.145.250.22 stm| Assoc request @ 10:34:59.757606: f0:99:bf:87:62:b6 (SN 2193): AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 Jul 5 10:34:59 :522296: |authmgr| Auth GSM : USER_STA delete event for user f0:99:bf:87:62:b6 age 0 deauth_reason 62 Jul 5 10:34:59 :522036: |authmgr| MAC=f0:99:bf:87:62:b6 Station DN: BSSID=04:bd:88:17:ab:90 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-09 Jul 5 10:34:59 :522234: |authmgr| Setting idle timer for user f0:99:bf:87:62:b6 to 1200 seconds (idle timeout: 1200 ageout: 0). Jul 5 10:34:59 :501080: |stm| Deauth to sta: f0:99:bf:87:62:b6: Ageout AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 STA has roamed to another AP Jul 5 10:34:59 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:09:b0 GSM: Successfully published PMK-cache object. Jul 5 10:34:59 :501100: |stm| Assoc success @ 10:34:59.761099: f0:99:bf:87:62:b6: AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 Jul 5 10:34:59 :524140: |authmgr| add_pmkcache_ft():950: MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:09:b0 Update: Jul 5 10:34:59 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:09:b0 GSM: Successfully published PMK-cache object. Jul 5 10:34:59 :522295: |authmgr| Auth GSM : USER_STA event 0 for user f0:99:bf:87:62:b6 Jul 5 10:34:59 :522035: |authmgr| MAC=f0:99:bf:87:62:b6 Station UP: BSSID=94:b4:0f:0e:09:b0 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-01 Jul 5 10:34:59 :501105: |AP 005-S1C-AP-S1-09@10.145.250.54 stm| Deauth from sta: f0:99:bf:87:62:b6: AP 10.145.250.54-04:bd:88:17:ab:90-005-S1C-AP-S1-09 Reason STA has roamed to another AP Jul 5 10:34:59 :522077: |authmgr| MAC=f0:99:bf:87:62:b6 ingress 0x0x12d4e (tunnel 11598), u_encr 64, m_encr 64, slotport 0x0x2100 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 1 ft_complete 1 Jul 5 10:34:59 :501000: |AP 005-S1C-AP-S1-09@10.145.250.54 stm| Station f0:99:bf:87:62:b6: Clearing state Jul 5 10:34:59 :522078: |authmgr| MAC=f0:99:bf:87:62:b6, wired: 0, vlan:998 ingress:0x0x12d4e (tunnel 11598), ingress:0x0x12d4e new_aaa_prof: AIA-dot1x-aaa_prof, stored profile: AIA-dot1x-aaa_prof stored wired: 0 stored essid: TEST, stored-ingress: 0x0x1185f Jul 5 10:34:59 :501100: |AP 005-S1C-AP-S1-01@10.145.250.22 stm| Assoc success @ 10:34:59.761257: f0:99:bf:87:62:b6: AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 Jul 5 10:34:59 :522247: |authmgr| User idle timer removed for user with MAC f0:99:bf:87:62:b6. Jul 5 10:34:59 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset VLANs for Station up index 15. Jul 5 10:34:59 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Default VLAN. Jul 5 10:34:59 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Default VLAN index 16. Jul 5 10:34:59 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Current VLAN updated. Jul 5 10:34:59 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Current VLAN updated index 17. Jul 5 10:34:59 :522246: |authmgr| Idle timeout should be driven by STM for MAC f0:99:bf:87:62:b6. Jul 5 10:34:59 :522287: |authmgr| Auth GSM : MAC_USER publish for mac f0:99:bf:87:62:b6 bssid 94:b4:0f:0e:09:b0 vlan 998 type 1 data-ready 0 Jul 5 10:34:59 :522254: |authmgr| VDR - mac f0:99:bf:87:62:b6 rolename logon fwdmode 0 derivation_type Initial Role Contained vp not present. Jul 5 10:34:59 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset Role Based VLANs index 18. Jul 5 10:34:59 :524124: |authmgr| dot1x_supplicant_up(): MAC:f0:99:bf:87:62:b6, pmkid_present:True, pmkid:96 17 e9 ca 74 28 32 d8 52 87 fa 71 e7 0f 74 20 Jul 5 10:34:59 :522243: |authmgr| MAC=f0:99:bf:87:62:b6 Station Updated Update MMS: BSSID=94:b4:0f:0e:09:b0 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-01 Jul 5 10:34:59 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:35:04 :527000: |mdns| mdns_parse_auth_userapname_message 448 Auth->MDNS User APNAME: MAC:f0:99:bf:87:62:b6, NEW AP NAME:005-S1C-AP-S1-01 Jul 5 10:35:46 :501109: |AP 005-S1C-AP-S1-03@10.145.250.37 stm| Auth request: f0:99:bf:87:62:b6: AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 auth_alg 2 Jul 5 10:35:46 :501093: |AP 005-S1C-AP-S1-03@10.145.250.37 stm| Auth success: f0:99:bf:87:62:b6: AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 Jul 5 10:35:46 :501095: |AP 005-S1C-AP-S1-03@10.145.250.37 stm| Assoc request @ 10:35:46.903244: f0:99:bf:87:62:b6 (SN 2387): AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 Jul 5 10:35:46 :522296: |authmgr| Auth GSM : USER_STA delete event for user f0:99:bf:87:62:b6 age 0 deauth_reason 62 Jul 5 10:35:46 :522036: |authmgr| MAC=f0:99:bf:87:62:b6 Station DN: BSSID=94:b4:0f:0e:09:b0 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-01 Jul 5 10:35:46 :522234: |authmgr| Setting idle timer for user f0:99:bf:87:62:b6 to 1200 seconds (idle timeout: 1200 ageout: 0). Jul 5 10:35:46 :501080: |stm| Deauth to sta: f0:99:bf:87:62:b6: Ageout AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 STA has roamed to another AP Jul 5 10:35:46 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:04:bd:88:19:a1:b0 GSM: Successfully published PMK-cache object. Jul 5 10:35:46 :524140: |authmgr| add_pmkcache_ft():950: MAC:f0:99:bf:87:62:b6 BSS:04:bd:88:19:a1:b0 Update: Jul 5 10:35:46 :501105: |AP 005-S1C-AP-S1-01@10.145.250.22 stm| Deauth from sta: f0:99:bf:87:62:b6: AP 10.145.250.22-94:b4:0f:0e:09:b0-005-S1C-AP-S1-01 Reason STA has roamed to another AP Jul 5 10:35:46 :501100: |stm| Assoc success @ 10:35:46.907604: f0:99:bf:87:62:b6: AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 Jul 5 10:35:46 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:04:bd:88:19:a1:b0 GSM: Successfully published PMK-cache object. Jul 5 10:35:46 :501000: |AP 005-S1C-AP-S1-01@10.145.250.22 stm| Station f0:99:bf:87:62:b6: Clearing state Jul 5 10:35:46 :522295: |authmgr| Auth GSM : USER_STA event 0 for user f0:99:bf:87:62:b6 Jul 5 10:35:46 :501100: |AP 005-S1C-AP-S1-03@10.145.250.37 stm| Assoc success @ 10:35:46.906641: f0:99:bf:87:62:b6: AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 Jul 5 10:35:46 :522035: |authmgr| MAC=f0:99:bf:87:62:b6 Station UP: BSSID=04:bd:88:19:a1:b0 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-03 Jul 5 10:35:46 :522077: |authmgr| MAC=f0:99:bf:87:62:b6 ingress 0x0x11d13 (tunnel 7443), u_encr 64, m_encr 64, slotport 0x0x2100 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 1 ft_complete 1 Jul 5 10:35:46 :522078: |authmgr| MAC=f0:99:bf:87:62:b6, wired: 0, vlan:998 ingress:0x0x11d13 (tunnel 7443), ingress:0x0x11d13 new_aaa_prof: AIA-dot1x-aaa_prof, stored profile: AIA-dot1x-aaa_prof stored wired: 0 stored essid: TEST, stored-ingress: 0x0x12d4e Jul 5 10:35:46 :522247: |authmgr| User idle timer removed for user with MAC f0:99:bf:87:62:b6. Jul 5 10:35:46 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset VLANs for Station up index 19. Jul 5 10:35:46 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Default VLAN. Jul 5 10:35:46 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Default VLAN index 20. Jul 5 10:35:46 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Current VLAN updated. Jul 5 10:35:46 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Current VLAN updated index 21. Jul 5 10:35:46 :522246: |authmgr| Idle timeout should be driven by STM for MAC f0:99:bf:87:62:b6. Jul 5 10:35:46 :522287: |authmgr| Auth GSM : MAC_USER publish for mac f0:99:bf:87:62:b6 bssid 04:bd:88:19:a1:b0 vlan 998 type 1 data-ready 0 Jul 5 10:35:46 :522254: |authmgr| VDR - mac f0:99:bf:87:62:b6 rolename logon fwdmode 0 derivation_type Initial Role Contained vp not present. Jul 5 10:35:46 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset Role Based VLANs index 22. Jul 5 10:35:46 :524124: |authmgr| dot1x_supplicant_up(): MAC:f0:99:bf:87:62:b6, pmkid_present:True, pmkid:c9 fb a2 13 ce f3 01 c4 a7 60 db 13 04 c5 4b b0 Jul 5 10:35:46 :522243: |authmgr| MAC=f0:99:bf:87:62:b6 Station Updated Update MMS: BSSID=04:bd:88:19:a1:b0 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-03 Jul 5 10:35:46 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:35:49 :527000: |mdns| mdns_parse_auth_userapname_message 448 Auth->MDNS User APNAME: MAC:f0:99:bf:87:62:b6, NEW AP NAME:005-S1C-AP-S1-03 Jul 5 10:35:52 :522026: |authmgr| MAC=f0:99:bf:87:62:b6 IP=10.61.100.250 User miss: ingress=0x11d13, VLAN=998 flags=0x8040 Jul 5 10:35:52 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:35:52 :522005: |authmgr| MAC=f0:99:bf:87:62:b6 IP=10.145.162.152 User entry deleted: reason=unknown Jul 5 10:35:52 :522006: |authmgr| MAC=f0:99:bf:87:62:b6 IP=10.61.100.250 User entry added: reason=Sibtye Jul 5 10:35:52 :522050: |authmgr| MAC=f0:99:bf:87:62:b6,IP=10.61.100.250 User data downloaded to datapath, new Role=TEST-user/71, bw Contract=0/0, reason=New user IP processing, idle-timeout=1200 Jul 5 10:35:52 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:35:54 :527004: |mdns| mdns_parse_auth_useradd_message 226 Auth User ADD: MAC:f0:99:bf:87:62:b6, IP:10.61.100.250, VLAN:998, Role:TEST-user Name:testuser APName:005-S1C-AP-S1-03 Type:1. Groups: Jul 5 10:35:54 :527000: |mdns| mdns_auth_userinfo_req_message 341 mac(f0:99:bf:87:62:b6), ip(10.61.100.250) Jul 5 10:35:55 :501109: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Auth request: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:50-005-S1C-AP-S1-04 auth_alg 2 Jul 5 10:35:55 :501093: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Auth success: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:50-005-S1C-AP-S1-04 Jul 5 10:35:55 :501095: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Assoc request @ 10:35:55.659657: f0:99:bf:87:62:b6 (SN 2445): AP 10.145.250.38-94:b4:0f:0e:30:50-005-S1C-AP-S1-04 Jul 5 10:35:55 :522296: |authmgr| Auth GSM : USER_STA delete event for user f0:99:bf:87:62:b6 age 0 deauth_reason 62 Jul 5 10:35:55 :522036: |authmgr| MAC=f0:99:bf:87:62:b6 Station DN: BSSID=04:bd:88:19:a1:b0 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-03 Jul 5 10:35:55 :522234: |authmgr| Setting idle timer for user f0:99:bf:87:62:b6 to 1200 seconds (idle timeout: 1200 ageout: 0). Jul 5 10:35:55 :501080: |stm| Deauth to sta: f0:99:bf:87:62:b6: Ageout AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 STA has roamed to another AP Jul 5 10:35:55 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:30:50 GSM: Successfully published PMK-cache object. Jul 5 10:35:55 :524140: |authmgr| add_pmkcache_ft():950: MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:30:50 Update: Jul 5 10:35:55 :501100: |stm| Assoc success @ 10:35:55.663195: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:50-005-S1C-AP-S1-04 Jul 5 10:35:55 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:30:50 GSM: Successfully published PMK-cache object. Jul 5 10:35:55 :522295: |authmgr| Auth GSM : USER_STA event 0 for user f0:99:bf:87:62:b6 Jul 5 10:35:55 :501105: |AP 005-S1C-AP-S1-03@10.145.250.37 stm| Deauth from sta: f0:99:bf:87:62:b6: AP 10.145.250.37-04:bd:88:19:a1:b0-005-S1C-AP-S1-03 Reason STA has roamed to another AP Jul 5 10:35:55 :522035: |authmgr| MAC=f0:99:bf:87:62:b6 Station UP: BSSID=94:b4:0f:0e:30:50 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-04 Jul 5 10:35:55 :501000: |AP 005-S1C-AP-S1-03@10.145.250.37 stm| Station f0:99:bf:87:62:b6: Clearing state Jul 5 10:35:55 :522077: |authmgr| MAC=f0:99:bf:87:62:b6 ingress 0x0x1112b (tunnel 4395), u_encr 64, m_encr 64, slotport 0x0x2100 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 1 ft_complete 1 Jul 5 10:35:55 :501100: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Assoc success @ 10:35:55.663415: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:50-005-S1C-AP-S1-04 Jul 5 10:35:55 :522078: |authmgr| MAC=f0:99:bf:87:62:b6, wired: 0, vlan:998 ingress:0x0x1112b (tunnel 4395), ingress:0x0x1112b new_aaa_prof: AIA-dot1x-aaa_prof, stored profile: AIA-dot1x-aaa_prof stored wired: 0 stored essid: TEST, stored-ingress: 0x0x11d13 Jul 5 10:35:55 :522247: |authmgr| User idle timer removed for user with MAC f0:99:bf:87:62:b6. Jul 5 10:35:55 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset VLANs for Station up index 23. Jul 5 10:35:55 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Default VLAN. Jul 5 10:35:55 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Default VLAN index 24. Jul 5 10:35:55 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Current VLAN updated. Jul 5 10:35:55 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Current VLAN updated index 25. Jul 5 10:35:55 :522246: |authmgr| Idle timeout should be driven by STM for MAC f0:99:bf:87:62:b6. Jul 5 10:35:55 :522287: |authmgr| Auth GSM : MAC_USER publish for mac f0:99:bf:87:62:b6 bssid 94:b4:0f:0e:30:50 vlan 998 type 1 data-ready 0 Jul 5 10:35:55 :522254: |authmgr| VDR - mac f0:99:bf:87:62:b6 rolename logon fwdmode 0 derivation_type Initial Role Contained vp not present. Jul 5 10:35:55 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset Role Based VLANs index 26. Jul 5 10:35:55 :524124: |authmgr| dot1x_supplicant_up(): MAC:f0:99:bf:87:62:b6, pmkid_present:True, pmkid:63 e1 4a 69 a7 15 18 b0 86 3c ed f0 29 5a 8b b3 Jul 5 10:35:55 :522243: |authmgr| MAC=f0:99:bf:87:62:b6 Station Updated Update MMS: BSSID=94:b4:0f:0e:30:50 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-04 Jul 5 10:35:55 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:35:59 :527000: |mdns| mdns_parse_userinfo 372 UserInfo resp=1 ip=10.61.100.250, mac=f0:99:bf:87:62:b6, apname=005-S1C-AP-S1-03, role=TEST-user, username=testuser, vlan=998 Jul 5 10:35:59 :527000: |mdns| mdns_parse_auth_userinfo_resp_message 397 UserInfo response completed for ip=10.61.100.250 mac=f0:99:bf:87:62:b6 Jul 5 10:35:59 :527000: |mdns| mdns_parse_auth_userapname_message 448 Auth->MDNS User APNAME: MAC:f0:99:bf:87:62:b6, NEW AP NAME:005-S1C-AP-S1-04 Jul 5 10:36:22 :501109: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Auth request: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:40-005-S1C-AP-S1-04 auth_alg 2 Jul 5 10:36:22 :501093: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Auth success: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:40-005-S1C-AP-S1-04 Jul 5 10:36:22 :501095: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Assoc request @ 10:36:22.144103: f0:99:bf:87:62:b6 (SN 2563): AP 10.145.250.38-94:b4:0f:0e:30:40-005-S1C-AP-S1-04 Jul 5 10:36:22 :522296: |authmgr| Auth GSM : USER_STA delete event for user f0:99:bf:87:62:b6 age 0 deauth_reason 62 Jul 5 10:36:22 :501080: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Deauth to sta: f0:99:bf:87:62:b6: Ageout AP 10.145.250.38-94:b4:0f:0e:30:50-005-S1C-AP-S1-04 STA has roamed to another AP Jul 5 10:36:22 :522036: |authmgr| MAC=f0:99:bf:87:62:b6 Station DN: BSSID=94:b4:0f:0e:30:50 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-04 Jul 5 10:36:22 :522234: |authmgr| Setting idle timer for user f0:99:bf:87:62:b6 to 1200 seconds (idle timeout: 1200 ageout: 0). Jul 5 10:36:22 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:30:40 GSM: Successfully published PMK-cache object. Jul 5 10:36:22 :524140: |authmgr| add_pmkcache_ft():950: MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:30:40 Update: Jul 5 10:36:22 :501100: |stm| Assoc success @ 10:36:22.147860: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:40-005-S1C-AP-S1-04 Jul 5 10:36:22 :524134: |authmgr| dot1x_gsm_set_pmkcache(): MAC:f0:99:bf:87:62:b6 BSS:94:b4:0f:0e:30:40 GSM: Successfully published PMK-cache object. Jul 5 10:36:22 :522295: |authmgr| Auth GSM : USER_STA event 0 for user f0:99:bf:87:62:b6 Jul 5 10:36:22 :522035: |authmgr| MAC=f0:99:bf:87:62:b6 Station UP: BSSID=94:b4:0f:0e:30:40 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-04 Jul 5 10:36:22 :522077: |authmgr| MAC=f0:99:bf:87:62:b6 ingress 0x0x120e4 (tunnel 8420), u_encr 64, m_encr 64, slotport 0x0x2100 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 1 ft_complete 1 Jul 5 10:36:22 :522078: |authmgr| MAC=f0:99:bf:87:62:b6, wired: 0, vlan:998 ingress:0x0x120e4 (tunnel 8420), ingress:0x0x120e4 new_aaa_prof: AIA-dot1x-aaa_prof, stored profile: AIA-dot1x-aaa_prof stored wired: 0 stored essid: TEST, stored-ingress: 0x0x1112b Jul 5 10:36:22 :522247: |authmgr| User idle timer removed for user with MAC f0:99:bf:87:62:b6. Jul 5 10:36:22 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset VLANs for Station up index 27. Jul 5 10:36:22 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Default VLAN. Jul 5 10:36:22 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Default VLAN index 28. Jul 5 10:36:22 :522255: |authmgr| "VDR - set vlan in user for f0:99:bf:87:62:b6 vlan 998 fwdmode 0 derivation_type Current VLAN updated. Jul 5 10:36:22 :501100: |AP 005-S1C-AP-S1-04@10.145.250.38 stm| Assoc success @ 10:36:22.147853: f0:99:bf:87:62:b6: AP 10.145.250.38-94:b4:0f:0e:30:40-005-S1C-AP-S1-04 Jul 5 10:36:22 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 998 derivation_type Current VLAN updated index 29. Jul 5 10:36:22 :522246: |authmgr| Idle timeout should be driven by STM for MAC f0:99:bf:87:62:b6. Jul 5 10:36:22 :522287: |authmgr| Auth GSM : MAC_USER publish for mac f0:99:bf:87:62:b6 bssid 94:b4:0f:0e:30:40 vlan 998 type 1 data-ready 0 Jul 5 10:36:22 :522254: |authmgr| VDR - mac f0:99:bf:87:62:b6 rolename logon fwdmode 0 derivation_type Initial Role Contained vp not present. Jul 5 10:36:22 :522258: |authmgr| "VDR - Add to history of user user f0:99:bf:87:62:b6 vlan 0 derivation_type Reset Role Based VLANs index 30. Jul 5 10:36:22 :524124: |authmgr| dot1x_supplicant_up(): MAC:f0:99:bf:87:62:b6, pmkid_present:True, pmkid:71 ea d1 d0 e1 8a 14 da 48 06 8b 60 fb d8 f0 c4 Jul 5 10:36:22 :522243: |authmgr| MAC=f0:99:bf:87:62:b6 Station Updated Update MMS: BSSID=94:b4:0f:0e:30:40 ESSID=TEST VLAN=998 AP-name=005-S1C-AP-S1-04 Jul 5 10:36:22 :522301: |authmgr| Auth GSM : USER publish for uuid 3550 mac f0:99:bf:87:62:b6 name testuser role TEST-user devtype iPhone wired 0 authtype 4 subtype 9 encrypt-type 10 conn-port 8448 fwd-mode 0 Jul 5 10:36:24 :527000: |mdns| mdns_parse_auth_userapname_message 448 Auth->MDNS User APNAME: MAC:f0:99:bf:87:62:b6, NEW AP NAME:005-S1C-AP-S1-04