root@quagga:/opt/freeradius/etc/raddb# /opt/freeradius/sbin/radiusd -X radiusd: FreeRADIUS Version 2.2.10, for host i686-pc-linux-gnu, built on Dec 20 2017 at 23:19:06 Copyright (C) 1999-2015 The FreeRADIUS server project and contributors. There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. You may redistribute copies of FreeRADIUS under the terms of the GNU General Public License. For more information about these matters, see the file named COPYRIGHT. Starting - reading configuration files ... including configuration file /opt/freeradius/etc/raddb/radiusd.conf including configuration file /opt/freeradius/etc/raddb/proxy.conf including configuration file /opt/freeradius/etc/raddb/clients.conf including files in directory /opt/freeradius/etc/raddb/modules/ including configuration file /opt/freeradius/etc/raddb/modules/files including configuration file /opt/freeradius/etc/raddb/modules/radrelay including configuration file /opt/freeradius/etc/raddb/modules/inner-eap including configuration file /opt/freeradius/etc/raddb/modules/sql_log including configuration file /opt/freeradius/etc/raddb/modules/expr including configuration file /opt/freeradius/etc/raddb/modules/attr_rewrite including configuration file /opt/freeradius/etc/raddb/modules/counter including configuration file /opt/freeradius/etc/raddb/modules/radutmp including configuration file /opt/freeradius/etc/raddb/modules/mschap including configuration file /opt/freeradius/etc/raddb/modules/perl including configuration file /opt/freeradius/etc/raddb/modules/ntlm_auth including configuration file /opt/freeradius/etc/raddb/modules/sqlcounter_expire_on_login including configuration file /opt/freeradius/etc/raddb/modules/etc_group including configuration file /opt/freeradius/etc/raddb/modules/otp including configuration file /opt/freeradius/etc/raddb/modules/detail.log including configuration file /opt/freeradius/etc/raddb/modules/krb5 including configuration file /opt/freeradius/etc/raddb/modules/ippool including configuration file /opt/freeradius/etc/raddb/modules/expiration including configuration file /opt/freeradius/etc/raddb/modules/detail.example.com including configuration file /opt/freeradius/etc/raddb/modules/logintime including configuration file /opt/freeradius/etc/raddb/modules/acct_unique including configuration file /opt/freeradius/etc/raddb/modules/ldap including configuration file /opt/freeradius/etc/raddb/modules/dhcp_sqlippool including configuration file /opt/freeradius/etc/raddb/sql/mysql/ippool-dhcp.conf including configuration file /opt/freeradius/etc/raddb/modules/policy including configuration file /opt/freeradius/etc/raddb/modules/checkval including configuration file /opt/freeradius/etc/raddb/modules/cache including configuration file /opt/freeradius/etc/raddb/modules/replicate including configuration file /opt/freeradius/etc/raddb/modules/smsotp including configuration file /opt/freeradius/etc/raddb/modules/soh including configuration file /opt/freeradius/etc/raddb/modules/digest including configuration file /opt/freeradius/etc/raddb/modules/mac2ip including configuration file /opt/freeradius/etc/raddb/modules/redis including configuration file /opt/freeradius/etc/raddb/modules/passwd including configuration file /opt/freeradius/etc/raddb/modules/rediswho including configuration file /opt/freeradius/etc/raddb/modules/sradutmp including configuration file /opt/freeradius/etc/raddb/modules/echo including configuration file /opt/freeradius/etc/raddb/modules/smbpasswd including configuration file /opt/freeradius/etc/raddb/modules/cui including configuration file /opt/freeradius/etc/raddb/modules/dynamic_clients including configuration file /opt/freeradius/etc/raddb/modules/pam including configuration file /opt/freeradius/etc/raddb/modules/realm including configuration file /opt/freeradius/etc/raddb/modules/linelog including configuration file /opt/freeradius/etc/raddb/modules/chap including configuration file /opt/freeradius/etc/raddb/modules/attr_filter including configuration file /opt/freeradius/etc/raddb/modules/detail including configuration file /opt/freeradius/etc/raddb/modules/opendirectory including configuration file /opt/freeradius/etc/raddb/modules/exec including configuration file /opt/freeradius/etc/raddb/modules/wimax including configuration file /opt/freeradius/etc/raddb/modules/unix including configuration file /opt/freeradius/etc/raddb/modules/preprocess including configuration file /opt/freeradius/etc/raddb/modules/pap including configuration file /opt/freeradius/etc/raddb/modules/always including configuration file /opt/freeradius/etc/raddb/modules/mac2vlan including configuration file /opt/freeradius/etc/raddb/sql.conf including configuration file /opt/freeradius/etc/raddb/sql/mysql/dialup.conf including configuration file /opt/freeradius/etc/raddb/policy.conf including files in directory /opt/freeradius/etc/raddb/sites-enabled/ including configuration file /opt/freeradius/etc/raddb/sites-enabled/default including configuration file /opt/freeradius/etc/raddb/sites-enabled/control-socket main { allow_core_dumps = no } including dictionary file /opt/freeradius/etc/raddb/dictionary main { name = "radiusd" prefix = "/opt/freeradius" localstatedir = "/opt/freeradius/var" sbindir = "/opt/freeradius/sbin" logdir = "/var/log/radius" run_dir = "/opt/freeradius/var/run/radiusd" libdir = "/opt/freeradius/lib" radacctdir = "/var/log/radius/radacct" hostname_lookups = no max_request_time = 30 cleanup_delay = 5 max_requests = 1024 pidfile = "/opt/freeradius/var/run/radiusd/radiusd.pid" checkrad = "/opt/freeradius/sbin/checkrad" debug_level = 0 proxy_requests = yes log { stripped_names = no auth = no auth_badpass = no auth_goodpass = no } security { max_attributes = 200 reject_delay = 1 status_server = yes allow_vulnerable_openssl = no } } radiusd: #### Loading Realms and Home Servers #### proxy server { retry_delay = 5 retry_count = 3 default_fallback = no dead_time = 120 wake_all_if_all_dead = no } home_server localhost { ipaddr = 127.0.0.1 port = 1812 type = "auth" secret = "testing123" response_window = 20 max_outstanding = 65536 require_message_authenticator = yes zombie_period = 40 status_check = "status-server" ping_interval = 30 check_interval = 30 num_answers_to_alive = 3 num_pings_to_alive = 3 revive_interval = 120 status_check_timeout = 4 coa { irt = 2 mrt = 16 mrc = 5 mrd = 30 } } home_server_pool my_auth_failover { type = fail-over home_server = localhost } realm example.com { auth_pool = my_auth_failover } realm LOCAL { } radiusd: #### Loading Clients #### client 212.88.155.47 { require_message_authenticator = no secret = "wz34th" shortname = "vs-mx5-lab.6898-lo0" nastype = "other" } client 212.18.216.16/32 { require_message_authenticator = no secret = "wz34th" shortname = "vs-mx5-lab.6898-vrf-132" nastype = "other" } client 212.18.216.17/32 { require_message_authenticator = no secret = "wz34th" shortname = "vs-mx5-lab.6898-vrf-232" nastype = "other" } client 212.18.216.18/32 { require_message_authenticator = no secret = "wz34th" shortname = "vs-mx5-lab.6898-vrf-432" nastype = "other" } radiusd: #### Instantiating modules #### instantiate { Module: Linked to module rlm_exec Module: Instantiating module "exec" from file /opt/freeradius/etc/raddb/modules/exec exec { wait = no input_pairs = "request" shell_escape = yes timeout = 10 } Module: Linked to module rlm_expr Module: Instantiating module "expr" from file /opt/freeradius/etc/raddb/modules/expr Module: Linked to module rlm_expiration Module: Instantiating module "expiration" from file /opt/freeradius/etc/raddb/modules/expiration expiration { reply-message = "Password Has Expired " } Module: Linked to module rlm_logintime Module: Instantiating module "logintime" from file /opt/freeradius/etc/raddb/modules/logintime logintime { reply-message = "You are calling outside your allowed timespan " minimum-timeout = 60 } } radiusd: #### Loading Virtual Servers #### server { # from file /opt/freeradius/etc/raddb/radiusd.conf modules { Module: Creating Auth-Type = digest Module: Checking authenticate {...} for more modules to load Module: Linked to module rlm_pap Module: Instantiating module "pap" from file /opt/freeradius/etc/raddb/modules/pap pap { encryption_scheme = "auto" auto_header = no } Module: Linked to module rlm_chap Module: Instantiating module "chap" from file /opt/freeradius/etc/raddb/modules/chap Module: Linked to module rlm_mschap Module: Instantiating module "mschap" from file /opt/freeradius/etc/raddb/modules/mschap mschap { use_mppe = yes require_encryption = no require_strong = no with_ntdomain_hack = no allow_retry = yes } Module: Linked to module rlm_digest Module: Instantiating module "digest" from file /opt/freeradius/etc/raddb/modules/digest Module: Linked to module rlm_unix Module: Instantiating module "unix" from file /opt/freeradius/etc/raddb/modules/unix unix { radwtmp = "/var/log/radius/radwtmp" } Module: Checking authorize {...} for more modules to load Module: Linked to module rlm_preprocess Module: Instantiating module "preprocess" from file /opt/freeradius/etc/raddb/modules/preprocess preprocess { huntgroups = "/opt/freeradius/etc/raddb/huntgroups" hints = "/opt/freeradius/etc/raddb/hints" with_ascend_hack = no ascend_channels_per_line = 23 with_ntdomain_hack = no with_specialix_jetstream_hack = no with_cisco_vsa_hack = no with_alvarion_vsa_hack = no } reading pairlist file /opt/freeradius/etc/raddb/huntgroups reading pairlist file /opt/freeradius/etc/raddb/hints Module: Linked to module rlm_detail Module: Instantiating module "auth_log" from file /opt/freeradius/etc/raddb/modules/detail.log detail auth_log { detailfile = "/var/log/radius/radacct/auth-detail-%Y%m%d" header = "%t" detailperm = 384 dirperm = 493 locking = no log_packet_header = no escape_filenames = no } Module: Linked to module rlm_realm Module: Instantiating module "suffix" from file /opt/freeradius/etc/raddb/modules/realm realm suffix { format = "suffix" delimiter = "@" ignore_default = no ignore_null = no } Module: Linked to module rlm_files Module: Instantiating module "files" from file /opt/freeradius/etc/raddb/modules/files files { usersfile = "/opt/freeradius/etc/raddb/users" acctusersfile = "/opt/freeradius/etc/raddb/acct_users" preproxy_usersfile = "/opt/freeradius/etc/raddb/preproxy_users" compat = "no" } reading pairlist file /opt/freeradius/etc/raddb/users reading pairlist file /opt/freeradius/etc/raddb/acct_users reading pairlist file /opt/freeradius/etc/raddb/preproxy_users Module: Linked to module rlm_sql Module: Instantiating module "sql" from file /opt/freeradius/etc/raddb/sql.conf sql { driver = "rlm_sql_mysql" server = "localhost" port = "" login = "radius" password = "IlsFmgB" radius_db = "radius" read_groups = yes sqltrace = no sqltracefile = "/var/log/radius/sqltrace.sql" readclients = no deletestalesessions = yes num_sql_socks = 4 lifetime = 0 max_queries = 0 sql_user_name = "%{User-Name}" default_user_profile = "" nas_query = "SELECT id, nasname, shortname, type, secret, server FROM nas" authorize_check_query = "SELECT id, username, attribute, value, op FROM radcheck WHERE username = '%{SQL-User-Name}' ORDER BY id" authorize_reply_query = "SELECT id, username, attribute, value, op FROM radreply WHERE username = '%{SQL-User-Name}' ORDER BY id" authorize_group_check_query = "SELECT id, groupname, attribute, Value, op FROM radgroupcheck WHERE groupname = '%{Sql-Group}' ORDER BY id" authorize_group_reply_query = "SELECT id, groupname, attribute, value, op FROM radgroupreply WHERE groupname = '%{Sql-Group}' ORDER BY id" accounting_onoff_query = " UPDATE radacct SET acctstoptime = '%S', acctsessiontime = unix_timestamp('%S') - unix_timestamp(acctstarttime), acctterminatecause = '%{Acct-Terminate-Cause}', acctstopdelay = %{%{Acct-Delay-Time}:-0} WHERE acctstoptime IS NULL AND nasipaddress = '%{NAS-IP-Address}' AND acctstarttime <= '%S'" accounting_update_query = " UPDATE radacct SET framedipaddress = '%{Framed-IP-Address}', acctsessiontime = '%{%{Acct-Session-Time}:-0}', acctinputoctets = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}' WHERE acctsessionid = '%{Acct-Session-Id}' AND username = '%{SQL-User-Name}' AND nasipaddress = '%{NAS-IP-Address}'" accounting_update_query_alt = " INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctsessiontime, acctauthentic, connectinfo_start, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, servicetype, framedprotocol, framedipaddress, acctstartdelay, xascendsessionsvrkey) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', DATE_SUB('%S', INTERVAL (%{%{Acct-Session-Time}:-0} + %{%{Acct-Delay-Time}:-0}) SECOND), '%{%{Acct-Session-Time}:-0}', '%{Acct-Authentic}', '', '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0', '%{X-Ascend-Session-Svr-Key}')" accounting_start_query = " INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctstoptime, acctsessiontime, acctauthentic, connectinfo_start, connectinfo_stop, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, acctterminatecause, servicetype, framedprotocol, framedipaddress, acctstartdelay, acctstopdelay, xascendsessionsvrkey) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', '%S', NULL, '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{%{Acct-Delay-Time}:-0}', '0', '%{X-Ascend-Session-Svr-Key}')" accounting_start_query_alt = " UPDATE radacct SET acctstarttime = '%S', acctstartdelay = '%{%{Acct-Delay-Time}:-0}', connectinfo_start = '%{Connect-Info}' WHERE acctsessionid = '%{Acct-Session-Id}' AND username = '%{SQL-User-Name}' AND nasipaddress = '%{NAS-IP-Address}'" accounting_stop_query = " UPDATE radacct SET acctstoptime = '%S', acctsessiontime = '%{%{Acct-Session-Time}:-0}', acctinputoctets = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}', acctterminatecause = '%{Acct-Terminate-Cause}', acctstopdelay = '%{%{Acct-Delay-Time}:-0}', connectinfo_stop = '%{Connect-Info}' WHERE acctsessionid = '%{Acct-Session-Id}' AND username = '%{SQL-User-Name}' AND nasipaddress = '%{NAS-IP-Address}'" accounting_stop_query_alt = " INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctstoptime, acctsessiontime, acctauthentic, connectinfo_start, connectinfo_stop, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, acctterminatecause, servicetype, framedprotocol, framedipaddress, acctstartdelay, acctstopdelay) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', DATE_SUB('%S', INTERVAL (%{%{Acct-Session-Time}:-0} + %{%{Acct-Delay-Time}:-0}) SECOND), '%S', '%{%{Acct-Session-Time}:-0}', '%{Acct-Authentic}', '', '%{Connect-Info}', '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Acct-Terminate-Cause}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0', '%{%{Acct-Delay-Time}:-0}')" group_membership_query = "SELECT groupname FROM radusergroup WHERE username = '%{SQL-User-Name}' ORDER BY priority" connect_failure_retry_delay = 60 simul_count_query = "" simul_verify_query = "SELECT radacctid, acctsessionid, username, nasipaddress, nasportid, framedipaddress, callingstationid, framedprotocol FROM radacct WHERE username = '%{SQL-User-Name}' AND acctstoptime IS NULL" postauth_query = "INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ( '%{User-Name}', '%{%{User-Password}:-%{Chap-Password}}', '%{reply:Packet-Type}', '%S')" safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /" } rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked rlm_sql (sql): Attempting to connect to radius@localhost:/radius rlm_sql (sql): starting 0 rlm_sql (sql): Attempting to connect rlm_sql_mysql #0 rlm_sql_mysql: Starting connect to MySQL server for #0 rlm_sql (sql): Connected new DB handle, #0 rlm_sql (sql): starting 1 rlm_sql (sql): Attempting to connect rlm_sql_mysql #1 rlm_sql_mysql: Starting connect to MySQL server for #1 rlm_sql (sql): Connected new DB handle, #1 rlm_sql (sql): starting 2 rlm_sql (sql): Attempting to connect rlm_sql_mysql #2 rlm_sql_mysql: Starting connect to MySQL server for #2 rlm_sql (sql): Connected new DB handle, #2 rlm_sql (sql): starting 3 rlm_sql (sql): Attempting to connect rlm_sql_mysql #3 rlm_sql_mysql: Starting connect to MySQL server for #3 rlm_sql (sql): Connected new DB handle, #3 Module: Checking preacct {...} for more modules to load Module: Linked to module rlm_acct_unique Module: Instantiating module "acct_unique" from file /opt/freeradius/etc/raddb/modules/acct_unique acct_unique { key = "User-Name, Acct-Session-Id, NAS-IP-Address, NAS-Identifier, NAS-Port" } Module: Checking accounting {...} for more modules to load Module: Instantiating module "detail" from file /opt/freeradius/etc/raddb/modules/detail detail { detailfile = "/var/log/radius/radacct/detail" header = "%t" detailperm = 384 dirperm = 493 locking = no log_packet_header = no escape_filenames = no } Module: Linked to module rlm_sql_log Module: Instantiating module "sql_log" from file /opt/freeradius/etc/raddb/modules/sql_log sql_log { path = "/var/log/radius/radacct/sql-relay" Post-Auth = "INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ('%{User-Name}', '%{User-Password:-Chap-Password}', '%{reply:Packet-Type}', '%S');" sql_user_name = "%{%{User-Name}:-DEFAULT}" utf8 = no safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /" } Module: Linked to module rlm_attr_filter Module: Instantiating module "attr_filter.accounting_response" from file /opt/freeradius/etc/raddb/modules/attr_filter attr_filter attr_filter.accounting_response { attrsfile = "/opt/freeradius/etc/raddb/attrs.accounting_response" key = "%{User-Name}" relaxed = no } reading pairlist file /opt/freeradius/etc/raddb/attrs.accounting_response Module: Checking session {...} for more modules to load Module: Linked to module rlm_radutmp Module: Instantiating module "radutmp" from file /opt/freeradius/etc/raddb/modules/radutmp radutmp { filename = "/var/log/radius/radutmp" username = "%{User-Name}" case_sensitive = yes check_with_nas = yes perm = 384 callerid = yes } Module: Checking post-auth {...} for more modules to load Module: Instantiating module "reply_log" from file /opt/freeradius/etc/raddb/modules/detail.log detail reply_log { detailfile = "/var/log/radius/radacct//reply-detail-%Y%m%d" header = "%t" detailperm = 384 dirperm = 493 locking = no log_packet_header = no escape_filenames = no } Module: Instantiating module "attr_filter.access_reject" from file /opt/freeradius/etc/raddb/modules/attr_filter attr_filter attr_filter.access_reject { attrsfile = "/opt/freeradius/etc/raddb/attrs.access_reject" key = "%{User-Name}" relaxed = no } reading pairlist file /opt/freeradius/etc/raddb/attrs.access_reject } # modules } # server radiusd: #### Opening IP addresses and Ports #### listen { type = "auth" ipaddr = * port = 0 } listen { type = "acct" ipaddr = * port = 0 } listen { type = "control" listen { socket = "/opt/freeradius/var/run/radiusd/radiusd.sock" } } ... adding new socket proxy address * port 54215 Listening on authentication address * port 1812 Listening on accounting address * port 1813 Listening on command file /opt/freeradius/var/run/radiusd/radiusd.sock Listening on proxy address * port 1814 Ready to process requests. rad_recv: Access-Request packet from host 212.88.155.47 port 62146, id=14, length=637 User-Name = "HSI/DEU.MYID.47110815" User-Password = "lab" Service-Type = Framed-User Chargeable-User-Identity = "" Acct-Session-Id = "169" Calling-Station-Id = "vs-mx5-testlab.6898/1ISAM-GSL-7302 eth 1/1/15/02/DEU.MYID.47110815" ERX-Dhcp-Options = 0x3501013c1341564d2044484350432076392e30342e3339353d0fffd77f2ae8000300010896d77f2ae80c09667269747a2e626f78370b01030679f9d40f782a1a9e528c011c314953414d2d47534c2d373330322065746820312f312f31352f303202114445552e4d5949442e3437313130383135095900000de95481040000049f8204000044f683040000020084040000052085040000a0ab8604000000008704000004a08804000064608904000000008a04000000808b04000000088c04000000038d04000000088e0400000008 ERX-Dhcp-Gi-Address = 212.88.155.47 ERX-Dhcp-Mac-Addr = "0896.d77f.2ae8" NAS-Identifier = "vs-mx5-testlab.6898" NAS-Port = 284201092 NAS-Port-Id = "ge-1/1/3.demux0.3221225559:777-132" NAS-Port-Type = Ethernet ERX-Pppoe-Description = "pppoe 08:96:d7:7f:2a:e8" ADSL-Agent-Circuit-Id = "1ISAM-GSL-7302 eth 1/1/15/02" ADSL-Agent-Remote-Id = "DEU.MYID.47110815" Actual-Data-Rate-Upstream = 1183 Actual-Data-Rate-Downstream = 17654 Minimum-Data-Rate-Upstream = 512 Minimum-Data-Rate-Downstream = 1312 Attainable-Data-Rate-Upstream = 41131 Attainable-Data-Rate-Downstream = 0 Maximum-Data-Rate-Upstream = 1184 Maximum-Data-Rate-Downstream = 25696 Minimum-Data-Rate-Upstream-Low-Power = 0 Minimum-Data-Rate-Downstream-Low-Power = 128 Maximum-Interleaving-Delay-Upstream = 8 Actual-Interleaving-Delay-Upstream = 3 Maximum-Interleaving-Delay-Downstream = 8 Actual-Interleaving-Delay-Downstream = 8 NAS-IP-Address = 212.88.155.47 # Executing section authorize from file /opt/freeradius/etc/raddb/sites-enabled/default +group authorize { ++[preprocess] = ok [auth_log] expand: /var/log/radius/radacct/auth-detail-%Y%m%d -> /var/log/radius/radacct/auth-detail-20171221 [auth_log] /var/log/radius/radacct/auth-detail-%Y%m%d expands to /var/log/radius/radacct/auth-detail-20171221 [auth_log] expand: %t -> Thu Dec 21 14:43:52 2017 ++[auth_log] = ok ++[chap] = noop ++[mschap] = noop ++[digest] = noop [suffix] No '@' in User-Name = "HSI/DEU.MYID.47110815", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] = noop [files] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [files] users: Matched entry DEFAULT at line 1 ++[files] = ok [sql] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [sql] sql_set_user escaped user --> 'HSI/DEU.MYID.47110815' rlm_sql (sql): Reserving sql socket id: 3 [sql] expand: SELECT id, username, attribute, value, op FROM radcheck WHERE username = '%{SQL-User-Name}' ORDER BY id -> SELECT id, username, attribute, value, op FROM radcheck WHERE username = 'HSI/DEU.MYID.47110815' ORDER BY id WARNING: Found User-Password == "...". WARNING: Are you sure you don't mean Cleartext-Password? WARNING: See "man rlm_pap" for more information. [sql] User found in radcheck table [sql] expand: SELECT id, username, attribute, value, op FROM radreply WHERE username = '%{SQL-User-Name}' ORDER BY id -> SELECT id, username, attribute, value, op FROM radreply WHERE username = 'HSI/DEU.MYID.47110815' ORDER BY id [sql] expand: SELECT groupname FROM radusergroup WHERE username = '%{SQL-User-Name}' ORDER BY priority -> SELECT groupname FROM radusergroup WHERE username = 'HSI/DEU.MYID.47110815' ORDER BY priority rlm_sql (sql): Released sql socket id: 3 ++[sql] = ok ++[expiration] = noop ++[logintime] = noop [pap] WARNING: Auth-Type already set. Not setting to PAP ++[pap] = noop +} # group authorize = ok Found Auth-Type = Accept Auth-Type = Accept, accepting the user # Executing section post-auth from file /opt/freeradius/etc/raddb/sites-enabled/default +group post-auth { [reply_log] expand: /var/log/radius/radacct//reply-detail-%Y%m%d -> /var/log/radius/radacct//reply-detail-20171221 [reply_log] /var/log/radius/radacct//reply-detail-%Y%m%d expands to /var/log/radius/radacct//reply-detail-20171221 [reply_log] expand: %t -> Thu Dec 21 14:43:52 2017 ++[reply_log] = ok [sql_log] Processing sql_log_postauth [sql_log] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [sql_log] expand: %{%{User-Name}:-DEFAULT} -> HSI/DEU.MYID.47110815 [sql_log] sql_set_user escaped user --> 'HSI/DEU.MYID.47110815' [sql_log] WARNING: Deprecated conditional expansion ":-". See "man unlang" for details [sql_log] expand: INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ('%{User-Name}', '%{User-Password:-Chap-Password}', '%{reply:Packet-Type}', '%S'); -> INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ('HSI/DEU.MYID.47110815', 'lab', 'Access-Accept', '2017-12-21 14:43:52'); [sql_log] expand: /var/log/radius/radacct/sql-relay -> /var/log/radius/radacct/sql-relay ++[sql_log] = ok ++[exec] = noop +} # group post-auth = ok Sending Access-Accept of id 14 to 212.88.155.47 port 62146 Class = 0x44656661756c74 ERX-Virtual-Router-Name == "VRF-132" Finished request 0. Going to the next request Waking up in 4.9 seconds. rad_recv: Accounting-Request packet from host 212.88.155.47 port 62146, id=15, length=706 User-Name = "HSI/DEU.MYID.47110815" Acct-Status-Type = Start Acct-Session-Id = "169" Event-Timestamp = "Dec 21 2017 14:43:54 CET" Acct-Delay-Time = 0 Service-Type = Framed-User ERX-Attr-177 = 0x506f72742073706565643a20313030303030306b Acct-Authentic = RADIUS Calling-Station-Id = "vs-mx5-testlab.6898/1ISAM-GSL-7302 eth 1/1/15/02/DEU.MYID.47110815" Class = 0x44656661756c74 ERX-Dhcp-Options = 0x3501013c1341564d2044484350432076392e30342e3339353d0fffd77f2ae8000300010896d77f2ae80c09667269747a2e626f78370b01030679f9d40f782a1a9e528c011c314953414d2d47534c2d373330322065746820312f312f31352f303202114445552e4d5949442e3437313130383135095900000de95481040000049f8204000044f683040000020084040000052085040000a0ab8604000000008704000004a08804000064608904000000008a04000000808b04000000088c04000000038d04000000088e0400000008 ERX-Dhcp-Gi-Address = 192.168.203.21 ERX-Dhcp-Mac-Addr = "0896.d77f.2ae8" Framed-IP-Address = 100.127.128.12 NAS-Identifier = "vs-mx5-testlab.6898" NAS-Port = 284201092 NAS-Port-Id = "ge-1/1/3.demux0.3221225559:777-132" NAS-Port-Type = Ethernet ERX-Virtual-Router-Name = "default:VRF-132" ERX-Pppoe-Description = "pppoe 08:96:d7:7f:2a:e8" ADSL-Agent-Circuit-Id = "1ISAM-GSL-7302 eth 1/1/15/02" ADSL-Agent-Remote-Id = "DEU.MYID.47110815" Actual-Data-Rate-Upstream = 1183 Actual-Data-Rate-Downstream = 17654 Minimum-Data-Rate-Upstream = 512 Minimum-Data-Rate-Downstream = 1312 Attainable-Data-Rate-Upstream = 41131 Attainable-Data-Rate-Downstream = 0 Maximum-Data-Rate-Upstream = 1184 Maximum-Data-Rate-Downstream = 25696 Minimum-Data-Rate-Upstream-Low-Power = 0 Minimum-Data-Rate-Downstream-Low-Power = 128 Maximum-Interleaving-Delay-Upstream = 8 Actual-Interleaving-Delay-Upstream = 3 Maximum-Interleaving-Delay-Downstream = 8 Actual-Interleaving-Delay-Downstream = 8 NAS-IP-Address = 212.88.155.47 # Executing section preacct from file /opt/freeradius/etc/raddb/sites-enabled/default +group preacct { ++[preprocess] = ok [acct_unique] Hashing 'NAS-Port = 284201092,NAS-Identifier = "vs-mx5-testlab.6898",NAS-IP-Address = 212.88.155.47,Acct-Session-Id = "169",User-Name = "HSI/DEU.MYID.47110815"' [acct_unique] Acct-Unique-Session-ID = "9f08cfde822d1d74". ++[acct_unique] = ok [suffix] No '@' in User-Name = "HSI/DEU.MYID.47110815", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] = noop ++[files] = noop +} # group preacct = ok # Executing section accounting from file /opt/freeradius/etc/raddb/sites-enabled/default +group accounting { [detail] expand: /var/log/radius/radacct/detail -> /var/log/radius/radacct/detail [detail] /var/log/radius/radacct/detail expands to /var/log/radius/radacct/detail [detail] expand: %t -> Thu Dec 21 14:43:54 2017 ++[detail] = ok [sql] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [sql] sql_set_user escaped user --> 'HSI/DEU.MYID.47110815' [sql] expand: %{Acct-Delay-Time} -> 0 [sql] expand: INSERT INTO radacct (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctstoptime, acctsessiontime, acctauthentic, connectinfo_start, connectinfo_stop, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, acctterminatecause, servicetype, framedprotocol, framedipaddress, acctstartdelay, acctstopdelay, xascendsessionsvrkey) VALUES ('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', '%S', NULL, '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', rlm_sql (sql): Reserving sql socket id: 2 rlm_sql (sql): Released sql socket id: 2 ++[sql] = ok [sql_log] Processing sql_log_accounting [sql_log] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [sql_log] expand: %{%{User-Name}:-DEFAULT} -> HSI/DEU.MYID.47110815 [sql_log] sql_set_user escaped user --> 'HSI/DEU.MYID.47110815' [sql_log] expand: INSERT INTO radacct (AcctSessionId, UserName, NASIPAddress, FramedIPAddress, AcctStartTime, AcctStopTime, AcctSessionTime, AcctTerminateCause) VALUES ('%{Acct-Session-Id}', '%{User-Name}', '%{NAS-IP-Address}', '%{Framed-IP-Address}', '%S', '0', '0', ''); -> INSERT INTO radacct (AcctSessionId, UserName, NASIPAddress, FramedIPAddress, AcctStartTime, AcctStopTime, AcctSessionTime, AcctTerminateCause) VALUES ('169', 'HSI/DEU.MYID.47110815', '212.88.155.47', '100.127.128.12', '2017-12-21 14:43:54', '0', '0', ''); [sql_log] expand: /var/log/radius/radacct/sql-relay -> /var/log/radius/radacct/sql-relay ++[sql_log] = ok ++[exec] = noop [attr_filter.accounting_response] expand: %{User-Name} -> HSI/DEU.MYID.47110815 attr_filter: Matched entry DEFAULT at line 12 ++[attr_filter.accounting_response] = updated +} # group accounting = updated Sending Accounting-Response of id 15 to 212.88.155.47 port 62146 Finished request 1. Cleaning up request 1 ID 15 with timestamp +140 Going to the next request Waking up in 3.6 seconds. rad_recv: Accounting-Request packet from host 212.88.155.47 port 62146, id=17, length=844 User-Name = "HSI/DEU.MYID.47110815" Acct-Status-Type = Interim-Update Acct-Session-Id = "169" Event-Timestamp = "Dec 21 2017 14:43:54 CET" Acct-Input-Octets = 0 Acct-Output-Octets = 0 Acct-Session-Time = 0 Acct-Input-Packets = 0 Acct-Output-Packets = 0 Acct-Delay-Time = 0 Service-Type = Framed-User ERX-Attr-177 = 0x506f72742073706565643a20313030303030306b Acct-Authentic = RADIUS Calling-Station-Id = "vs-mx5-testlab.6898/1ISAM-GSL-7302 eth 1/1/15/02/DEU.MYID.47110815" Class = 0x44656661756c74 ERX-Dhcp-Options = 0x3501013c1341564d2044484350432076392e30342e3339353d0fffd77f2ae8000300010896d77f2ae80c09667269747a2e626f78370b01030679f9d40f782a1a9e528c011c314953414d2d47534c2d373330322065746820312f312f31352f303202114445552e4d5949442e3437313130383135095900000de95481040000049f8204000044f683040000020084040000052085040000a0ab8604000000008704000004a08804000064608904000000008a04000000808b04000000088c04000000038d04000000088e0400000008 ERX-Dhcp-Gi-Address = 192.168.203.21 ERX-Dhcp-Mac-Addr = "0896.d77f.2ae8" Framed-IP-Address = 100.127.128.12 ERX-Input-Gigapkts = 0 Acct-Input-Gigawords = 0 NAS-Identifier = "vs-mx5-testlab.6898" NAS-Port = 284201092 NAS-Port-Id = "ge-1/1/3.demux0.3221225559:777-132" NAS-Port-Type = Ethernet ERX-Output-Gigapkts = 0 Acct-Output-Gigawords = 0 ERX-IPv6-Acct-Input-Octets = 0 ERX-IPv6-Acct-Output-Octets = 0 ERX-IPv6-Acct-Input-Packets = 0 ERX-IPv6-Acct-Output-Packets = 0 ERX-IPv6-Acct-Input-Gigawords = 0 ERX-IPv6-Acct-Output-Gigawords = 0 ERX-Virtual-Router-Name = "default:VRF-132" ERX-Pppoe-Description = "pppoe 08:96:d7:7f:2a:e8" ADSL-Agent-Circuit-Id = "1ISAM-GSL-7302 eth 1/1/15/02" ADSL-Agent-Remote-Id = "DEU.MYID.47110815" Actual-Data-Rate-Upstream = 1183 Actual-Data-Rate-Downstream = 17654 Minimum-Data-Rate-Upstream = 512 Minimum-Data-Rate-Downstream = 1312 Attainable-Data-Rate-Upstream = 41131 Attainable-Data-Rate-Downstream = 0 Maximum-Data-Rate-Upstream = 1184 Maximum-Data-Rate-Downstream = 25696 Minimum-Data-Rate-Upstream-Low-Power = 0 Minimum-Data-Rate-Downstream-Low-Power = 128 Maximum-Interleaving-Delay-Upstream = 8 Actual-Interleaving-Delay-Upstream = 3 Maximum-Interleaving-Delay-Downstream = 8 Actual-Interleaving-Delay-Downstream = 8 NAS-IP-Address = 212.88.155.47 # Executing section preacct from file /opt/freeradius/etc/raddb/sites-enabled/default +group preacct { ++[preprocess] = ok [acct_unique] Hashing 'NAS-Port = 284201092,NAS-Identifier = "vs-mx5-testlab.6898",NAS-IP-Address = 212.88.155.47,Acct-Session-Id = "169",User-Name = "HSI/DEU.MYID.47110815"' [acct_unique] Acct-Unique-Session-ID = "9f08cfde822d1d74". ++[acct_unique] = ok [suffix] No '@' in User-Name = "HSI/DEU.MYID.47110815", looking up realm NULL [suffix] No such realm "NULL" ++[suffix] = noop ++[files] = noop +} # group preacct = ok # Executing section accounting from file /opt/freeradius/etc/raddb/sites-enabled/default +group accounting { [detail] expand: /var/log/radius/radacct/detail -> /var/log/radius/radacct/detail [detail] /var/log/radius/radacct/detail expands to /var/log/radius/radacct/detail [detail] expand: %t -> Thu Dec 21 14:43:54 2017 ++[detail] = ok [sql] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [sql] sql_set_user escaped user --> 'HSI/DEU.MYID.47110815' [sql] expand: %{Acct-Session-Time} -> 0 [sql] expand: %{Acct-Input-Gigawords} -> 0 [sql] expand: %{Acct-Input-Octets} -> 0 [sql] expand: %{Acct-Output-Gigawords} -> 0 [sql] expand: %{Acct-Output-Octets} -> 0 [sql] expand: UPDATE radacct SET framedipaddress = '%{Framed-IP-Address}', acctsessiontime = '%{%{Acct-Session-Time}:-0}', acctinputoctets = '%{%{Acct-Input-Gigawords}:-0}' << 32 | '%{%{Acct-Input-Octets}:-0}', acctoutputoctets = '%{%{Acct-Output-Gigawords}:-0}' << 32 | '%{%{Acct-Output-Octets}:-0}' WHERE acctsessionid = '%{Acct-Session-Id}' AND username = '%{SQL-User-Name}' AND nasipaddress = '%{NAS-IP-Address}' -> UPDATE radacct SET framedipaddress = '100.127.128.12', acctsessiontime = '0', acctinputoctets = '0' << 32 | '0', acctoutputoctets = '0' << 32 | '0' WHERE acctsessionid = '169' AND username = 'HSI/DEU.MYID.47110815' rlm_sql (sql): Reserving sql socket id: 1 rlm_sql (sql): Released sql socket id: 1 ++[sql] = ok [sql_log] Processing sql_log_accounting [sql_log] expand: %{User-Name} -> HSI/DEU.MYID.47110815 [sql_log] expand: %{%{User-Name}:-DEFAULT} -> HSI/DEU.MYID.47110815 [sql_log] sql_set_user escaped user --> 'HSI/DEU.MYID.47110815' [sql_log] expand: INSERT INTO radacct (AcctSessionId, UserName, NASIPAddress, FramedIPAddress, AcctStartTime, AcctStopTime, AcctSessionTime, AcctTerminateCause) VALUES ('%{Acct-Session-Id}', '%{User-Name}', '%{NAS-IP-Address}', '%{Framed-IP-Address}', '0', '0', '%{Acct-Session-Time}',''); -> INSERT INTO radacct (AcctSessionId, UserName, NASIPAddress, FramedIPAddress, AcctStartTime, AcctStopTime, AcctSessionTime, AcctTerminateCause) VALUES ('169', 'HSI/DEU.MYID.47110815', '212.88.155.47', '100.127.128.12', '0', '0', '0',''); [sql_log] expand: /var/log/radius/radacct/sql-relay -> /var/log/radius/radacct/sql-relay ++[sql_log] = ok ++[exec] = noop [attr_filter.accounting_response] expand: %{User-Name} -> HSI/DEU.MYID.47110815 attr_filter: Matched entry DEFAULT at line 12 ++[attr_filter.accounting_response] = updated +} # group accounting = updated Sending Accounting-Response of id 17 to 212.88.155.47 port 62146 Finished request 2. Cleaning up request 2 ID 17 with timestamp +140 Going to the next request Waking up in 3.4 seconds. Cleaning up request 0 ID 14 with timestamp +138 Ready to process requests.