Tue Jul 10 18:04:02 2018 : Info: Debugger not attached

Tue Jul 10 18:04:03 2018 : Info: [etsnac connection_mgr] Successfully connected to port: 1300 on server 127.0.0.1 from client port: 0

Tue Jul 10 18:04:03 2018 : Info: [etsnac server_process] Resetting diagnostics (debug_level: 1, rad_debug_lvl: 3, fr_debug_lvl: 3, log_serialization: disabled, log_mutex: disabled, log_nrm_cleanup: disabled, log_pword: disabled, log_auth: disabled, log_auth_goodpass: disabled, log_auth_badpass: disabled

Tue Jul 10 18:04:03 2018 : Info: [etsnac server_process] Resetting response attr count to: 3

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] response attr (1): 11 - Filter-Id

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] response attr (2): 63 - Login-LAT-Port

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] response attr (3): 6 - Service-Type

Tue Jul 10 18:04:03 2018 : Info: [etsnac server_process] Resetting remove attr count to: 4

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] remove attr (1): 57 - MS-Extended-Quarantine-State

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] remove attr (2): 50 - MS-Machine-Name

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] remove attr (3): 45 - MS-Quarantine-State

Tue Jul 10 18:04:03 2018 : Debug: [etsnac server_process] remove attr (4): 54 - MS-RNAP-Not-Quarantine-Capable

Tue Jul 10 18:04:03 2018 : Info: [etsnac server_process] Resetting proxy attr count to: 0

Tue Jul 10 18:04:03 2018 : Debug: [etsnac connection_mgr] Releasing authentication server connection ID: 31.

Tue Jul 10 18:04:03 2018 : Info: [etsnac] Starting Extreme Networks NAC module version: 10, Feb 14 2018 13:45:46

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "expiration" from file /opt/nac/radius/raddb/mods-enabled/expiration

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "attr_filter.post-proxy" from file /opt/nac/radius/raddb/mods-enabled/attr_filter

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/attr_filter/post-proxy

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "attr_filter.pre-proxy" from file /opt/nac/radius/raddb/mods-enabled/attr_filter

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/attr_filter/pre-proxy

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "attr_filter.access_reject" from file /opt/nac/radius/raddb/mods-enabled/attr_filter

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/attr_filter/access_reject

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "attr_filter.access_challenge" from file /opt/nac/radius/raddb/mods-enabled/attr_filter

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/attr_filter/access_challenge

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "attr_filter.accounting_response" from file /opt/nac/radius/raddb/mods-enabled/attr_filter

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/attr_filter/accounting_response

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "pap" from file /opt/nac/radius/raddb/mods-enabled/pap

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "preprocess" from file /opt/nac/radius/raddb/mods-enabled/preprocess

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/preprocess/huntgroups

Tue Jul 10 18:04:03 2018 : Debug: reading pairlist file /opt/nac/radius/raddb/mods-config/preprocess/hints

Tue Jul 10 18:04:03 2018 : Debug: # Instantiating module "mschap" from file /opt/nac/radius/raddb/mods-enabled/mschap

Tue Jul 10 18:04:03 2018 : Debug: rlm_mschap (mschap): authenticating by calling 'ntlm_auth'

Tue Jul 10 18:04:03 2018 : Debug: } # modules

Tue Jul 10 18:04:03 2018 : Debug: radiusd: #### Loading Virtual Servers ####

Tue Jul 10 18:04:03 2018 : Debug: server { # from file /opt/nac/radius/raddb/radiusd.conf

Tue Jul 10 18:04:03 2018 : Debug: } # server

Tue Jul 10 18:04:03 2018 : Debug: server nac-server { # from file /opt/nac/radius/raddb/sites-enabled/nac-server

Tue Jul 10 18:04:03 2018 : Debug: # Loading authenticate {...}

Tue Jul 10 18:04:03 2018 : Debug: eap

Tue Jul 10 18:04:03 2018 : Debug: # Loading authorize {...}

Tue Jul 10 18:04:03 2018 : Warning: /opt/nac/radius/raddb/sites-enabled/nac-server[44]: Please change attribute reference to '&Load-Balance-Key = ...'

Tue Jul 10 18:04:03 2018 : Debug: update {

Tue Jul 10 18:04:03 2018 : Debug: &control:Load-Balance-Key = "%{Calling-Station-Id}"

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: etsnac

Tue Jul 10 18:04:03 2018 : Debug: chap

Tue Jul 10 18:04:03 2018 : Debug: mschap

Tue Jul 10 18:04:03 2018 : Debug: eap

Tue Jul 10 18:04:03 2018 : Debug: pap

Tue Jul 10 18:04:03 2018 : Debug: # Loading preacct {...}

Tue Jul 10 18:04:03 2018 : Warning: /opt/nac/radius/raddb/policy.d/accounting[37]: Please change attribute reference to '&Acct-Unique-Session-Id := ...'

Tue Jul 10 18:04:03 2018 : Warning: /opt/nac/radius/raddb/policy.d/accounting[49]: Please change attribute reference to '&Acct-Unique-Session-Id := ...'

Tue Jul 10 18:04:03 2018 : Debug: policy acct_unique {

Tue Jul 10 18:04:03 2018 : Debug: if ("%{string:Class}" =~ /ai:([0-9a-f]{32})/) {

Tue Jul 10 18:04:03 2018 : Debug: update {

Tue Jul 10 18:04:03 2018 : Debug: &Acct-Unique-Session-Id := "%{md5:%{1},%{Acct-Session-ID}}"

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: else {

Tue Jul 10 18:04:03 2018 : Debug: update {

Tue Jul 10 18:04:03 2018 : Debug: &Acct-Unique-Session-Id := "%{md5:%{User-Name},%{Acct-Session-ID},%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}},%{NAS-Identifier},%{NAS-Port-ID},%{NAS-Port}}"

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: etsnac

Tue Jul 10 18:04:03 2018 : Debug: # Loading accounting {...}

Tue Jul 10 18:04:03 2018 : Debug: etsnac

Tue Jul 10 18:04:03 2018 : Debug: # Loading session {...}

Tue Jul 10 18:04:03 2018 : Debug: radutmp

Tue Jul 10 18:04:03 2018 : Debug: # Loading pre-proxy {...}

Tue Jul 10 18:04:03 2018 : Debug: etsnac

Tue Jul 10 18:04:03 2018 : Debug: # Loading post-proxy {...}

Tue Jul 10 18:04:03 2018 : Debug: etsnac

Tue Jul 10 18:04:03 2018 : Debug: eap

Tue Jul 10 18:04:03 2018 : Debug: # Loading post-auth {...}

Tue Jul 10 18:04:03 2018 : Debug: etsnac

Tue Jul 10 18:04:03 2018 : Debug: } # server nac-server

Tue Jul 10 18:04:03 2018 : Debug: server nac-inner-tunnel { # from file /opt/nac/radius/raddb/sites-enabled/nac-inner-tunnel

Tue Jul 10 18:04:03 2018 : Debug: # Loading authenticate {...}

Tue Jul 10 18:04:03 2018 : Warning: /opt/nac/radius/raddb/sites-enabled/nac-inner-tunnel[41]: Please change attribute reference to '&Module-Failure-Message = ...'

Tue Jul 10 18:04:03 2018 : Debug: eap

Tue Jul 10 18:04:03 2018 : Debug: # Loading authorize {...}

Tue Jul 10 18:04:03 2018 : Debug: ets_tunnel

Tue Jul 10 18:04:03 2018 : Debug: chap

Tue Jul 10 18:04:03 2018 : Debug: mschap

Tue Jul 10 18:04:03 2018 : Debug: eap

Tue Jul 10 18:04:03 2018 : Debug: pap

Tue Jul 10 18:04:03 2018 : Debug: # Loading session {...}

Tue Jul 10 18:04:03 2018 : Debug: radutmp

Tue Jul 10 18:04:03 2018 : Debug: # Loading post-auth {...}

Tue Jul 10 18:04:03 2018 : Warning: /opt/nac/radius/raddb/sites-enabled/nac-inner-tunnel[57]: Please change attribute reference to '&Module-Failure-Message := ...'

Tue Jul 10 18:04:03 2018 : Debug: ets_tunnel

Tue Jul 10 18:04:03 2018 : Debug: } # server nac-inner-tunnel

Tue Jul 10 18:04:03 2018 : Debug: server nac-proxy-failed { # from file /opt/nac/radius/raddb/sites-enabled/nac-proxy-failed

Tue Jul 10 18:04:03 2018 : Debug: # Loading authenticate {...}

Tue Jul 10 18:04:03 2018 : Debug: ets_proxy_failed

Tue Jul 10 18:04:03 2018 : Debug: # Loading authorize {...}

Tue Jul 10 18:04:03 2018 : Debug: ets_proxy_failed

Tue Jul 10 18:04:03 2018 : Debug: } # server nac-proxy-failed

Tue Jul 10 18:04:03 2018 : Debug: thread pool {

Tue Jul 10 18:04:03 2018 : Debug: start_servers = 5

Tue Jul 10 18:04:03 2018 : Debug: max_servers = 32

Tue Jul 10 18:04:03 2018 : Debug: min_spare_servers = 3

Tue Jul 10 18:04:03 2018 : Debug: max_spare_servers = 10

Tue Jul 10 18:04:03 2018 : Debug: max_requests_per_server = 3000

Tue Jul 10 18:04:03 2018 : Debug: cleanup_delay = 5

Tue Jul 10 18:04:03 2018 : Debug: max_queue_size = 65536

Tue Jul 10 18:04:03 2018 : Debug: auto_limit_acct = no

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: Thread spawned new child 1. Total threads in pool: 1

Tue Jul 10 18:04:03 2018 : Debug: Thread 1 waiting to be assigned a request

Tue Jul 10 18:04:03 2018 : Debug: Thread spawned new child 2. Total threads in pool: 2

Tue Jul 10 18:04:03 2018 : Debug: Thread 2 waiting to be assigned a request

Tue Jul 10 18:04:03 2018 : Debug: Thread 3 waiting to be assigned a request

Tue Jul 10 18:04:03 2018 : Debug: Thread spawned new child 3. Total threads in pool: 3

Tue Jul 10 18:04:03 2018 : Debug: Thread spawned new child 4. Total threads in pool: 4

Tue Jul 10 18:04:03 2018 : Debug: Thread 4 waiting to be assigned a request

Tue Jul 10 18:04:03 2018 : Debug: Thread spawned new child 5. Total threads in pool: 5

Tue Jul 10 18:04:03 2018 : Debug: Thread 5 waiting to be assigned a request

Tue Jul 10 18:04:03 2018 : Debug: Thread pool initialized

Tue Jul 10 18:04:03 2018 : Debug: radiusd: #### Opening IP addresses and Ports ####

Tue Jul 10 18:04:03 2018 : Debug: listen {

Tue Jul 10 18:04:03 2018 : Debug: type = "auth"

Tue Jul 10 18:04:03 2018 : Debug: ipaddr = 192.168.1.200

Tue Jul 10 18:04:03 2018 : Debug: port = 1812

Tue Jul 10 18:04:03 2018 : Debug: limit {

Tue Jul 10 18:04:03 2018 : Debug: max_connections = 16

Tue Jul 10 18:04:03 2018 : Debug: lifetime = 0

Tue Jul 10 18:04:03 2018 : Debug: idle_timeout = 30

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: listen {

Tue Jul 10 18:04:03 2018 : Debug: type = "acct"

Tue Jul 10 18:04:03 2018 : Debug: ipaddr = 192.168.1.200

Tue Jul 10 18:04:03 2018 : Debug: port = 1813

Tue Jul 10 18:04:03 2018 : Debug: limit {

Tue Jul 10 18:04:03 2018 : Debug: max_connections = 16

Tue Jul 10 18:04:03 2018 : Debug: lifetime = 0

Tue Jul 10 18:04:03 2018 : Debug: idle_timeout = 30

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: listen {

Tue Jul 10 18:04:03 2018 : Debug: type = "auth"

Tue Jul 10 18:04:03 2018 : Debug: ipaddr = 127.0.0.1

Tue Jul 10 18:04:03 2018 : Debug: port = 18120

Tue Jul 10 18:04:03 2018 : Debug: }

Tue Jul 10 18:04:03 2018 : Debug: Listening on auth address 192.168.1.200 port 1812 bound to server nac-server

Tue Jul 10 18:04:03 2018 : Debug: Listening on acct address 192.168.1.200 port 1813 bound to server nac-server

Tue Jul 10 18:04:03 2018 : Debug: Listening on auth address 127.0.0.1 port 18120 bound to server nac-inner-tunnel

Tue Jul 10 18:04:03 2018 : Debug: Opened new proxy socket 'proxy address * port 40433'

Tue Jul 10 18:04:03 2018 : Debug: Listening on proxy address * port 40433

Tue Jul 10 18:04:03 2018 : Info: Ready to process requests

Tue Jul 10 18:04:41 2018 : Debug: Threads: total/active/spare threads = 5/0/5

Tue Jul 10 18:04:41 2018 : Debug: Waking up in 0.3 seconds.

Tue Jul 10 18:04:41 2018 : Debug: Thread 5 got semaphore

Tue Jul 10 18:04:41 2018 : Debug: Thread 5 handling request 0, (1 handled so far)

Tue Jul 10 18:04:41 2018 : Debug: (0) Received Access-Request Id 110 from 192.168.1.111:33867 to 192.168.1.200:1812 length 259

Tue Jul 10 18:04:41 2018 : Debug: (0) User-Name = "88B111C3EDFD"

Tue Jul 10 18:04:41 2018 : Debug: (0) User-Password = "88B111C3EDFD"

Tue Jul 10 18:04:41 2018 : Debug: (0) NAS-IP-Address = 127.0.0.1

Tue Jul 10 18:04:41 2018 : Debug: (0) NAS-Port = 101

Tue Jul 10 18:04:41 2018 : Debug: (0) NAS-Port-Type = Wireless-Other

Tue Jul 10 18:04:41 2018 : Debug: (0) NAS-Identifier = "SupportVO"

Tue Jul 10 18:04:41 2018 : Debug: (0) Siemens-AP-Serial = "15141805085D0000"

Tue Jul 10 18:04:41 2018 : Debug: (0) Siemens-AP-Name = "15141805085D0000"

Tue Jul 10 18:04:41 2018 : Debug: (0) Siemens-VNS-Name = "SupportVO"

Tue Jul 10 18:04:41 2018 : Debug: (0) Siemens-SSID = "SupportVO"

Tue Jul 10 18:04:41 2018 : Debug: (0) Siemens-BSS-MAC = "D88466272BF0"

Tue Jul 10 18:04:41 2018 : Debug: (0) Siemens-Policy-Name = "Non Authenticated"

Tue Jul 10 18:04:41 2018 : Debug: (0) Calling-Station-Id = "88B111C3EDFD"

Tue Jul 10 18:04:41 2018 : Debug: (0) Called-Station-Id = "D88466272BF0"

Tue Jul 10 18:04:41 2018 : Debug: (0) Acct-Session-Id = "M3153457a0002"

Tue Jul 10 18:04:41 2018 : Debug: (0) session-state: No State attribute

Tue Jul 10 18:04:41 2018 : Debug: (0) # Executing section authorize from file /opt/nac/radius/raddb/sites-enabled/nac-server

Tue Jul 10 18:04:41 2018 : Debug: (0) authorize {

Tue Jul 10 18:04:41 2018 : Debug: (0) update control {

Tue Jul 10 18:04:41 2018 : Debug: (0) EXPAND %{Calling-Station-Id}

Tue Jul 10 18:04:41 2018 : Debug: (0) --> 88B111C3EDFD

Tue Jul 10 18:04:41 2018 : Debug: (0) Load-Balance-Key = 88B111C3EDFD

Tue Jul 10 18:04:41 2018 : Debug: (0) } # update control = noop

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: calling etsnac (rlm_etsnac) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] *NOT* Continuing proxied conversation, skipping...

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Generated MAC 88b111c3edfd from Calling-Station-Id: 88B111C3EDFD

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Found username from: User-Name: 88B111C3EDFD

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Found User-Password attribute: 2, setting auth type to: PAP

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Found switch ip from: NAS-IP-Address: 127.0.0.1

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Unable to find existing NAC request manager instance.

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Making a new request to the AAA server for request ID: 0

Tue Jul 10 18:04:41 2018 : Info: (0) [etsnac connection_mgr] AAA Request [ID: 0, Source IP: 192.168.1.111, Command: Authenticate & Authorize Request(0x02)] 

(0) --- Request VPs ---

(0) User-Name = "88B111C3EDFD"

(0) User-Password = ************

(0) NAS-IP-Address = 127.0.0.1

(0) NAS-Port = 101

(0) NAS-Port-Type = Wireless-Other

(0) NAS-Identifier = "SupportVO"

(0) Siemens-AP-Serial = "15141805085D0000"

(0) Siemens-AP-Name = ****************

(0) Siemens-VNS-Name = "SupportVO"

(0) Siemens-SSID = "SupportVO"

(0) Siemens-BSS-MAC = "D88466272BF0"

(0) Siemens-Policy-Name = "Non Authenticated"

(0) Calling-Station-Id = "88B111C3EDFD"

(0) Called-Station-Id = "D88466272BF0"

(0) Acct-Session-Id = "M3153457a0002"

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac connection_mgr] Using authentication server connection ID: 31.

Tue Jul 10 18:04:41 2018 : Info: (0) [etsnac connection_mgr] AAA Response [ID: 0, Command: Accept User(0x22)] 

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac connection_mgr] Releasing authentication server connection ID: 31.

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Unable to find existing NAC request manager instance.

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] The AAA server says to accept the request.

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: returned from etsnac (rlm_etsnac) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] = ok

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: calling chap (rlm_chap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: returned from chap (rlm_chap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [chap] = noop

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: calling mschap (rlm_mschap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: returned from mschap (rlm_mschap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [mschap] = noop

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: calling eap (rlm_eap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) eap: No EAP-Message, not doing EAP

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: returned from eap (rlm_eap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [eap] = noop

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: calling pap (rlm_pap) for request 0

Tue Jul 10 18:04:41 2018 : WARNING: (0) pap: Auth-Type already set. Not setting to PAP

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[authorize]: returned from pap (rlm_pap) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [pap] = noop

Tue Jul 10 18:04:41 2018 : Debug: (0) } # authorize = ok

Tue Jul 10 18:04:41 2018 : Debug: (0) Found Auth-Type = Accept

Tue Jul 10 18:04:41 2018 : Debug: (0) Auth-Type = Accept, accepting the user

Tue Jul 10 18:04:41 2018 : Debug: (0) # Executing section post-auth from file /opt/nac/radius/raddb/sites-enabled/nac-server

Tue Jul 10 18:04:41 2018 : Debug: (0) post-auth {

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[post-auth]: calling etsnac (rlm_etsnac) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] Processing Response-Packet-Type Access-Accept(2)

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] Not running EAP-TLS User-Name replacement for non EAP authentication

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Generated MAC 88b111c3edfd from Calling-Station-Id: 88B111C3EDFD

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Found username from: User-Name: 88B111C3EDFD

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Found User-Password attribute: 2, setting auth type to: PAP

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac nac_request_mgr] Found switch ip from: NAS-IP-Address: 127.0.0.1

Tue Jul 10 18:04:41 2018 : Info: (0) [etsnac connection_mgr] AAA Request [ID: 0, Source IP: 192.168.1.111, Command: Post Authorize Request(0x03)] 

(0) --- Request VPs ---

(0) User-Name = "88B111C3EDFD"

(0) User-Password = ************

(0) NAS-IP-Address = 127.0.0.1

(0) NAS-Port = 101

(0) NAS-Port-Type = Wireless-Other

(0) NAS-Identifier = "SupportVO"

(0) Siemens-AP-Serial = "15141805085D0000"

(0) Siemens-AP-Name = ****************

(0) Siemens-VNS-Name = "SupportVO"

(0) Siemens-SSID = "SupportVO"

(0) Siemens-BSS-MAC = "D88466272BF0"

(0) Siemens-Policy-Name = "Non Authenticated"

(0) Calling-Station-Id = "88B111C3EDFD"

(0) Called-Station-Id = "D88466272BF0"

(0) Acct-Session-Id = "M3153457a0002"

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac connection_mgr] Using authentication server connection ID: 31.

Tue Jul 10 18:04:41 2018 : Info: (0) [etsnac connection_mgr] AAA Response [ID: 0, Command: Replace Response Attributes(0x27)] 

(0) Filter-Id := "Enterasys:version=1:policy=Unregistered"

(0) Login-LAT-Port := "0"

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac connection_mgr] Releasing authentication server connection ID: 31.

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] The AAA server says to replace the response attributes.

Tue Jul 10 18:04:41 2018 : Debug: (0) modsingle[post-auth]: returned from etsnac (rlm_etsnac) for request 0

Tue Jul 10 18:04:41 2018 : Debug: (0) [etsnac] = updated

Tue Jul 10 18:04:41 2018 : Debug: (0) } # post-auth = updated

Tue Jul 10 18:04:41 2018 : Debug: (0) Sent Access-Accept Id 110 from 192.168.1.200:1812 to 192.168.1.111:33867 length 0

Tue Jul 10 18:04:41 2018 : Debug: (0) Filter-Id := "Enterasys:version=1:policy=Unregistered"

Tue Jul 10 18:04:41 2018 : Debug: (0) Login-LAT-Port := "0"

Tue Jul 10 18:04:41 2018 : Debug: (0) Finished request

Tue Jul 10 18:04:41 2018 : Debug: Thread 5 waiting to be assigned a request

Tue Jul 10 18:04:41 2018 : Debug: Waking up in 4.7 seconds.

Tue Jul 10 18:04:46 2018 : Debug: (0) Cleaning up request packet ID 110 with timestamp +38

Tue Jul 10 18:04:46 2018 : Info: Ready to process requests

Tue Jul 10 18:07:00 2018 : Debug: Waking up in 0.3 seconds.

Tue Jul 10 18:07:00 2018 : Debug: Thread 4 got semaphore

Tue Jul 10 18:07:00 2018 : Debug: Thread 4 handling request 1, (1 handled so far)

Tue Jul 10 18:07:00 2018 : Debug: (1) Received Accounting-Request Id 0 from 192.168.1.111:49438 to 192.168.1.200:1813 length 364

Tue Jul 10 18:07:00 2018 : Debug: (1) Acct-Session-Id = "M3153457a0002"

Tue Jul 10 18:07:00 2018 : Debug: (1) NAS-Port = 101

Tue Jul 10 18:07:00 2018 : Debug: (1) User-Name = "88B111C3EDFD"

Tue Jul 10 18:07:00 2018 : Debug: (1) Filter-Id = "Enterprise User"

Tue Jul 10 18:07:00 2018 : Debug: (1) Acct-Interim-Interval = 1800

Tue Jul 10 18:07:00 2018 : Debug: (1) Session-Timeout = 0

Tue Jul 10 18:07:00 2018 : Debug: (1) Event-Timestamp = "Jul 10 2018 18:07:12 MSK"

Tue Jul 10 18:07:00 2018 : Debug: (1) Acct-Status-Type = Start

Tue Jul 10 18:07:00 2018 : Debug: (1) Acct-Delay-Time = 2

Tue Jul 10 18:07:00 2018 : Debug: (1) Acct-Authentic = 0

Tue Jul 10 18:07:00 2018 : Debug: (1) Framed-IP-Address = 192.168.1.67

Tue Jul 10 18:07:00 2018 : Debug: (1) Framed-IPv6-Address = fe80::2499:faf8:246a:a9f3

Tue Jul 10 18:07:00 2018 : Debug: (1) Framed-IPv6-Address = fdd0:7ab5:af20:fb00:2499:faf8:246a:a9f3

Tue Jul 10 18:07:00 2018 : Debug: (1) Framed-IPv6-Address = fdd0:7ab5:af20:fb00:ac1a:6867:29ad:cf89

Tue Jul 10 18:07:00 2018 : Debug: (1) Connect-Info = "802.11a"

Tue Jul 10 18:07:00 2018 : Debug: (1) NAS-Port-Type = Wireless-Other

Tue Jul 10 18:07:00 2018 : Debug: (1) Called-Station-Id = "D88466272BF0"

Tue Jul 10 18:07:00 2018 : Debug: (1) Calling-Station-Id = "88B111C3EDFD"

Tue Jul 10 18:07:00 2018 : Debug: (1) NAS-IP-Address = 127.0.0.1

Tue Jul 10 18:07:00 2018 : Debug: (1) NAS-Identifier = "SupportVO"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-AP-Serial = "15141805085D0000"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-AP-Name = "15141805085D0000"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-BSS-MAC = "D88466272BF0"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-VNS-Name = "SupportVO"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-SSID = "SupportVO"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-Policy-Name = "Enterprise User"

Tue Jul 10 18:07:00 2018 : Debug: (1) Siemens-Topology-Name = "n/a"

Tue Jul 10 18:07:00 2018 : Debug: (1) # Executing section preacct from file /opt/nac/radius/raddb/sites-enabled/nac-server

Tue Jul 10 18:07:00 2018 : Debug: (1) preacct {

Tue Jul 10 18:07:00