Attributes from DEFAULT user are always used although there's a more specific user

Alex D. listensammler at gmx.de
Thu Dec 21 15:40:58 CET 2017


Hello,

i try to use files and SQL for authentication. In my users file, i have 
a DEFAULT user and a specific user 'HSI/DEU.MYID.47110815' in SQL 
radcheck/radreply table. If my user is authenticated, Attributes from 
respective user *and* from DEFAULT user are replied to the nas although 
there is no explicit "Fall-Through = Yes" attribute. Is that an 
excpected behaviour ?
How can i achieve that only attributes from specific user is replied and 
those from DEFAULT user are only replied, when there is no accurate user.

root at quagga:/opt/freeradius/etc/raddb# more users
DEFAULT User-Name =~ "^HSI.*", Auth-Type := Accept
     Class = "Default"

reply-detail-20171221:
Thu Dec 21 14:43:52 2017
     Packet-Type = Access-Accept
     Class = 0x44656661756c74
     ERX-Virtual-Router-Name == "VRF-132"

mysql> select * from radreply;
+----+-----------------------+-------------------------+----+---------+
| id | username              | attribute               | op | value   |
+----+-----------------------+-------------------------+----+---------+
|  4 | HSI/DEU.MYID.47110815 | ERX-Virtual-Router-Name | == | VRF-132 |
+----+-----------------------+-------------------------+----+---------+

Freeradius version is 2.2.10. Attached you also find a debug from the 
mentioned authentication request.

Regards,
Alex
-------------- next part --------------
root at 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 at 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.


More information about the Freeradius-Users mailing list