This is the output of the user debugging. I don't see anything myself, but I'm also not an expert on reading this log.
Feb 21 09:45:06 :501093: <NOTI> |AP APNAME@x.x.x.161 stm| Auth success: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:06 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| remove_stale_sta 2953: client ec:1f:72:83:82:6d not in stale hash table
Feb 21 09:45:06 :501095: <NOTI> |AP APNAME@x.x.x.161 stm| Assoc request @ 09:45:06.896428: ec:1f:72:83:82:6d (SN 0): AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:06 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| handle_assoc_req:5568: mcast_encr(0x00000001), ucast_encr(0x00000001)
Feb 21 09:45:06 :501218: <NOTI> |AP APNAME@x.x.x.161 stm| stm_sta_assign_vlan 17773: VLAN: sta ec:1f:72:83:82:6d, STM assigns MAC based vlan_id 60
Feb 21 09:45:06 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| handle_assoc_req: STA:ec:1f:72:83:82:6d aid:0
Feb 21 09:45:06 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| handle_assoc_req:6452 XXX mac=ec:1f:72:83:82:6d reassoc(0), dot11r(0), ftie_present(0), auth_alg(0) SNR(0) max_neg_rate(866)
Feb 21 09:45:06 :501218: <4024> <NOTI> |stm| stm_sta_assign_vlan 17767: VLAN: sta ec:1f:72:83:82:6d, STM assigns MAC based vlan_id 60
Feb 21 09:45:06 :501100: <NOTI> |AP APNAME@x.x.x.161 stm| Assoc success @ 09:45:06.898319: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:06 :501065: <4024> <DBUG> |stm| a2c_sm_process_stalist:851 reassoc(0), dot11r(0), ftie_present(0), auth_alg(0)
Feb 21 09:45:06 :501100: <4024> <NOTI> |stm| Assoc success @ 09:45:06.902418: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:06 :522295: <4618> <DBUG> |authmgr| Auth GSM : USER_STA event 0 for user ec:1f:72:83:82:6d
Feb 21 09:45:06 :522035: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d Station UP: BSSID=34:fc:b9:23:2c:d0 ESSID=ATest VLAN=60 AP-name=APNAME
Feb 21 09:45:06 :522077: <4618> <DBUG> |authmgr| MAC=ec:1f:72:83:82:6d ingress 0x0x10015 (tunnel 21), u_encr 1, m_encr 1, slotport 0x0x20c0 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 0 ft_complete 0
Feb 21 09:45:06 :522264: <4618> <DBUG> |authmgr| "MAC:ec:1f:72:83:82:6d: Allocating UUID: 0x2b1820b6d9f70056
Feb 21 09:45:06 :522158: <4618> <DBUG> |authmgr| Role Derivation for user N/A-00:00:00:00:00:00- logon mac user created.
Feb 21 09:45:06 :522142: <4618> <DBUG> |authmgr| Setting default role to logon for user 00:00:00:00:00:00".
Feb 21 09:45:06 :522127: <4618> <DBUG> |authmgr| {L2} Update role from (null) to logon for IP=N/A, MAC=00:00:00:00:00:00.
Feb 21 09:45:06 :522049: <4618> <INFO> |authmgr| MAC=00:00:00:00:00:00,IP=N/A User role updated, existing Role=none/none, new Role=logon/none, reason=mac user created
Feb 21 09:45:06 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 0 derivation_type Reset VLANs for Station up index 0.
Feb 21 09:45:06 :522255: <4618> <DBUG> |authmgr| "VDR - set vlan in user for ec:1f:72:83:82:6d vlan 60 fwdmode 0 derivation_type Default VLAN.
Feb 21 09:45:06 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 60 derivation_type Default VLAN index 1.
Feb 21 09:45:06 :522255: <4618> <DBUG> |authmgr| "VDR - set vlan in user for ec:1f:72:83:82:6d vlan 60 fwdmode 0 derivation_type Current VLAN updated.
Feb 21 09:45:06 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 60 derivation_type Current VLAN updated index 2.
Feb 21 09:45:06 :522158: <4618> <DBUG> |authmgr| Role Derivation for user N/A-ec:1f:72:83:82:6d- N/A Set AAA profile defaults.
Feb 21 09:45:06 :522142: <4618> <DBUG> |authmgr| Setting default role to Test-authenticated for user ec:1f:72:83:82:6d".
Feb 21 09:45:06 :522127: <4618> <DBUG> |authmgr| {L2} Update role from logon to Test-authenticated for IP=N/A, MAC=ec:1f:72:83:82:6d.
Feb 21 09:45:06 :522049: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d,IP=N/A User role updated, existing Role=logon/none, new Role=Test-authenticated/none, reason=Set AAA profile defaults
Feb 21 09:45:06 :522246: <4618> <DBUG> |authmgr| Idle timeout should be driven by STM for MAC ec:1f:72:83:82:6d.
Feb 21 09:45:06 :524141: <4618> <DBUG> |authmgr| clr_pmkcache_ft():1013: MAC:ec:1f:72:83:82:6d BSS:34:fc:b9:23:2c:d0
Feb 21 09:45:06 :522287: <4618> <DBUG> |authmgr| Auth GSM : MAC_USER publish for mac ec:1f:72:83:82:6d bssid 34:fc:b9:23:2c:d0 vlan 60 type 1 data-ready 0
Feb 21 09:45:06 :522254: <4618> <DBUG> |authmgr| VDR - mac ec:1f:72:83:82:6d rolename Test-authenticated fwdmode 0 derivation_type Initial Role Contained vp not present.
Feb 21 09:45:06 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 0 derivation_type Reset Role Based VLANs index 3.
Feb 21 09:45:06 :522083: <4618> <DBUG> |authmgr| Skip User-Derivation, mba:0 udr_exist:0,default_role:Test-authenticated,pDefRole:0x0x1218f24
Feb 21 09:45:06 :522320: <4618> <DBUG> |authmgr| handle_sta_up_dn (2958): rtts user=ec:1f:72:83:82:6d enabled=0 initial tput=100000
Feb 21 09:45:06 :524124: <4618> <DBUG> |authmgr| dot1x_supplicant_up(): MAC:ec:1f:72:83:82:6d, pmkid_present:False, pmkid:N/A
Feb 21 09:45:06 :522255: <4618> <DBUG> |authmgr| "VDR - set vlan in user for ec:1f:72:83:82:6d vlan 60 fwdmode 0 derivation_type Current VLAN updated.
Feb 21 09:45:06 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 60 derivation_type Current VLAN updated index 4.
Feb 21 09:45:06 :522260: <4618> <DBUG> |authmgr| "VDR - Cur VLAN updated ec:1f:72:83:82:6d mob 0 inform 1 remote 0 wired 0 defvlan 60 exportedvlan 0 curvlan 60.
Feb 21 09:45:06 :522128: <4618> <DBUG> |authmgr| download-L2: acl=57/0 role=Test-authenticated, tunl=0x0x10015, PA=0, HA=1, RO=0, VPN=0 L3MOB=0.
Feb 21 09:45:06 :522050: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d,IP=N/A User data downloaded to datapath, new Role=Test-authenticated/57, bw Contract=0/0, reason=layer 2 event driven download, idle-timeout=300
Feb 21 09:45:06 :522242: <4618> <DBUG> |authmgr| MAC=ec:1f:72:83:82:6d Station Created Update MMS: BSSID=34:fc:b9:23:2c:d0 ESSID=ATest VLAN=60 AP-name=APNAME
Feb 21 09:45:06 :522301: <4618> <DBUG> |authmgr| Auth GSM : USER publish for uuid 0x2b1820b6d9f70056 mac ec:1f:72:83:82:6d name role Test-authenticated devtype wired 0 authtype 0 subtype 0 encrypt-type 0 conn-port 8384 fwd-mode 0
Feb 21 09:45:37 :501102: <NOTI> |AP APNAME@x.x.x.161 stm| Disassoc from sta: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME Reason STA has left and is disassociated
Feb 21 09:45:37 :522296: <4618> <DBUG> |authmgr| Auth GSM : USER_STA delete event for user ec:1f:72:83:82:6d age 0 deauth_reason 8
Feb 21 09:45:37 :522036: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d Station DN: BSSID=34:fc:b9:23:2c:d0 ESSID=ATest VLAN=60 AP-name=APNAME
Feb 21 09:45:37 :501000: <DBUG> |AP APNAME@x.x.x.161 stm| Station ec:1f:72:83:82:6d: Clearing state
Feb 21 09:45:37 :522234: <4618> <DBUG> |authmgr| Setting idle timer for user ec:1f:72:83:82:6d to 300 seconds (idle timeout: 300 ageout: 0).
Feb 21 09:45:37 :522152: <4618> <DBUG> |authmgr| station free: bssid=34:fc:b9:23:2c:d0, @=0x0x123a25c.
Feb 21 09:45:37 :522244: <4618> <DBUG> |authmgr| MAC=ec:1f:72:83:82:6d Station Deleted Update MMS
Feb 21 09:45:37 :522301: <4618> <DBUG> |authmgr| Auth GSM : USER publish for uuid 0x2b1820b6d9f70056 mac ec:1f:72:83:82:6d name role Test-authenticated devtype wired 0 authtype 0 subtype 0 encrypt-type 0 conn-port 8384 fwd-mode 0
Feb 21 09:45:37 :522004: <4618> <DBUG> |authmgr| ec:1f:72:83:82:6d: station datapath entry deleted
Feb 21 09:45:37 :522290: <4618> <DBUG> |authmgr| Auth GSM : MAC_USER delete for mac ec:1f:72:83:82:6d
Feb 21 09:45:37 :522303: <4618> <DBUG> |authmgr| Auth GSM : USER delete for mac ec:1f:72:83:82:6d uuid 0x2b1820b6d9f70056
Feb 21 09:45:37 :527004: <4261> <INFO> |mdns| mdns_parse_auth_useridle_message 169 Auth User Idle Timeout: MAC:ec:1f:72:83:82:6d
Feb 21 09:45:37 :527000: <4261> <DBUG> |mdns| ag_ssdp_get_token_list_for_mac 348 AirGroup user doesn't exist: mac=ec:1f:72:83:82:6d
Feb 21 09:45:37 :527000: <4261> <DBUG> |mdns| ag_mdns_get_token_list_for_mac 648 AirGroup user doesn't exist: mac=ec:1f:72:83:82:6d
Feb 21 09:45:37 :527000: <4261> <DBUG> |mdns| mdns_client_purge 1146 Purge mdns client, mac=ec:1f:72:83:82:6d, del_client = 1
Feb 21 09:45:37 :501000: <4024> <DBUG> |stm| Station ec:1f:72:83:82:6d: Clearing state
Feb 21 09:45:40 :501093: <NOTI> |AP APNAME@x.x.x.161 stm| Auth success: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:40 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| remove_stale_sta 2953: client ec:1f:72:83:82:6d not in stale hash table
Feb 21 09:45:40 :501095: <NOTI> |AP APNAME@x.x.x.161 stm| Assoc request @ 09:45:40.102380: ec:1f:72:83:82:6d (SN 0): AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:40 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| handle_assoc_req:5568: mcast_encr(0x00000001), ucast_encr(0x00000001)
Feb 21 09:45:40 :501218: <NOTI> |AP APNAME@x.x.x.161 stm| stm_sta_assign_vlan 17773: VLAN: sta ec:1f:72:83:82:6d, STM assigns MAC based vlan_id 60
Feb 21 09:45:40 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| handle_assoc_req: STA:ec:1f:72:83:82:6d aid:0
Feb 21 09:45:40 :501065: <DBUG> |AP APNAME@x.x.x.161 stm| handle_assoc_req:6452 XXX mac=ec:1f:72:83:82:6d reassoc(0), dot11r(0), ftie_present(0), auth_alg(0) SNR(0) max_neg_rate(866)
Feb 21 09:45:40 :501218: <4024> <NOTI> |stm| stm_sta_assign_vlan 17767: VLAN: sta ec:1f:72:83:82:6d, STM assigns MAC based vlan_id 60
Feb 21 09:45:40 :501100: <NOTI> |AP APNAME@x.x.x.161 stm| Assoc success @ 09:45:40.104205: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:40 :501065: <4024> <DBUG> |stm| a2c_sm_process_stalist:851 reassoc(0), dot11r(0), ftie_present(0), auth_alg(0)
Feb 21 09:45:40 :501100: <4024> <NOTI> |stm| Assoc success @ 09:45:40.105808: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME
Feb 21 09:45:40 :522295: <4618> <DBUG> |authmgr| Auth GSM : USER_STA event 0 for user ec:1f:72:83:82:6d
Feb 21 09:45:40 :522035: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d Station UP: BSSID=34:fc:b9:23:2c:d0 ESSID=ATest VLAN=60 AP-name=APNAME
Feb 21 09:45:40 :522077: <4618> <DBUG> |authmgr| MAC=ec:1f:72:83:82:6d ingress 0x0x10015 (tunnel 21), u_encr 1, m_encr 1, slotport 0x0x20c0 , type: local, FW mode: 0, AP IP: 0.0.0.0 mdie 0 ft_complete 0
Feb 21 09:45:40 :522264: <4618> <DBUG> |authmgr| "MAC:ec:1f:72:83:82:6d: Allocating UUID: 0x2b1829b6d9f70057
Feb 21 09:45:40 :522158: <4618> <DBUG> |authmgr| Role Derivation for user N/A-00:00:00:00:00:00- logon mac user created.
Feb 21 09:45:40 :522142: <4618> <DBUG> |authmgr| Setting default role to logon for user 00:00:00:00:00:00".
Feb 21 09:45:40 :522127: <4618> <DBUG> |authmgr| {L2} Update role from (null) to logon for IP=N/A, MAC=00:00:00:00:00:00.
Feb 21 09:45:40 :522049: <4618> <INFO> |authmgr| MAC=00:00:00:00:00:00,IP=N/A User role updated, existing Role=none/none, new Role=logon/none, reason=mac user created
Feb 21 09:45:40 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 0 derivation_type Reset VLANs for Station up index 0.
Feb 21 09:45:40 :522255: <4618> <DBUG> |authmgr| "VDR - set vlan in user for ec:1f:72:83:82:6d vlan 60 fwdmode 0 derivation_type Default VLAN.
Feb 21 09:45:40 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 60 derivation_type Default VLAN index 1.
Feb 21 09:45:40 :522255: <4618> <DBUG> |authmgr| "VDR - set vlan in user for ec:1f:72:83:82:6d vlan 60 fwdmode 0 derivation_type Current VLAN updated.
Feb 21 09:45:40 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 60 derivation_type Current VLAN updated index 2.
Feb 21 09:45:40 :522158: <4618> <DBUG> |authmgr| Role Derivation for user N/A-ec:1f:72:83:82:6d- N/A Set AAA profile defaults.
Feb 21 09:45:40 :522142: <4618> <DBUG> |authmgr| Setting default role to Test-authenticated for user ec:1f:72:83:82:6d".
Feb 21 09:45:40 :522127: <4618> <DBUG> |authmgr| {L2} Update role from logon to Test-authenticated for IP=N/A, MAC=ec:1f:72:83:82:6d.
Feb 21 09:45:40 :522049: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d,IP=N/A User role updated, existing Role=logon/none, new Role=Test-authenticated/none, reason=Set AAA profile defaults
Feb 21 09:45:40 :522246: <4618> <DBUG> |authmgr| Idle timeout should be driven by STM for MAC ec:1f:72:83:82:6d.
Feb 21 09:45:40 :524141: <4618> <DBUG> |authmgr| clr_pmkcache_ft():1013: MAC:ec:1f:72:83:82:6d BSS:34:fc:b9:23:2c:d0
Feb 21 09:45:40 :522287: <4618> <DBUG> |authmgr| Auth GSM : MAC_USER publish for mac ec:1f:72:83:82:6d bssid 34:fc:b9:23:2c:d0 vlan 60 type 1 data-ready 0
Feb 21 09:45:40 :522254: <4618> <DBUG> |authmgr| VDR - mac ec:1f:72:83:82:6d rolename Test-authenticated fwdmode 0 derivation_type Initial Role Contained vp not present.
Feb 21 09:45:40 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 0 derivation_type Reset Role Based VLANs index 3.
Feb 21 09:45:40 :522083: <4618> <DBUG> |authmgr| Skip User-Derivation, mba:0 udr_exist:0,default_role:Test-authenticated,pDefRole:0x0x1218f24
Feb 21 09:45:40 :522320: <4618> <DBUG> |authmgr| handle_sta_up_dn (2958): rtts user=ec:1f:72:83:82:6d enabled=0 initial tput=100000
Feb 21 09:45:40 :524124: <4618> <DBUG> |authmgr| dot1x_supplicant_up(): MAC:ec:1f:72:83:82:6d, pmkid_present:False, pmkid:N/A
Feb 21 09:45:40 :522255: <4618> <DBUG> |authmgr| "VDR - set vlan in user for ec:1f:72:83:82:6d vlan 60 fwdmode 0 derivation_type Current VLAN updated.
Feb 21 09:45:40 :522258: <4618> <DBUG> |authmgr| "VDR - Add to history of user user ec:1f:72:83:82:6d vlan 60 derivation_type Current VLAN updated index 4.
Feb 21 09:45:40 :522260: <4618> <DBUG> |authmgr| "VDR - Cur VLAN updated ec:1f:72:83:82:6d mob 0 inform 1 remote 0 wired 0 defvlan 60 exportedvlan 0 curvlan 60.
Feb 21 09:45:40 :522128: <4618> <DBUG> |authmgr| download-L2: acl=57/0 role=Test-authenticated, tunl=0x0x10015, PA=0, HA=1, RO=0, VPN=0 L3MOB=0.
Feb 21 09:45:40 :522050: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d,IP=N/A User data downloaded to datapath, new Role=Test-authenticated/57, bw Contract=0/0, reason=layer 2 event driven download, idle-timeout=300
Feb 21 09:45:40 :522242: <4618> <DBUG> |authmgr| MAC=ec:1f:72:83:82:6d Station Created Update MMS: BSSID=34:fc:b9:23:2c:d0 ESSID=ATest VLAN=60 AP-name=APNAME
Feb 21 09:45:40 :522301: <4618> <DBUG> |authmgr| Auth GSM : USER publish for uuid 0x2b1829b6d9f70057 mac ec:1f:72:83:82:6d name role Test-authenticated devtype wired 0 authtype 0 subtype 0 encrypt-type 0 conn-port 8384 fwd-mode 0
Feb 21 09:46:10 :501102: <NOTI> |AP APNAME@x.x.x.161 stm| Disassoc from sta: ec:1f:72:83:82:6d: AP x.x.x.161-34:fc:b9:23:2c:d0-APNAME Reason STA has left and is disassociated
Feb 21 09:46:10 :501000: <DBUG> |AP APNAME@x.x.x.161 stm| Station ec:1f:72:83:82:6d: Clearing state
Feb 21 09:46:10 :522296: <4618> <DBUG> |authmgr| Auth GSM : USER_STA delete event for user ec:1f:72:83:82:6d age 0 deauth_reason 8
Feb 21 09:46:10 :522036: <4618> <INFO> |authmgr| MAC=ec:1f:72:83:82:6d Station DN: BSSID=34:fc:b9:23:2c:d0 ESSID=ATest VLAN=60 AP-name=APNAME
Feb 21 09:46:10 :522234: <4618> <DBUG> |authmgr| Setting idle timer for user ec:1f:72:83:82:6d to 300 seconds (idle timeout: 300 ageout: 0).
Feb 21 09:46:10 :522152: <4618> <DBUG> |authmgr| station free: bssid=34:fc:b9:23:2c:d0, @=0x0x12b0d9c.
Feb 21 09:46:10 :522244: <4618> <DBUG> |authmgr| MAC=ec:1f:72:83:82:6d Station Deleted Update MMS
Feb 21 09:46:10 :522301: <4618> <DBUG> |authmgr| Auth GSM : USER publish for uuid 0x2b1829b6d9f70057 mac ec:1f:72:83:82:6d name role Test-authenticated devtype wired 0 authtype 0 subtype 0 encrypt-type 0 conn-port 8384 fwd-mode 0
Feb 21 09:46:10 :522004: <4618> <DBUG> |authmgr| ec:1f:72:83:82:6d: station datapath entry deleted
Feb 21 09:46:10 :522290: <4618> <DBUG> |authmgr| Auth GSM : MAC_USER delete for mac ec:1f:72:83:82:6d
Feb 21 09:46:10 :522303: <4618> <DBUG> |authmgr| Auth GSM : USER delete for mac ec:1f:72:83:82:6d uuid 0x2b1829b6d9f70057
Feb 21 09:46:10 :527004: <4261> <INFO> |mdns| mdns_parse_auth_useridle_message 169 Auth User Idle Timeout: MAC:ec:1f:72:83:82:6d
Feb 21 09:46:10 :527000: <4261> <DBUG> |mdns| ag_ssdp_get_token_list_for_mac 348 AirGroup user doesn't exist: mac=ec:1f:72:83:82:6d
Feb 21 09:46:10 :527000: <4261> <DBUG> |mdns| ag_mdns_get_token_list_for_mac 648 AirGroup user doesn't exist: mac=ec:1f:72:83:82:6d
Feb 21 09:46:10 :527000: <4261> <DBUG> |mdns| mdns_client_purge 1146 Purge mdns client, mac=ec:1f:72:83:82:6d, del_client = 1
Feb 21 09:46:10 :501000: <4024> <DBUG> |stm| Station ec:1f:72:83:82:6d: Clearing state