Problem with Simultaneous-Use

Felipe Lopez Placencio felipe.lopez at pucv.cl
Tue Jun 9 15:17:18 CEST 2015


Dear Alan,

First of all, we apologize for the missunderstanding.
We are sorry for our bad English skills. We really appreciate your help and
we understand the discomfort of our poor response.

In no case our intention was not share the debug output, only that
initially we did not understand correctly your ask. Sorry once again...

Here is the output of the debug:

[root at radius3 ~]# radiusd -X
FreeRADIUS Version 2.1.12, for host i386-redhat-linux-gnu, built on Jan  9
2013 at 05:02:57
Copyright (C) 1999-2009 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 v2.
Starting - reading configuration files ...
including configuration file /etc/raddb/radiusd.conf
including configuration file /etc/raddb/proxy.conf
including configuration file /etc/raddb/clients.conf
including files in directory /etc/raddb/modules/
including configuration file /etc/raddb/modules/rediswho
including configuration file /etc/raddb/modules/preprocess
including configuration file /etc/raddb/modules/opendirectory
including configuration file /etc/raddb/modules/otp
including configuration file /etc/raddb/modules/passwd
including configuration file /etc/raddb/modules/pam
including configuration file /etc/raddb/modules/detail.example.com
including configuration file /etc/raddb/modules/mac2vlan
including configuration file /etc/raddb/modules/linelog
including configuration file /etc/raddb/modules/detail.log
including configuration file /etc/raddb/modules/echo
including configuration file /etc/raddb/modules/digest
including configuration file /etc/raddb/modules/sql_log
including configuration file /etc/raddb/modules/smbpasswd
including configuration file /etc/raddb/modules/acct_unique
including configuration file /etc/raddb/modules/ippool
including configuration file /etc/raddb/modules/expr
including configuration file /etc/raddb/modules/wimax
including configuration file /etc/raddb/modules/soh
including configuration file /etc/raddb/modules/mac2ip
including configuration file /etc/raddb/modules/attr_rewrite
including configuration file /etc/raddb/modules/sqlcounter_expire_on_login
including configuration file /etc/raddb/modules/exec
including configuration file /etc/raddb/modules/logintime
including configuration file /etc/raddb/modules/checkval
including configuration file /etc/raddb/modules/etc_group
including configuration file /etc/raddb/modules/radutmp
including configuration file /etc/raddb/modules/sradutmp
including configuration file /etc/raddb/modules/chap
including configuration file /etc/raddb/modules/detail
including configuration file /etc/raddb/modules/krb5
including configuration file /etc/raddb/modules/policy
including configuration file /etc/raddb/modules/cui
including configuration file /etc/raddb/modules/ldap
including configuration file /etc/raddb/modules/smsotp
including configuration file /etc/raddb/modules/replicate
including configuration file /etc/raddb/modules/files
including configuration file /etc/raddb/modules/mschap
including configuration file /etc/raddb/modules/expiration
including configuration file /etc/raddb/modules/dynamic_clients
including configuration file /etc/raddb/modules/always
including configuration file /etc/raddb/modules/redis
including configuration file /etc/raddb/modules/pap
including configuration file /etc/raddb/modules/attr_filter
including configuration file /etc/raddb/modules/ntlm_auth
including configuration file /etc/raddb/modules/unix
including configuration file /etc/raddb/modules/inner-eap
including configuration file /etc/raddb/modules/realm
including configuration file /etc/raddb/modules/perl
including configuration file /etc/raddb/modules/counter
including configuration file /etc/raddb/eap.conf
including configuration file /etc/raddb/sql.conf
including configuration file /etc/raddb/sql/mysql/dialup.conf
including configuration file /etc/raddb/sql/mysql/counter.conf
including configuration file /etc/raddb/policy.conf
including files in directory /etc/raddb/sites-enabled/
including configuration file /etc/raddb/sites-enabled/default
including configuration file /etc/raddb/sites-enabled/control-socket
main {
        user = "radiusd"
        group = "radiusd"
        allow_core_dumps = yes
}
Core dumps are enabled.
including dictionary file /etc/raddb/dictionary
main {
        name = "radiusd"
        prefix = "/usr"
        localstatedir = "/var"
        sbindir = "/usr/sbin"
        logdir = "/var/log/radius"
        run_dir = "/var/run/radiusd"
        libdir = "/usr/lib/freeradius"
        radacctdir = "/var/log/radius/radacct"
        hostname_lookups = no
        max_request_time = 30
        cleanup_delay = 5
        max_requests = 1024
        pidfile = "/var/run/radiusd/radiusd.pid"
        checkrad = "/usr/sbin/checkrad"
        debug_level = 0
        proxy_requests = yes
 log {
        stripped_names = no
        auth = yes
        auth_badpass = yes
        auth_goodpass = no
 }
 security {
        max_attributes = 200
        reject_delay = 1
        status_server = yes
 }
}
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 localhost {
        ipaddr = 127.0.0.1
        require_message_authenticator = no
        secret = "testing123"
        nastype = "other"
 }
 client 158.251.4.193 {
        require_message_authenticator = no
        secret = "testing123"
        shortname = "LAB-DSIC-PUCV"
        nastype = "other"
 }
radiusd: #### Instantiating modules ####
 instantiate {
 Module: Linked to module rlm_exec
 Module: Instantiating module "exec" from file /etc/raddb/modules/exec
  exec {
        wait = no
        input_pairs = "request"
        shell_escape = yes
  }
 Module: Linked to module rlm_expr
 Module: Instantiating module "expr" from file /etc/raddb/modules/expr
 Module: Linked to module rlm_expiration
 Module: Instantiating module "expiration" from file
/etc/raddb/modules/expiration
  expiration {
        reply-message = "Password Has Expired  "
  }
 Module: Linked to module rlm_logintime
 Module: Instantiating module "logintime" from file
/etc/raddb/modules/logintime
  logintime {
        reply-message = "You are calling outside your allowed timespan  "
        minimum-timeout = 60
  }
 }
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/raddb/radiusd.conf
 modules {
  Module: Creating Auth-Type = LDAP
  Module: Creating Post-Auth-Type = REJECT
 Module: Checking authenticate {...} for more modules to load
 Module: Linked to module rlm_ldap
 Module: Instantiating module "ldapDS01" from file /etc/raddb/modules/ldap
  ldap ldapDS01 {
        server = "158.251.4.163"
        port = 389
        password = "radius"
        identity = "uid=radius,dc=pucv,dc=mundo"
        net_timeout = 1
        timeout = 4
        timelimit = 3
        tls_mode = no
        start_tls = no
        tls_require_cert = "allow"
        basedn = "dc=pucv,dc=mundo"
        filter = "(uid=%{Stripped-User-Name:-%{User-Name}})"
        base_filter = "(objectclass=radiusprofile)"
        password_attribute = "userPassword"
        auto_header = no
        access_attr_used_for_allow = yes
        groupname_attribute = "cn"
        groupmembership_filter =
"(&(objectClass=GroupOfUniqueNames)(uniquemember=uid=%{User-Name},ou=logins,ou=cuentas,dc=pucv,dc=mundo))"
        dictionary_mapping = "/etc/raddb/ldap.attrmap"
        ldap_debug = 0
        ldap_connections_number = 5
        compare_check_items = no
        do_xlat = yes
        set_auth_type = yes
  }
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Creating new attribute ldapDS01-Ldap-Group
rlm_ldap: Registering ldap_groupcmp for ldapDS01-Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldapDS01
rlm_ldap: reading ldap<->radius mappings from file /etc/raddb/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type
rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use
rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id
rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id
rlm_ldap: LDAP lmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP ntPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP dBCSPwd mapped to RADIUS LM-Password
rlm_ldap: LDAP userPassword mapped to RADIUS Password-With-Header
rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT
rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration
rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address
rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type
rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol
rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address
rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask
rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route
rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing
rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id
rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU
rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression
rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host
rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service
rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port
rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number
rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id
rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network
rlm_ldap: LDAP radiusClass mapped to RADIUS Class
rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout
rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout
rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action
rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service
rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node
rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group
rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS
Framed-AppleTalk-Link
rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS
Framed-AppleTalk-Network
rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS
Framed-AppleTalk-Zone
rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit
rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port
rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message
rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type
rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type
rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS
Tunnel-Private-Group-Id
conns: 0x9340890
 Module: Instantiating module "ldapDS02" from file /etc/raddb/modules/ldap
  ldap ldapDS02 {
        server = "158.251.4.164"
        port = 389
        password = "radius"
        identity = "uid=radius,dc=pucv,dc=mundo"
        net_timeout = 1
        timeout = 4
        timelimit = 3
        tls_mode = no
        start_tls = no
        tls_require_cert = "allow"
        basedn = "dc=pucv,dc=mundo"
        filter = "(uid=%{Stripped-User-Name:-%{User-Name}})"
        base_filter = "(objectclass=radiusprofile)"
        password_attribute = "userPassword"
        auto_header = no
        access_attr_used_for_allow = yes
        groupname_attribute = "cn"
        groupmembership_filter =
"(&(objectClass=groupOfUniqueNames)(uniquemember=uid=%{User-Name},ou=logins,ou=cuentas,dc=pucv,dc=mundo))"
        dictionary_mapping = "/etc/raddb/ldap.attrmap"
        ldap_debug = 0
        ldap_connections_number = 5
        compare_check_items = no
        do_xlat = yes
        set_auth_type = yes
  }
rlm_ldap: Registering ldap_groupcmp for Ldap-Group
rlm_ldap: Creating new attribute ldapDS02-Ldap-Group
rlm_ldap: Registering ldap_groupcmp for ldapDS02-Ldap-Group
rlm_ldap: Registering ldap_xlat with xlat_name ldapDS02
rlm_ldap: reading ldap<->radius mappings from file /etc/raddb/ldap.attrmap
rlm_ldap: LDAP radiusCheckItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusReplyItem mapped to RADIUS $GENERIC$
rlm_ldap: LDAP radiusAuthType mapped to RADIUS Auth-Type
rlm_ldap: LDAP radiusSimultaneousUse mapped to RADIUS Simultaneous-Use
rlm_ldap: LDAP radiusCalledStationId mapped to RADIUS Called-Station-Id
rlm_ldap: LDAP radiusCallingStationId mapped to RADIUS Calling-Station-Id
rlm_ldap: LDAP lmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP ntPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP sambaLmPassword mapped to RADIUS LM-Password
rlm_ldap: LDAP sambaNtPassword mapped to RADIUS NT-Password
rlm_ldap: LDAP dBCSPwd mapped to RADIUS LM-Password
rlm_ldap: LDAP userPassword mapped to RADIUS Password-With-Header
rlm_ldap: LDAP acctFlags mapped to RADIUS SMB-Account-CTRL-TEXT
rlm_ldap: LDAP radiusExpiration mapped to RADIUS Expiration
rlm_ldap: LDAP radiusNASIpAddress mapped to RADIUS NAS-IP-Address
rlm_ldap: LDAP radiusServiceType mapped to RADIUS Service-Type
rlm_ldap: LDAP radiusFramedProtocol mapped to RADIUS Framed-Protocol
rlm_ldap: LDAP radiusFramedIPAddress mapped to RADIUS Framed-IP-Address
rlm_ldap: LDAP radiusFramedIPNetmask mapped to RADIUS Framed-IP-Netmask
rlm_ldap: LDAP radiusFramedRoute mapped to RADIUS Framed-Route
rlm_ldap: LDAP radiusFramedRouting mapped to RADIUS Framed-Routing
rlm_ldap: LDAP radiusFilterId mapped to RADIUS Filter-Id
rlm_ldap: LDAP radiusFramedMTU mapped to RADIUS Framed-MTU
rlm_ldap: LDAP radiusFramedCompression mapped to RADIUS Framed-Compression
rlm_ldap: LDAP radiusLoginIPHost mapped to RADIUS Login-IP-Host
rlm_ldap: LDAP radiusLoginService mapped to RADIUS Login-Service
rlm_ldap: LDAP radiusLoginTCPPort mapped to RADIUS Login-TCP-Port
rlm_ldap: LDAP radiusCallbackNumber mapped to RADIUS Callback-Number
rlm_ldap: LDAP radiusCallbackId mapped to RADIUS Callback-Id
rlm_ldap: LDAP radiusFramedIPXNetwork mapped to RADIUS Framed-IPX-Network
rlm_ldap: LDAP radiusClass mapped to RADIUS Class
rlm_ldap: LDAP radiusSessionTimeout mapped to RADIUS Session-Timeout
rlm_ldap: LDAP radiusIdleTimeout mapped to RADIUS Idle-Timeout
rlm_ldap: LDAP radiusTerminationAction mapped to RADIUS Termination-Action
rlm_ldap: LDAP radiusLoginLATService mapped to RADIUS Login-LAT-Service
rlm_ldap: LDAP radiusLoginLATNode mapped to RADIUS Login-LAT-Node
rlm_ldap: LDAP radiusLoginLATGroup mapped to RADIUS Login-LAT-Group
rlm_ldap: LDAP radiusFramedAppleTalkLink mapped to RADIUS
Framed-AppleTalk-Link
rlm_ldap: LDAP radiusFramedAppleTalkNetwork mapped to RADIUS
Framed-AppleTalk-Network
rlm_ldap: LDAP radiusFramedAppleTalkZone mapped to RADIUS
Framed-AppleTalk-Zone
rlm_ldap: LDAP radiusPortLimit mapped to RADIUS Port-Limit
rlm_ldap: LDAP radiusLoginLATPort mapped to RADIUS Login-LAT-Port
rlm_ldap: LDAP radiusReplyMessage mapped to RADIUS Reply-Message
rlm_ldap: LDAP radiusTunnelType mapped to RADIUS Tunnel-Type
rlm_ldap: LDAP radiusTunnelMediumType mapped to RADIUS Tunnel-Medium-Type
rlm_ldap: LDAP radiusTunnelPrivateGroupId mapped to RADIUS
Tunnel-Private-Group-Id
conns: 0x9341ef8
 Module: Checking authorize {...} for more modules to load
 Module: Linked to module rlm_preprocess
 Module: Instantiating module "preprocess" from file
/etc/raddb/modules/preprocess
  preprocess {
        huntgroups = "/etc/raddb/huntgroups"
        hints = "/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
  }
 Module: Linked to module rlm_detail
 Module: Instantiating module "auth_log" from file
/etc/raddb/modules/detail.log
  detail auth_log {
        detailfile =
"/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Linked to module rlm_realm
 Module: Instantiating module "suffix" from file /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 /etc/raddb/modules/files
  files {
        usersfile = "/etc/raddb/users"
        acctusersfile = "/etc/raddb/acct_users"
        preproxy_usersfile = "/etc/raddb/preproxy_users"
        compat = "no"
  }
 Module: Linked to module rlm_sql
 Module: Instantiating module "sql" from file /etc/raddb/sql.conf
  sql {
        driver = "rlm_sql_mysql"
        server = "localhost"
        port = ""
        login = "radius"
        password = "radpass"
        radius_db = "radius"
        read_groups = yes
        sqltrace = no
        sqltracefile = "/var/log/radius/sqltrace.sql"
        readclients = no
        deletestalesessions = yes
        num_sql_socks = 5
        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}',              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}',              '%{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}',              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}', '%{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 = "SELECT COUNT(*)
 FROM radacct                              WHERE username =
'%{SQL-User-Name}'                              AND acctstoptime IS NOT
NULL"
        simul_verify_query = "SELECT radacctid, acctsessionid, username,
                             nasipaddress, nasportid, framedipaddress,
                           callingstationid, framedprotocol
               FROM radacct                                WHERE username =
'%{SQL-User-Name}'                                AND acctstoptime IS NOT
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
rlm_sql (sql): starting 4
rlm_sql (sql): Attempting to connect rlm_sql_mysql #4
rlm_sql_mysql: Starting connect to MySQL server for #4
rlm_sql (sql): Connected new DB handle, #4
 Module: Checking preacct {...} for more modules to load
 Module: Linked to module rlm_acct_unique
 Module: Instantiating module "acct_unique" from file
/etc/raddb/modules/acct_unique
  acct_unique {
        key = "User-Name, Acct-Session-Id, NAS-IP-Address,
Client-IP-Address"
  }
 Module: Checking accounting {...} for more modules to load
 Module: Instantiating module "detail" from file /etc/raddb/modules/detail
  detail {
        detailfile =
"/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Linked to module rlm_radutmp
 Module: Instantiating module "radutmp" from file /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: Linked to module rlm_attr_filter
 Module: Instantiating module "attr_filter.accounting_response" from file
/etc/raddb/modules/attr_filter
  attr_filter attr_filter.accounting_response {
        attrsfile = "/etc/raddb/attrs.accounting_response"
        key = "%{User-Name}"
        relaxed = no
  }
 Module: Checking session {...} for more modules to load
 Module: Checking post-proxy {...} for more modules to load
 Module: Linked to module rlm_eap
 Module: Instantiating module "eap" from file /etc/raddb/eap.conf
  eap {
        default_eap_type = "md5"
        timer_expire = 60
        ignore_unknown_eap_types = no
        cisco_accounting_username_bug = no
        max_sessions = 4096
  }
 Module: Linked to sub-module rlm_eap_md5
 Module: Instantiating eap-md5
 Module: Linked to sub-module rlm_eap_leap
 Module: Instantiating eap-leap
 Module: Linked to sub-module rlm_eap_gtc
 Module: Instantiating eap-gtc
   gtc {
        challenge = "Password: "
        auth_type = "PAP"
   }
 Module: Linked to sub-module rlm_eap_tls
 Module: Instantiating eap-tls
   tls {
        rsa_key_exchange = no
        dh_key_exchange = yes
        rsa_key_length = 512
        dh_key_length = 512
        verify_depth = 0
        CA_path = "/etc/raddb/certs"
        pem_file_type = yes
        private_key_file = "/etc/raddb/certs/server.pem"
        certificate_file = "/etc/raddb/certs/server.pem"
        CA_file = "/etc/raddb/certs/ca.pem"
        private_key_password = "whatever"
        dh_file = "/etc/raddb/certs/dh"
        random_file = "/etc/raddb/certs/random"
        fragment_size = 1024
        include_length = yes
        check_crl = no
        cipher_list = "DEFAULT"
    cache {
        enable = no
        lifetime = 24
        max_entries = 255
    }
    verify {
    }
    ocsp {
        enable = no
        override_cert_url = yes
        url = "http://127.0.0.1/ocsp/"
    }
   }
 Module: Linked to sub-module rlm_eap_ttls
 Module: Instantiating eap-ttls
   ttls {
        default_eap_type = "md5"
        copy_request_to_tunnel = no
        use_tunneled_reply = no
        virtual_server = "inner-tunnel"
        include_length = yes
   }
 Module: Linked to sub-module rlm_eap_peap
 Module: Instantiating eap-peap
   peap {
        default_eap_type = "mschapv2"
        copy_request_to_tunnel = no
        use_tunneled_reply = no
        proxy_tunneled_request_as_eap = yes
        virtual_server = "inner-tunnel"
        soh = no
   }
 Module: Linked to sub-module rlm_eap_mschapv2
 Module: Instantiating eap-mschapv2
   mschapv2 {
        with_ntdomain_hack = no
        send_error = no
   }
 Module: Checking post-auth {...} for more modules to load
 Module: Instantiating module "reply_log" from file
/etc/raddb/modules/detail.log
  detail reply_log {
        detailfile =
"/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d"
        header = "%t"
        detailperm = 384
        dirperm = 493
        locking = no
        log_packet_header = no
  }
 Module: Instantiating module "attr_filter.access_reject" from file
/etc/raddb/modules/attr_filter
  attr_filter attr_filter.access_reject {
        attrsfile = "/etc/raddb/attrs.access_reject"
        key = "%{User-Name}"
        relaxed = no
  }
 } # 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 = "/var/run/radiusd/radiusd.sock"
 }
}
 ... adding new socket proxy address * port 50229
Listening on authentication address * port 1812
Listening on accounting address * port 1813
Listening on command file /var/run/radiusd/radiusd.sock
Listening on proxy address * port 1814
Ready to process requests.
rad_recv: Access-Request packet from host 158.251.4.193 port 32768, id=172,
length=144
        User-Name = "13634337"
        User-Password = "arvb4722"
        Service-Type = Login-User
        NAS-IP-Address = 158.251.4.193
        NAS-Port = 29
        NAS-Identifier = "wlc.ucv.cl"
        NAS-Port-Type = Wireless-802.11
        Airespace-Wlan-Id = 4
        Calling-Station-Id = "158.251.211.5"
        Called-Station-Id = "158.251.4.193"
        Message-Authenticator = 0x0bc7803d74769da77c9faf215b133a36
# Executing section authorize from file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log]      expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/158.251.4.193/auth-detail-20150609
[auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to /var/log/radius/radacct/158.251.4.193/auth-detail-20150609
[auth_log]      expand: %t -> Tue Jun  9 09:44:13 2015
++[auth_log] returns ok
[suffix] No '@' in User-Name = "13634337", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[files] users: Matched entry DEFAULT at line 1
++[files] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
rlm_sql (sql): Reserving sql socket id: 4
[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 = '13634337'           ORDER BY id
[sql]   expand: SELECT groupname           FROM radusergroup
WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT
groupname           FROM radusergroup           WHERE username = '13634337'
          ORDER BY priority
rlm_sql (sql): Released sql socket id: 4
[sql] User 13634337 not found
++[sql] returns notfound
++- entering load-balance group load-balance {...}
+++- entering policy redundant {...}
[ldapDS01] performing user authorization for 13634337
[ldapDS01] WARNING: Deprecated conditional expansion ":-".  See "man
unlang" for details
[ldapDS01]      ... expanding second conditional
[ldapDS01]      expand: %{User-Name} -> 13634337
[ldapDS01]      expand: (uid=%{Stripped-User-Name:-%{User-Name}}) ->
(uid=13634337)
[ldapDS01]      expand: dc=pucv,dc=mundo -> dc=pucv,dc=mundo
  [ldapDS01] ldap_get_conn: Checking Id: 0
  [ldapDS01] ldap_get_conn: Got Id: 0
  [ldapDS01] attempting LDAP reconnection
  [ldapDS01] (re)connect to 158.251.4.163:389, authentication 0
  [ldapDS01] bind as uid=radius,dc=pucv,dc=mundo/radius to 158.251.4.163:389
  [ldapDS01] waiting for bind result ...
  [ldapDS01] Bind was successful
  [ldapDS01] performing search in dc=pucv,dc=mundo, with filter
(uid=13634337)
[ldapDS01] Added User-Password = {SHA}zb4+Y9Lt4KJxSZyKG88Dy8S9j9g= in check
items
[ldapDS01] looking for check items in directory...
  [ldapDS01] userPassword -> Password-With-Header ==
"{SHA}zb4+Y9Lt4KJxSZyKG88Dy8S9j9g="
[ldapDS01] looking for reply items in directory...
[ldapDS01] user 13634337 authorized to use remote access
  [ldapDS01] ldap_release_conn: Release Id: 0
++++[ldapDS01] returns ok
+++- policy redundant returns ok
++- load-balance group load-balance returns ok
++[expiration] returns noop
++[logintime] returns noop
Found Auth-Type = LDAP
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!!    Replacing User-Password in config items with Cleartext-Password.
!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!! Please update your configuration so that the "known good"
!!!
!!! clear text password is in Cleartext-Password, and not in User-Password.
!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
# Executing group from file /etc/raddb/sites-enabled/default
+- entering group LDAP {...}
++- entering load-balance group load-balance {...}
+++- entering policy redundant {...}
[ldapDS01] login attempt by "13634337" with password "arvb4722"
[ldapDS01] user DN: uid=13634337,ou=Logins,ou=Cuentas,dc=pucv,dc=mundo
  [ldapDS01] (re)connect to 158.251.4.163:389, authentication 1
  [ldapDS01] bind as
uid=13634337,ou=Logins,ou=Cuentas,dc=pucv,dc=mundo/arvb4722 to
158.251.4.163:389
  [ldapDS01] waiting for bind result ...
  [ldapDS01] Bind was successful
[ldapDS01] user 13634337 authenticated succesfully
++++[ldapDS01] returns ok
+++- policy redundant returns ok
++- load-balance group load-balance returns ok
# Executing section session from file /etc/raddb/sites-enabled/default
+- entering group session {...}
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 13634337
++[radutmp] returns ok
Login OK: [13634337] (from client LAB-DSIC-PUCV port 29 cli 158.251.211.5)
# Executing section post-auth from file /etc/raddb/sites-enabled/default
+- entering group post-auth {...}
[reply_log]     expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/158.251.4.193/reply-detail-20150609
[reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/158.251.4.193/reply-detail-20150609
[reply_log]     expand: %t -> Tue Jun  9 09:44:13 2015
++[reply_log] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
[sql]   expand: %{User-Password} -> arvb4722
[sql]   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
(                           '13634337',
'arvb4722',                           'Access-Accept', '2015-06-09
09:44:13')
rlm_sql (sql) in sql_postauth: query is INSERT INTO radpostauth
              (username, pass, reply, authdate)
VALUES (                           '13634337',
'arvb4722',                           'Access-Accept', '2015-06-09
09:44:13')
rlm_sql (sql): Reserving sql socket id: 3
rlm_sql (sql): Released sql socket id: 3
++[sql] returns ok
++[exec] returns noop
Sending Access-Accept of id 172 to 158.251.4.193 port 32768
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 158.251.4.193 port 32768,
id=168, length=164
        User-Name = "13634337"
        NAS-Port = 29
        NAS-IP-Address = 158.251.4.193
        Framed-IP-Address = 158.251.211.5
        NAS-Identifier = "wlc.ucv.cl"
        Airespace-Wlan-Id = 4
        Acct-Session-Id = "5576dfd8/ac:81:12:4e:67:36/1314"
        Acct-Authentic = RADIUS
        Tunnel-Type:0 = VLAN
        Tunnel-Medium-Type:0 = IEEE-802
        Tunnel-Private-Group-Id:0 = "226"
        Acct-Status-Type = Start
        Calling-Station-Id = "158.251.211.5"
        Called-Station-Id = "158.251.4.193"
# Executing section preacct from file /etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'Client-IP-Address = 158.251.4.193,NAS-IP-Address =
158.251.4.193,Acct-Session-Id = "5576dfd8/ac:81:12:4e:67:36/1314",User-Name
= "13634337"'
[acct_unique] Acct-Unique-Session-ID = "11f2f471b08abf69".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "13634337", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
# Executing section accounting from file /etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail]        expand: %{Packet-Src-IP-Address} -> 158.251.4.193
[detail]        expand:
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
-> /var/log/radius/radacct/158.251.4.193/detail-20150609
[detail]
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to /var/log/radius/radacct/158.251.4.193/detail-20150609
[detail]        expand: %t -> Tue Jun  9 09:44:13 2015
++[detail] returns ok
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 13634337
++[radutmp] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
[sql]   expand: %{Acct-Delay-Time} ->
[sql]   ... expanding second conditional
[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] returns ok
++[exec] returns noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 13634337
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 168 to 158.251.4.193 port 32768
Finished request 1.
Cleaning up request 1 ID 168 with timestamp +24
Going to the next request
Waking up in 4.9 seconds.
Cleaning up request 0 ID 172 with timestamp +24
Ready to process requests.
rad_recv: Access-Request packet from host 158.251.4.193 port 32768, id=173,
length=144
        User-Name = "13634337"
        User-Password = "arvb4722"
        Service-Type = Login-User
        NAS-IP-Address = 158.251.4.193
        NAS-Port = 29
        NAS-Identifier = "wlc.ucv.cl"
        NAS-Port-Type = Wireless-802.11
        Airespace-Wlan-Id = 4
        Calling-Station-Id = "158.251.211.7"
        Called-Station-Id = "158.251.4.193"
        Message-Authenticator = 0xb457475766bc95674dd09ad72406f35c
# Executing section authorize from file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log]      expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/158.251.4.193/auth-detail-20150609
[auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to /var/log/radius/radacct/158.251.4.193/auth-detail-20150609
[auth_log]      expand: %t -> Tue Jun  9 09:44:34 2015
++[auth_log] returns ok
[suffix] No '@' in User-Name = "13634337", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[files] users: Matched entry DEFAULT at line 1
++[files] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
rlm_sql (sql): Reserving sql socket id: 1
[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 = '13634337'           ORDER BY id
[sql]   expand: SELECT groupname           FROM radusergroup
WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT
groupname           FROM radusergroup           WHERE username = '13634337'
          ORDER BY priority
rlm_sql (sql): Released sql socket id: 1
[sql] User 13634337 not found
++[sql] returns notfound
++- entering load-balance group load-balance {...}
+++- entering policy redundant {...}
[ldapDS01] performing user authorization for 13634337
[ldapDS01] WARNING: Deprecated conditional expansion ":-".  See "man
unlang" for details
[ldapDS01]      ... expanding second conditional
[ldapDS01]      expand: %{User-Name} -> 13634337
[ldapDS01]      expand: (uid=%{Stripped-User-Name:-%{User-Name}}) ->
(uid=13634337)
[ldapDS01]      expand: dc=pucv,dc=mundo -> dc=pucv,dc=mundo
  [ldapDS01] ldap_get_conn: Checking Id: 0
  [ldapDS01] ldap_get_conn: Got Id: 0
  [ldapDS01] performing search in dc=pucv,dc=mundo, with filter
(uid=13634337)
[ldapDS01] Added User-Password = {SHA}zb4+Y9Lt4KJxSZyKG88Dy8S9j9g= in check
items
[ldapDS01] looking for check items in directory...
  [ldapDS01] userPassword -> Password-With-Header ==
"{SHA}zb4+Y9Lt4KJxSZyKG88Dy8S9j9g="
[ldapDS01] looking for reply items in directory...
[ldapDS01] user 13634337 authorized to use remote access
  [ldapDS01] ldap_release_conn: Release Id: 0
++++[ldapDS01] returns ok
+++- policy redundant returns ok
++- load-balance group load-balance returns ok
++[expiration] returns noop
++[logintime] returns noop
Found Auth-Type = LDAP
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!!    Replacing User-Password in config items with Cleartext-Password.
!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!! Please update your configuration so that the "known good"
!!!
!!! clear text password is in Cleartext-Password, and not in User-Password.
!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
# Executing group from file /etc/raddb/sites-enabled/default
+- entering group LDAP {...}
++- entering load-balance group load-balance {...}
+++- entering policy redundant {...}
[ldapDS02] login attempt by "13634337" with password "arvb4722"
[ldapDS02] user DN: uid=13634337,ou=Logins,ou=Cuentas,dc=pucv,dc=mundo
  [ldapDS02] (re)connect to 158.251.4.164:389, authentication 1
  [ldapDS02] bind as
uid=13634337,ou=Logins,ou=Cuentas,dc=pucv,dc=mundo/arvb4722 to
158.251.4.164:389
  [ldapDS02] waiting for bind result ...
  [ldapDS02] Bind was successful
[ldapDS02] user 13634337 authenticated succesfully
++++[ldapDS02] returns ok
+++- policy redundant returns ok
++- load-balance group load-balance returns ok
# Executing section session from file /etc/raddb/sites-enabled/default
+- entering group session {...}
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 13634337
++[radutmp] returns ok
Login OK: [13634337] (from client LAB-DSIC-PUCV port 29 cli 158.251.211.7)
# Executing section post-auth from file /etc/raddb/sites-enabled/default
+- entering group post-auth {...}
[reply_log]     expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/158.251.4.193/reply-detail-20150609
[reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/158.251.4.193/reply-detail-20150609
[reply_log]     expand: %t -> Tue Jun  9 09:44:34 2015
++[reply_log] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
[sql]   expand: %{User-Password} -> arvb4722
[sql]   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
(                           '13634337',
'arvb4722',                           'Access-Accept', '2015-06-09
09:44:34')
rlm_sql (sql) in sql_postauth: query is INSERT INTO radpostauth
              (username, pass, reply, authdate)
VALUES (                           '13634337',
'arvb4722',                           'Access-Accept', '2015-06-09
09:44:34')
rlm_sql (sql): Reserving sql socket id: 0
rlm_sql (sql): Released sql socket id: 0
++[sql] returns ok
++[exec] returns noop
Sending Access-Accept of id 173 to 158.251.4.193 port 32768
Finished request 2.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 158.251.4.193 port 32768,
id=169, length=164
        User-Name = "13634337"
        NAS-Port = 29
        NAS-IP-Address = 158.251.4.193
        Framed-IP-Address = 158.251.211.7
        NAS-Identifier = "wlc.ucv.cl"
        Airespace-Wlan-Id = 4
        Acct-Session-Id = "5576dfec/00:17:9a:b0:26:ae/1315"
        Acct-Authentic = RADIUS
        Tunnel-Type:0 = VLAN
        Tunnel-Medium-Type:0 = IEEE-802
        Tunnel-Private-Group-Id:0 = "226"
        Acct-Status-Type = Start
        Calling-Station-Id = "158.251.211.7"
        Called-Station-Id = "158.251.4.193"
# Executing section preacct from file /etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'Client-IP-Address = 158.251.4.193,NAS-IP-Address =
158.251.4.193,Acct-Session-Id = "5576dfec/00:17:9a:b0:26:ae/1315",User-Name
= "13634337"'
[acct_unique] Acct-Unique-Session-ID = "9fc9da1901097859".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "13634337", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
# Executing section accounting from file /etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail]        expand: %{Packet-Src-IP-Address} -> 158.251.4.193
[detail]        expand:
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
-> /var/log/radius/radacct/158.251.4.193/detail-20150609
[detail]
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to /var/log/radius/radacct/158.251.4.193/detail-20150609
[detail]        expand: %t -> Tue Jun  9 09:44:34 2015
++[detail] returns ok
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 13634337
++[radutmp] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
[sql]   expand: %{Acct-Delay-Time} ->
[sql]   ... expanding second conditional
[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: 4
rlm_sql (sql): Released sql socket id: 4
++[sql] returns ok
++[exec] returns noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 13634337
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 169 to 158.251.4.193 port 32768
Finished request 3.
Cleaning up request 3 ID 169 with timestamp +45
Going to the next request
Waking up in 4.9 seconds.
Cleaning up request 2 ID 173 with timestamp +45
Ready to process requests.
rad_recv: Access-Request packet from host 158.251.4.193 port 32768, id=174,
length=144
        User-Name = "13634337"
        User-Password = "arvb4722"
        Service-Type = Login-User
        NAS-IP-Address = 158.251.4.193
        NAS-Port = 29
        NAS-Identifier = "wlc.ucv.cl"
        NAS-Port-Type = Wireless-802.11
        Airespace-Wlan-Id = 4
        Calling-Station-Id = "158.251.211.6"
        Called-Station-Id = "158.251.4.193"
        Message-Authenticator = 0x0d36f761168a50e3577db9be13dbc447
# Executing section authorize from file /etc/raddb/sites-enabled/default
+- entering group authorize {...}
++[preprocess] returns ok
[auth_log]      expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/158.251.4.193/auth-detail-20150609
[auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d
expands to /var/log/radius/radacct/158.251.4.193/auth-detail-20150609
[auth_log]      expand: %t -> Tue Jun  9 09:44:41 2015
++[auth_log] returns ok
[suffix] No '@' in User-Name = "13634337", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
[files] users: Matched entry DEFAULT at line 1
++[files] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
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 = '13634337'           ORDER BY id
[sql]   expand: SELECT groupname           FROM radusergroup
WHERE username = '%{SQL-User-Name}'           ORDER BY priority -> SELECT
groupname           FROM radusergroup           WHERE username = '13634337'
          ORDER BY priority
rlm_sql (sql): Released sql socket id: 3
[sql] User 13634337 not found
++[sql] returns notfound
++- entering load-balance group load-balance {...}
+++- entering policy redundant {...}
[ldapDS02] performing user authorization for 13634337
[ldapDS02] WARNING: Deprecated conditional expansion ":-".  See "man
unlang" for details
[ldapDS02]      ... expanding second conditional
[ldapDS02]      expand: %{User-Name} -> 13634337
[ldapDS02]      expand: (uid=%{Stripped-User-Name:-%{User-Name}}) ->
(uid=13634337)
[ldapDS02]      expand: dc=pucv,dc=mundo -> dc=pucv,dc=mundo
  [ldapDS02] ldap_get_conn: Checking Id: 0
  [ldapDS02] ldap_get_conn: Got Id: 0
  [ldapDS02] attempting LDAP reconnection
  [ldapDS02] (re)connect to 158.251.4.164:389, authentication 0
  [ldapDS02] bind as uid=radius,dc=pucv,dc=mundo/radius to 158.251.4.164:389
  [ldapDS02] waiting for bind result ...
  [ldapDS02] Bind was successful
  [ldapDS02] performing search in dc=pucv,dc=mundo, with filter
(uid=13634337)
[ldapDS02] Added User-Password = {SHA}zb4+Y9Lt4KJxSZyKG88Dy8S9j9g= in check
items
[ldapDS02] looking for check items in directory...
  [ldapDS02] userPassword -> Password-With-Header ==
"{SHA}zb4+Y9Lt4KJxSZyKG88Dy8S9j9g="
[ldapDS02] looking for reply items in directory...
[ldapDS02] user 13634337 authorized to use remote access
  [ldapDS02] ldap_release_conn: Release Id: 0
++++[ldapDS02] returns ok
+++- policy redundant returns ok
++- load-balance group load-balance returns ok
++[expiration] returns noop
++[logintime] returns noop
Found Auth-Type = LDAP
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!!    Replacing User-Password in config items with Cleartext-Password.
!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
!!! Please update your configuration so that the "known good"
!!!
!!! clear text password is in Cleartext-Password, and not in User-Password.
!!!
!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!
# Executing group from file /etc/raddb/sites-enabled/default
+- entering group LDAP {...}
++- entering load-balance group load-balance {...}
+++- entering policy redundant {...}
[ldapDS02] login attempt by "13634337" with password "arvb4722"
[ldapDS02] user DN: uid=13634337,ou=Logins,ou=Cuentas,dc=pucv,dc=mundo
  [ldapDS02] (re)connect to 158.251.4.164:389, authentication 1
  [ldapDS02] bind as
uid=13634337,ou=Logins,ou=Cuentas,dc=pucv,dc=mundo/arvb4722 to
158.251.4.164:389
  [ldapDS02] waiting for bind result ...
  [ldapDS02] Bind was successful
[ldapDS02] user 13634337 authenticated succesfully
++++[ldapDS02] returns ok
+++- policy redundant returns ok
++- load-balance group load-balance returns ok
# Executing section session from file /etc/raddb/sites-enabled/default
+- entering group session {...}
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 13634337
++[radutmp] returns ok
Login OK: [13634337] (from client LAB-DSIC-PUCV port 29 cli 158.251.211.6)
# Executing section post-auth from file /etc/raddb/sites-enabled/default
+- entering group post-auth {...}
[reply_log]     expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/158.251.4.193/reply-detail-20150609
[reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/158.251.4.193/reply-detail-20150609
[reply_log]     expand: %t -> Tue Jun  9 09:44:41 2015
++[reply_log] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
[sql]   expand: %{User-Password} -> arvb4722
[sql]   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
(                           '13634337',
'arvb4722',                           'Access-Accept', '2015-06-09
09:44:41')
rlm_sql (sql) in sql_postauth: query is INSERT INTO radpostauth
              (username, pass, reply, authdate)
VALUES (                           '13634337',
'arvb4722',                           'Access-Accept', '2015-06-09
09:44:41')
rlm_sql (sql): Reserving sql socket id: 2
rlm_sql (sql): Released sql socket id: 2
++[sql] returns ok
++[exec] returns noop
Sending Access-Accept of id 174 to 158.251.4.193 port 32768
Finished request 4.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 158.251.4.193 port 32768,
id=170, length=164
        User-Name = "13634337"
        NAS-Port = 29
        NAS-IP-Address = 158.251.4.193
        Framed-IP-Address = 158.251.211.6
        NAS-Identifier = "wlc.ucv.cl"
        Airespace-Wlan-Id = 4
        Acct-Session-Id = "5576dff3/c8:f7:33:d4:e5:90/1316"
        Acct-Authentic = RADIUS
        Tunnel-Type:0 = VLAN
        Tunnel-Medium-Type:0 = IEEE-802
        Tunnel-Private-Group-Id:0 = "226"
        Acct-Status-Type = Start
        Calling-Station-Id = "158.251.211.6"
        Called-Station-Id = "158.251.4.193"
# Executing section preacct from file /etc/raddb/sites-enabled/default
+- entering group preacct {...}
++[preprocess] returns ok
[acct_unique] Hashing 'Client-IP-Address = 158.251.4.193,NAS-IP-Address =
158.251.4.193,Acct-Session-Id = "5576dff3/c8:f7:33:d4:e5:90/1316",User-Name
= "13634337"'
[acct_unique] Acct-Unique-Session-ID = "b503f9f062ba51cf".
++[acct_unique] returns ok
[suffix] No '@' in User-Name = "13634337", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] returns noop
# Executing section accounting from file /etc/raddb/sites-enabled/default
+- entering group accounting {...}
[detail]        expand: %{Packet-Src-IP-Address} -> 158.251.4.193
[detail]        expand:
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
-> /var/log/radius/radacct/158.251.4.193/detail-20150609
[detail]
/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d
expands to /var/log/radius/radacct/158.251.4.193/detail-20150609
[detail]        expand: %t -> Tue Jun  9 09:44:41 2015
++[detail] returns ok
[radutmp]       expand: /var/log/radius/radutmp -> /var/log/radius/radutmp
[radutmp]       expand: %{User-Name} -> 13634337
++[radutmp] returns ok
[sql]   expand: %{User-Name} -> 13634337
[sql] sql_set_user escaped user --> '13634337'
[sql]   expand: %{Acct-Delay-Time} ->
[sql]   ... expanding second conditional
[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: 1
rlm_sql (sql): Released sql socket id: 1
++[sql] returns ok
++[exec] returns noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 13634337
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 170 to 158.251.4.193 port 32768
Finished request 5.
Cleaning up request 5 ID 170 with timestamp +52
Going to the next request
Waking up in 4.9 seconds.
Cleaning up request 4 ID 174 with timestamp +52
Ready to process requests.

################################################################################

If you have any request, feel free of asking us.
Thanks in advance once again.

Finally, I would like to thanks to Олег Кобелев. We will also try what he
said.

Kind regards
    Felipe López P.


2015-06-08 15:39 GMT-03:00 Alan DeKok <aland at deployingradius.com>:

> On Jun 8, 2015, at 2:26 PM, Felipe Lopez Placencio <felipe.lopez at pucv.cl>
> wrote:
> >   Yes, all users can log in 3 times, as we configured in the WLC Cisco,
> > but we want to restrict one group in 2 connections.
>
>   You've already said that.
>
> >> After the user logs in, does the client send an accounting "start"
> > packet?
> >
> >   We suppose. The log detail shows:
>
>   <sigh>  The debug output is useful.  The detail log isn't useful.
>
>   Why?  Because the debug output shows EVERYTHING.
>
> >> Is that packet stored in a DB (radutmp, sql, etc.)
> >
> >   Yes, the packet is stored in radutmp, but only the last connection.
> > Thats mean that appears only one input.
>
>   That's the problem, then.  The AP is telling FreeRADIUS that the user is
> logging in once.  And then again, from the same connection.
>
>   So the user *isn't* logging in twice, from two different connections.
> He's logging in twice from the SAME connection.
>
>   This isn't magic.  FreeRADIUS can't magically know your intent.  It
> can't know that even though the NAS *claims* the two logins are the same...
> that you think they're actually different.
>
>   So... are the requests the same?  You clearly don't know.  Because you
> haven't looked at the debug log.  Or if you did look there, you didn't
> notice the *important* pieces.
>
> >> Does the server discover that the user is already logged in?
> >
> >   We think no, because doesn't work.
>
>   "It doesn't work" is almost always a bad answer.
>
> >> What happens then?
> >
> >   We don't know for what reason does not work.
>
>   Yes, you do.  See above.
>
> >> The debug output, as suggested in the FAQ, "man" page, web pages, and
> > daily on this list?
> >
> >    The debug doesn't show any error or warning.
>
>   <sigh>  You can't get it to work.  I ask for the debug log, and you say
> "it doesn't show anything".
>
>   Really?  You're asking for help, and when I tell you I need the debug
> output, your response is essentially "No, you don't".
>
>   That's rude.  I've never understood why some people ask for help, and
> then fight against every attempt to help them.
>
>   Alan DeKok.
>
>
> -
> List info/subscribe/unsubscribe? See
> http://www.freeradius.org/list/users.html


More information about the Freeradius-Users mailing list