Write 3GPP attributes accounting into mysql problem

bit1 bit1 at freemail.hu
Wed Jan 15 12:36:56 CET 2014


Thanks Arran.
Here it is (the prolog attached if needed):

rad_recv: Accounting-Request packet from host 127.0.0.1 port 44001, id=5, length=142
        Acct-Status-Type = Start
        Calling-Station-Id = "12312312312"
        Framed-IP-Address = 10.0.0.1
        Acct-Session-Id = "d43363551081e4a8"
        NAS-IP-Address = 10.11.11.77
        Framed-Protocol = GPRS-PDP-Context
        Called-Station-Id = "test-gprs"
        NAS-Identifier = "test-gprs"
        User-Name = "testuser"
        NAS-Port-Type = Virtual
        Service-Type = Framed-User
        3GPP-IMSI = "123123123123123"
Wed Jan 15 12:29:40 2014 : Info: # Executing section preacct from file /usr/local/etc/raddb/sites-enabled/default
Wed Jan 15 12:29:40 2014 : Info: +- entering group preacct {...}
Wed Jan 15 12:29:40 2014 : Info: ++[preprocess] returns ok
Wed Jan 15 12:29:40 2014 : Info: [acct_unique] WARNING: Attribute NAS-Port was not found in request, unique ID MAY be inconsistent
Wed Jan 15 12:29:40 2014 : Info: [acct_unique] Hashing ',NAS-Identifier = "test-gprs",NAS-IP-Address = 10.11.11.77,Acct-Session-Id = "d43363551081e4a8",User-Name = "testuser"'
Wed Jan 15 12:29:40 2014 : Info: [acct_unique] Acct-Unique-Session-ID = "6ce7054b4a610197".
Wed Jan 15 12:29:40 2014 : Info: ++[acct_unique] returns ok
Wed Jan 15 12:29:40 2014 : Info: [suffix] No '@' in User-Name = "testuser", looking up realm NULL
Wed Jan 15 12:29:40 2014 : Info: [suffix] No such realm "NULL"
Wed Jan 15 12:29:40 2014 : Info: ++[suffix] returns noop
Wed Jan 15 12:29:40 2014 : Info: # Executing section accounting from file /usr/local/etc/raddb/sites-enabled/default
Wed Jan 15 12:29:40 2014 : Info: +- entering group accounting {...}
Wed Jan 15 12:29:40 2014 : Info: [detail]       expand: %{Packet-Src-IP-Address} -> 127.0.0.1
Wed Jan 15 12:29:40 2014 : Info: [detail]       expand: /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radacct/127.0.0.1/detail-20140115
Wed Jan 15 12:29:40 2014 : Info: [detail] /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radacct/127.0.0.1/detail-20140115
Wed Jan 15 12:29:40 2014 : Info: [detail]       expand: %t -> Wed Jan 15 12:29:40 2014
Wed Jan 15 12:29:40 2014 : Info: ++[detail] returns ok
Wed Jan 15 12:29:40 2014 : Info: [sql]  expand: %{User-Name} -> testuser
Wed Jan 15 12:29:40 2014 : Info: [sql] sql_set_user escaped user --> 'testuser'
Wed Jan 15 12:29:40 2014 : Info: [sql]  expand: INSERT INTO radacct (test,fullpacket) VALUES ('%{request:3GPP-IMSI}','%Z') -> INSERT INTO radacct (test,fullpacket) VALUES ('','    Acct-Status-Type = Start        Calling-Station-Id = "12312312312"      Framed-IP-Address = 10.0.0.1        Acct-Session-Id = "d43363551081e4a8"    NAS-IP-Address = 10.11.11.77    Framed-Protocol = GPRS-PDP-Context      Called-Station-Id = "test-gprs"     NAS-Identifier = "test-gprs"    User-Name = "testuser"  NAS-Port-Type = Virtual         Service-Type = Framed-User      3GPP-IMSI = "123123123123123"       Acct-Unique-Session-Id = "6ce7054b4a610197"     SQL-User-Name = "testuser" ')
Wed Jan 15 12:29:40 2014 : Debug: rlm_sql (sql): Reserving sql socket id: 3
Wed Jan 15 12:29:40 2014 : Debug: rlm_sql (sql): Released sql socket id: 3
Wed Jan 15 12:29:40 2014 : Info: ++[sql] returns ok
Wed Jan 15 12:29:40 2014 : Info: ++[exec] returns noop
Wed Jan 15 12:29:40 2014 : Info: [attr_filter.accounting_response]      expand: %{User-Name} -> testuser
Wed Jan 15 12:29:40 2014 : Debug: attr_filter: Matched entry DEFAULT at line 12
Wed Jan 15 12:29:40 2014 : Info: ++[attr_filter.accounting_response] returns updated
Sending Accounting-Response of id 5 to 127.0.0.1 port 44001
Wed Jan 15 12:29:40 2014 : Info: Finished request 0.
Wed Jan 15 12:29:40 2014 : Info: Cleaning up request 0 ID 5 with timestamp +165
Wed Jan 15 12:29:40 2014 : Debug: Going to the next request
Wed Jan 15 12:29:40 2014 : Info: Ready to process requests.

Regards
Dohány Tibor
mailto:bit1 at freemail.hu

Arran Cudbard-Bell <a.cudbardb at freeradius.org> írta:
>Add the following to authorize {}, post the debug output:
>
>authorize {
>        update request {
>                3GPP-IMSI := 123123123123123
>        }   
>
>        update request {
>                Tmp-String-0 := "%{request:3GPP-IMSI}"  
>        } 
>}
>
>Arran Cudbard-Bell <a.cudbardb at freeradius.org>
>FreeRADIUS Development Team
>
>FD31 3077 42EC 7FCD 32FE 5EE2 56CF 27F9 30A8 CAA2
>
>-
>List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
-------------- next part --------------
 Wed Jan 15 12:26:55 2014 : Info: FreeRADIUS Version 2.2.0, for host amd64-portbld-freebsd9.1, built on Apr 16 2013 at 17:45:45
Wed Jan 15 12:26:55 2014 : Info: Copyright (C) 1999-2012 The FreeRADIUS server project and contributors.
Wed Jan 15 12:26:55 2014 : Info: There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
Wed Jan 15 12:26:55 2014 : Info: PARTICULAR PURPOSE.
Wed Jan 15 12:26:55 2014 : Info: You may redistribute copies of FreeRADIUS under the terms of the
Wed Jan 15 12:26:55 2014 : Info: GNU General Public License v2.
Wed Jan 15 12:26:55 2014 : Info: Starting - reading configuration files ...
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/radiusd.conf
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/proxy.conf
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/clients.conf
Wed Jan 15 12:26:55 2014 : Debug: including files in directory /usr/local/etc/raddb/modules/
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/wimax
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/always
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_filter
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/attr_rewrite
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/cache
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/chap
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/checkval
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/counter
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/cui
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/detail
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.example.com
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/detail.log
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/dhcp_sqlippool
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/sql/mysql/ippool-dhcp.conf
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/digest
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/dynamic_clients
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/echo
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/etc_group
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/exec
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/expiration
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/expr
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/files
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/inner-eap
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/ippool
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/krb5
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/ldap
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/linelog
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/otp
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/logintime
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2ip
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/mac2vlan
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/mschap
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/ntlm_auth
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/opendirectory
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/pam
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/pap
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/passwd
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/perl
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/policy
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/preprocess
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/radrelay
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/radutmp
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/realm
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/redis
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/rediswho
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/replicate
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/smbpasswd
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/smsotp
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/soh
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/sql_log
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/sqlcounter_expire_on_login
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/sradutmp
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/unix
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/modules/acct_unique
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/eap.conf
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/sql.conf
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/sql/mysql/dialup.conf
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/policy.conf
Wed Jan 15 12:26:55 2014 : Debug: including files in directory /usr/local/etc/raddb/sites-enabled/
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/default
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/inner-tunnel
Wed Jan 15 12:26:55 2014 : Debug: including configuration file /usr/local/etc/raddb/sites-enabled/control-socket
Wed Jan 15 12:26:55 2014 : Debug: main {
Wed Jan 15 12:26:55 2014 : Debug:       user = "freeradius"
Wed Jan 15 12:26:55 2014 : Debug:       group = "freeradius"
Wed Jan 15 12:26:55 2014 : Debug:       allow_core_dumps = no
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: including dictionary file /usr/local/etc/raddb/dictionary
Wed Jan 15 12:26:55 2014 : Debug: main {
Wed Jan 15 12:26:55 2014 : Debug:       name = "radiusd"
Wed Jan 15 12:26:55 2014 : Debug:       prefix = "/usr/local"
Wed Jan 15 12:26:55 2014 : Debug:       localstatedir = "/var"
Wed Jan 15 12:26:55 2014 : Debug:       sbindir = "/usr/local/sbin"
Wed Jan 15 12:26:55 2014 : Debug:       logdir = "/var/log"
Wed Jan 15 12:26:55 2014 : Debug:       run_dir = "/var/run/radiusd"
Wed Jan 15 12:26:55 2014 : Debug:       libdir = "/usr/local/lib/freeradius-2.2.0"
Wed Jan 15 12:26:55 2014 : Debug:       radacctdir = "/var/log/radacct"
Wed Jan 15 12:26:55 2014 : Debug:       hostname_lookups = no
Wed Jan 15 12:26:55 2014 : Debug:       max_request_time = 30
Wed Jan 15 12:26:55 2014 : Debug:       cleanup_delay = 5
Wed Jan 15 12:26:55 2014 : Debug:       max_requests = 1024
Wed Jan 15 12:26:55 2014 : Debug:       pidfile = "/var/run/radiusd/radiusd.pid"
Wed Jan 15 12:26:55 2014 : Debug:       checkrad = "/usr/local/sbin/checkrad"
Wed Jan 15 12:26:55 2014 : Debug:       debug_level = 0
Wed Jan 15 12:26:55 2014 : Debug:       proxy_requests = yes
Wed Jan 15 12:26:55 2014 : Debug:  log {
Wed Jan 15 12:26:55 2014 : Debug:       stripped_names = no
Wed Jan 15 12:26:55 2014 : Debug:       auth = no
Wed Jan 15 12:26:55 2014 : Debug:       auth_badpass = no
Wed Jan 15 12:26:55 2014 : Debug:       auth_goodpass = no
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  security {
Wed Jan 15 12:26:55 2014 : Debug:       max_attributes = 200
Wed Jan 15 12:26:55 2014 : Debug:       reject_delay = 1
Wed Jan 15 12:26:55 2014 : Debug:       status_server = yes
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: radiusd: #### Loading Realms and Home Servers ####
Wed Jan 15 12:26:55 2014 : Debug:  proxy server {
Wed Jan 15 12:26:55 2014 : Debug:       retry_delay = 5
Wed Jan 15 12:26:55 2014 : Debug:       retry_count = 3
Wed Jan 15 12:26:55 2014 : Debug:       default_fallback = no
Wed Jan 15 12:26:55 2014 : Debug:       dead_time = 120
Wed Jan 15 12:26:55 2014 : Debug:       wake_all_if_all_dead = no
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  home_server localhost {
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = 127.0.0.1
Wed Jan 15 12:26:55 2014 : Debug:       port = 1812
Wed Jan 15 12:26:55 2014 : Debug:       type = "auth"
Wed Jan 15 12:26:55 2014 : Debug:       secret = "testing123"
Wed Jan 15 12:26:55 2014 : Debug:       response_window = 20
Wed Jan 15 12:26:55 2014 : Debug:       max_outstanding = 65536
Wed Jan 15 12:26:55 2014 : Debug:       require_message_authenticator = yes
Wed Jan 15 12:26:55 2014 : Debug:       zombie_period = 40
Wed Jan 15 12:26:55 2014 : Debug:       status_check = "status-server"
Wed Jan 15 12:26:55 2014 : Debug:       ping_interval = 30
Wed Jan 15 12:26:55 2014 : Debug:       check_interval = 30
Wed Jan 15 12:26:55 2014 : Debug:       num_answers_to_alive = 3
Wed Jan 15 12:26:55 2014 : Debug:       num_pings_to_alive = 3
Wed Jan 15 12:26:55 2014 : Debug:       revive_interval = 120
Wed Jan 15 12:26:55 2014 : Debug:       status_check_timeout = 4
Wed Jan 15 12:26:55 2014 : Debug:   coa {
Wed Jan 15 12:26:55 2014 : Debug:       irt = 2
Wed Jan 15 12:26:55 2014 : Debug:       mrt = 16
Wed Jan 15 12:26:55 2014 : Debug:       mrc = 5
Wed Jan 15 12:26:55 2014 : Debug:       mrd = 30
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  home_server_pool my_auth_failover {
Wed Jan 15 12:26:55 2014 : Debug:       type = fail-over
Wed Jan 15 12:26:55 2014 : Debug:       home_server = localhost
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  realm example.com {
Wed Jan 15 12:26:55 2014 : Debug:       auth_pool = my_auth_failover
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  realm LOCAL {
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug: radiusd: #### Loading Clients ####
Wed Jan 15 12:26:55 2014 : Debug:  client localhost {
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = 127.0.0.1
Wed Jan 15 12:26:55 2014 : Debug:       require_message_authenticator = no
Wed Jan 15 12:26:55 2014 : Debug:       secret = "testing123"
Wed Jan 15 12:26:55 2014 : Debug:       nastype = "other"
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  client 10.0.0.0/8 {
Wed Jan 15 12:26:55 2014 : Debug:       require_message_authenticator = no
Wed Jan 15 12:26:55 2014 : Debug:       secret = "testing123"
Wed Jan 15 12:26:55 2014 : Debug:       shortname = "private-network-10"
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug:  client 10.5.96.128 {
Wed Jan 15 12:26:55 2014 : Debug:       require_message_authenticator = no
Wed Jan 15 12:26:55 2014 : Debug:       secret = "testing123"
Wed Jan 15 12:26:55 2014 : Debug:       shortname = "PGW0"
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug: radiusd: #### Instantiating modules ####
Wed Jan 15 12:26:55 2014 : Debug:  instantiate {
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_exec, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_exec
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "exec" from file /usr/local/etc/raddb/modules/exec
Wed Jan 15 12:26:55 2014 : Debug:   exec {
Wed Jan 15 12:26:55 2014 : Debug:       wait = no
Wed Jan 15 12:26:55 2014 : Debug:       input_pairs = "request"
Wed Jan 15 12:26:55 2014 : Debug:       shell_escape = yes
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_expr, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_expr
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "expr" from file /usr/local/etc/raddb/modules/expr
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_expiration, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_expiration
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "expiration" from file /usr/local/etc/raddb/modules/expiration
Wed Jan 15 12:26:55 2014 : Debug:   expiration {
Wed Jan 15 12:26:55 2014 : Debug:       reply-message = "Password Has Expired  "
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_logintime, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_logintime
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "logintime" from file /usr/local/etc/raddb/modules/logintime
Wed Jan 15 12:26:55 2014 : Debug:   logintime {
Wed Jan 15 12:26:55 2014 : Debug:       reply-message = "You are calling outside your allowed timespan  "
Wed Jan 15 12:26:55 2014 : Debug:       minimum-timeout = 60
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug: radiusd: #### Loading Virtual Servers ####
Wed Jan 15 12:26:55 2014 : Debug: server { # from file /usr/local/etc/raddb/radiusd.conf
Wed Jan 15 12:26:55 2014 : Debug:  modules {
Wed Jan 15 12:26:55 2014 : Debug:   Module: Creating Auth-Type = digest
Wed Jan 15 12:26:55 2014 : Debug:   Module: Creating Post-Auth-Type = REJECT
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking authenticate {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_pap, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_pap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "pap" from file /usr/local/etc/raddb/modules/pap
Wed Jan 15 12:26:55 2014 : Debug:   pap {
Wed Jan 15 12:26:55 2014 : Debug:       encryption_scheme = "auto"
Wed Jan 15 12:26:55 2014 : Debug:       auto_header = no
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_chap, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_chap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "chap" from file /usr/local/etc/raddb/modules/chap
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_mschap, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_mschap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "mschap" from file /usr/local/etc/raddb/modules/mschap
Wed Jan 15 12:26:55 2014 : Debug:   mschap {
Wed Jan 15 12:26:55 2014 : Debug:       use_mppe = yes
Wed Jan 15 12:26:55 2014 : Debug:       require_encryption = no
Wed Jan 15 12:26:55 2014 : Debug:       require_strong = no
Wed Jan 15 12:26:55 2014 : Debug:       with_ntdomain_hack = no
Wed Jan 15 12:26:55 2014 : Debug:       allow_retry = yes
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_digest, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_digest
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "digest" from file /usr/local/etc/raddb/modules/digest
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_unix, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_unix
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "unix" from file /usr/local/etc/raddb/modules/unix
Wed Jan 15 12:26:55 2014 : Debug:   unix {
Wed Jan 15 12:26:55 2014 : Debug:       radwtmp = "/var/log/radwtmp"
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_eap, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_eap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "eap" from file /usr/local/etc/raddb/eap.conf
Wed Jan 15 12:26:55 2014 : Debug:   eap {
Wed Jan 15 12:26:55 2014 : Debug:       default_eap_type = "md5"
Wed Jan 15 12:26:55 2014 : Debug:       timer_expire = 60
Wed Jan 15 12:26:55 2014 : Debug:       ignore_unknown_eap_types = no
Wed Jan 15 12:26:55 2014 : Debug:       cisco_accounting_username_bug = no
Wed Jan 15 12:26:55 2014 : Debug:       max_sessions = 4096
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_md5
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-md5
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_leap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-leap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_gtc
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-gtc
Wed Jan 15 12:26:55 2014 : Debug:    gtc {
Wed Jan 15 12:26:55 2014 : Debug:       challenge = "Password: "
Wed Jan 15 12:26:55 2014 : Debug:       auth_type = "PAP"
Wed Jan 15 12:26:55 2014 : Debug:    }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_tls
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-tls
Wed Jan 15 12:26:55 2014 : Debug:    tls {
Wed Jan 15 12:26:55 2014 : Debug:       rsa_key_exchange = no
Wed Jan 15 12:26:55 2014 : Debug:       dh_key_exchange = yes
Wed Jan 15 12:26:55 2014 : Debug:       rsa_key_length = 512
Wed Jan 15 12:26:55 2014 : Debug:       dh_key_length = 512
Wed Jan 15 12:26:55 2014 : Debug:       verify_depth = 0
Wed Jan 15 12:26:55 2014 : Debug:       CA_path = "/usr/local/etc/raddb/certs"
Wed Jan 15 12:26:55 2014 : Debug:       pem_file_type = yes
Wed Jan 15 12:26:55 2014 : Debug:       private_key_file = "/usr/local/etc/raddb/certs/server.pem"
Wed Jan 15 12:26:55 2014 : Debug:       certificate_file = "/usr/local/etc/raddb/certs/server.pem"
Wed Jan 15 12:26:55 2014 : Debug:       CA_file = "/usr/local/etc/raddb/certs/ca.pem"
Wed Jan 15 12:26:55 2014 : Debug:       private_key_password = "whatever"
Wed Jan 15 12:26:55 2014 : Debug:       dh_file = "/usr/local/etc/raddb/certs/dh"
Wed Jan 15 12:26:55 2014 : Debug:       random_file = "/usr/local/etc/raddb/certs/random"
Wed Jan 15 12:26:55 2014 : Debug:       fragment_size = 1024
Wed Jan 15 12:26:55 2014 : Debug:       include_length = yes
Wed Jan 15 12:26:55 2014 : Debug:       check_crl = no
Wed Jan 15 12:26:55 2014 : Debug:       cipher_list = "DEFAULT"
Wed Jan 15 12:26:55 2014 : Debug:       make_cert_command = "/usr/local/etc/raddb/certs/bootstrap"
Wed Jan 15 12:26:55 2014 : Debug:       ecdh_curve = "prime256v1"
Wed Jan 15 12:26:55 2014 : Debug:     cache {
Wed Jan 15 12:26:55 2014 : Debug:       enable = no
Wed Jan 15 12:26:55 2014 : Debug:       lifetime = 24
Wed Jan 15 12:26:55 2014 : Debug:       max_entries = 255
Wed Jan 15 12:26:55 2014 : Debug:     }
Wed Jan 15 12:26:55 2014 : Debug:     verify {
Wed Jan 15 12:26:55 2014 : Debug:     }
Wed Jan 15 12:26:55 2014 : Debug:     ocsp {
Wed Jan 15 12:26:55 2014 : Debug:       enable = no
Wed Jan 15 12:26:55 2014 : Debug:       override_cert_url = yes
Wed Jan 15 12:26:55 2014 : Debug:       url = "http://127.0.0.1/ocsp/"
Wed Jan 15 12:26:55 2014 : Debug:       use_nonce = yes
Wed Jan 15 12:26:55 2014 : Debug:       timeout = 0
Wed Jan 15 12:26:55 2014 : Debug:       softfail = no
Wed Jan 15 12:26:55 2014 : Debug:     }
Wed Jan 15 12:26:55 2014 : Debug:    }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_ttls
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-ttls
Wed Jan 15 12:26:55 2014 : Debug:    ttls {
Wed Jan 15 12:26:55 2014 : Debug:       default_eap_type = "md5"
Wed Jan 15 12:26:55 2014 : Debug:       copy_request_to_tunnel = no
Wed Jan 15 12:26:55 2014 : Debug:       use_tunneled_reply = no
Wed Jan 15 12:26:55 2014 : Debug:       virtual_server = "inner-tunnel"
Wed Jan 15 12:26:55 2014 : Debug:       include_length = yes
Wed Jan 15 12:26:55 2014 : Debug:    }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_peap
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-peap
Wed Jan 15 12:26:55 2014 : Debug:    peap {
Wed Jan 15 12:26:55 2014 : Debug:       default_eap_type = "mschapv2"
Wed Jan 15 12:26:55 2014 : Debug:       copy_request_to_tunnel = no
Wed Jan 15 12:26:55 2014 : Debug:       use_tunneled_reply = no
Wed Jan 15 12:26:55 2014 : Debug:       proxy_tunneled_request_as_eap = yes
Wed Jan 15 12:26:55 2014 : Debug:       virtual_server = "inner-tunnel"
Wed Jan 15 12:26:55 2014 : Debug:       soh = no
Wed Jan 15 12:26:55 2014 : Debug:    }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to sub-module rlm_eap_mschapv2
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating eap-mschapv2
Wed Jan 15 12:26:55 2014 : Debug:    mschapv2 {
Wed Jan 15 12:26:55 2014 : Debug:       with_ntdomain_hack = no
Wed Jan 15 12:26:55 2014 : Debug:       send_error = no
Wed Jan 15 12:26:55 2014 : Debug:    }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking authorize {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_preprocess, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_preprocess
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "preprocess" from file /usr/local/etc/raddb/modules/preprocess
Wed Jan 15 12:26:55 2014 : Debug:   preprocess {
Wed Jan 15 12:26:55 2014 : Debug:       huntgroups = "/usr/local/etc/raddb/huntgroups"
Wed Jan 15 12:26:55 2014 : Debug:       hints = "/usr/local/etc/raddb/hints"
Wed Jan 15 12:26:55 2014 : Debug:       with_ascend_hack = no
Wed Jan 15 12:26:55 2014 : Debug:       ascend_channels_per_line = 23
Wed Jan 15 12:26:55 2014 : Debug:       with_ntdomain_hack = no
Wed Jan 15 12:26:55 2014 : Debug:       with_specialix_jetstream_hack = no
Wed Jan 15 12:26:55 2014 : Debug:       with_cisco_vsa_hack = no
Wed Jan 15 12:26:55 2014 : Debug:       with_alvarion_vsa_hack = no
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/huntgroups
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/hints
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_realm, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_realm
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "suffix" from file /usr/local/etc/raddb/modules/realm
Wed Jan 15 12:26:55 2014 : Debug:   realm suffix {
Wed Jan 15 12:26:55 2014 : Debug:       format = "suffix"
Wed Jan 15 12:26:55 2014 : Debug:       delimiter = "@"
Wed Jan 15 12:26:55 2014 : Debug:       ignore_default = no
Wed Jan 15 12:26:55 2014 : Debug:       ignore_null = no
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_files, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_files
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "files" from file /usr/local/etc/raddb/modules/files
Wed Jan 15 12:26:55 2014 : Debug:   files {
Wed Jan 15 12:26:55 2014 : Debug:       usersfile = "/usr/local/etc/raddb/users"
Wed Jan 15 12:26:55 2014 : Debug:       acctusersfile = "/usr/local/etc/raddb/acct_users"
Wed Jan 15 12:26:55 2014 : Debug:       preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"
Wed Jan 15 12:26:55 2014 : Debug:       compat = "no"
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/users
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/acct_users
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/preproxy_users
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_sql, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_sql
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "sql" from file /usr/local/etc/raddb/sql.conf
Wed Jan 15 12:26:55 2014 : Debug:   sql {
Wed Jan 15 12:26:55 2014 : Debug:       driver = "rlm_sql_mysql"
Wed Jan 15 12:26:55 2014 : Debug:       server = "localhost"
Wed Jan 15 12:26:55 2014 : Debug:       port = "3306"
Wed Jan 15 12:26:55 2014 : Debug:       login = "radius"
Wed Jan 15 12:26:55 2014 : Debug:       password = "testing123"
Wed Jan 15 12:26:55 2014 : Debug:       radius_db = "radius"
Wed Jan 15 12:26:55 2014 : Debug:       read_groups = yes
Wed Jan 15 12:26:55 2014 : Debug:       sqltrace = no
Wed Jan 15 12:26:55 2014 : Debug:       sqltracefile = "/var/log/sqltrace.sql"
Wed Jan 15 12:26:55 2014 : Debug:       readclients = yes
Wed Jan 15 12:26:55 2014 : Debug:       deletestalesessions = yes
Wed Jan 15 12:26:55 2014 : Debug:       num_sql_socks = 5
Wed Jan 15 12:26:55 2014 : Debug:       lifetime = 0
Wed Jan 15 12:26:55 2014 : Debug:       max_queries = 0
Wed Jan 15 12:26:55 2014 : Debug:       sql_user_name = "%{User-Name}"
Wed Jan 15 12:26:55 2014 : Debug:       default_user_profile = ""
Wed Jan 15 12:26:55 2014 : Debug:       nas_query = "SELECT id, nasname, shortname, type, secret, server FROM nas"
Wed Jan 15 12:26:55 2014 : Debug:       authorize_check_query = "SELECT id, username, attribute, value, op           FROM radcheck           WHERE username = '%{SQL-User-Name}'           ORDER BY id"
Wed Jan 15 12:26:55 2014 : Debug:       authorize_reply_query = "SELECT id, username, attribute, value, op           FROM radreply           WHERE username = '%{SQL-User-Name}'           ORDER BY id"
Wed Jan 15 12:26:55 2014 : Debug:       authorize_group_check_query = "SELECT id, groupname, attribute,           Value, op           FROM radgroupcheck           WHERE groupname = '%{Sql-Group}'           ORDER BY id"
Wed Jan 15 12:26:55 2014 : Debug:       authorize_group_reply_query = "SELECT id, groupname, attribute,           value, op           FROM radgroupreply           WHERE groupname = '%{Sql-Group}'           ORDER BY id"
Wed Jan 15 12:26:55 2014 : Debug:       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'"
Wed Jan 15 12:26:55 2014 : Debug:       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}'"
Wed Jan 15 12:26:55 2014 : Debug:       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,  test,fullpacket)           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','0','%{request:3GPP-IMSI}','%Z')"
Wed Jan 15 12:26:55 2014 : Debug:       accounting_start_query = "INSERT INTO radacct (test,fullpacket) VALUES ('%{request:3GPP-IMSI}','%Z')"
Wed Jan 15 12:26:55 2014 : Debug:       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}'"
Wed Jan 15 12:26:55 2014 : Debug:       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}'"
Wed Jan 15 12:26:55 2014 : Debug:       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}')"
Wed Jan 15 12:26:55 2014 : Debug:       group_membership_query = "SELECT groupname           FROM radusergroup           WHERE username = '%{SQL-User-Name}'           ORDER BY priority"
Wed Jan 15 12:26:55 2014 : Debug:       connect_failure_retry_delay = 60
Wed Jan 15 12:26:55 2014 : Debug:       simul_count_query = ""
Wed Jan 15 12:26:55 2014 : Debug:       simul_verify_query = "SELECT radacctid, acctsessionid, username,                                nasipaddress, nasportid, framedipaddress,                                callingstationid, framedprotocol                                FROM radacct                                WHERE username = '%{SQL-User-Name}'                                AND acctstoptime IS NULL"
Wed Jan 15 12:26:55 2014 : Debug:       postauth_query = "INSERT INTO radpostauth                           (username, pass, reply, authdate)                           VALUES (                           '%{User-Name}',                           '%{%{User-Password}:-%{Chap-Password}}',                           '%{reply:Packet-Type}', '%S')"
Wed Jan 15 12:26:55 2014 : Debug:       safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Attempting to connect to radius at localhost:3306/radius
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): starting 0
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #0
Wed Jan 15 12:26:55 2014 : Info: rlm_sql_mysql: Starting connect to MySQL server for #0
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Connected new DB handle, #0
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): starting 1
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #1
Wed Jan 15 12:26:55 2014 : Info: rlm_sql_mysql: Starting connect to MySQL server for #1
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Connected new DB handle, #1
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): starting 2
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #2
Wed Jan 15 12:26:55 2014 : Info: rlm_sql_mysql: Starting connect to MySQL server for #2
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Connected new DB handle, #2
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): starting 3
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #3
Wed Jan 15 12:26:55 2014 : Info: rlm_sql_mysql: Starting connect to MySQL server for #3
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Connected new DB handle, #3
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): starting 4
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Attempting to connect rlm_sql_mysql #4
Wed Jan 15 12:26:55 2014 : Info: rlm_sql_mysql: Starting connect to MySQL server for #4
Wed Jan 15 12:26:55 2014 : Info: rlm_sql (sql): Connected new DB handle, #4
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Processing generate_sql_clients
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql) in generate_sql_clients: query is SELECT id, nasname, shortname, type, secret, server FROM nas
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Reserving sql socket id: 4
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Read entry nasname=10.111.1.67,shortname=subzero,secret=titok
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Adding client 10.111.1.67 (subzero, server=<none>) to clients list
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Read entry nasname=10.1.1.220,shortname=motolla,secret=n6gy0nt1tk0s
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Adding client 10.1.1.220 (motolla, server=<none>) to clients list
Wed Jan 15 12:26:55 2014 : Debug: rlm_sql (sql): Released sql socket id: 4
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking preacct {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_acct_unique, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_acct_unique
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "acct_unique" from file /usr/local/etc/raddb/modules/acct_unique
Wed Jan 15 12:26:55 2014 : Debug:   acct_unique {
Wed Jan 15 12:26:55 2014 : Debug:       key = "User-Name, Acct-Session-Id, NAS-IP-Address, NAS-Identifier, NAS-Port"
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking accounting {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_detail, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_detail
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "detail" from file /usr/local/etc/raddb/modules/detail
Wed Jan 15 12:26:55 2014 : Debug:   detail {
Wed Jan 15 12:26:55 2014 : Debug:       detailfile = "/var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
Wed Jan 15 12:26:55 2014 : Debug:       header = "%t"
Wed Jan 15 12:26:55 2014 : Debug:       detailperm = 384
Wed Jan 15 12:26:55 2014 : Debug:       dirperm = 493
Wed Jan 15 12:26:55 2014 : Debug:       locking = no
Wed Jan 15 12:26:55 2014 : Debug:       log_packet_header = no
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_attr_filter, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_attr_filter
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "attr_filter.accounting_response" from file /usr/local/etc/raddb/modules/attr_filter
Wed Jan 15 12:26:55 2014 : Debug:   attr_filter attr_filter.accounting_response {
Wed Jan 15 12:26:55 2014 : Debug:       attrsfile = "/usr/local/etc/raddb/attrs.accounting_response"
Wed Jan 15 12:26:55 2014 : Debug:       key = "%{User-Name}"
Wed Jan 15 12:26:55 2014 : Debug:       relaxed = no
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/attrs.accounting_response
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking session {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:     (Loaded rlm_radutmp, checking if it's valid)
Wed Jan 15 12:26:55 2014 : Debug:  Module: Linked to module rlm_radutmp
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "radutmp" from file /usr/local/etc/raddb/modules/radutmp
Wed Jan 15 12:26:55 2014 : Debug:   radutmp {
Wed Jan 15 12:26:55 2014 : Debug:       filename = "/var/log/radutmp"
Wed Jan 15 12:26:55 2014 : Debug:       username = "%{User-Name}"
Wed Jan 15 12:26:55 2014 : Debug:       case_sensitive = yes
Wed Jan 15 12:26:55 2014 : Debug:       check_with_nas = yes
Wed Jan 15 12:26:55 2014 : Debug:       perm = 384
Wed Jan 15 12:26:55 2014 : Debug:       callerid = yes
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking post-proxy {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking post-auth {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  Module: Instantiating module "attr_filter.access_reject" from file /usr/local/etc/raddb/modules/attr_filter
Wed Jan 15 12:26:55 2014 : Debug:   attr_filter attr_filter.access_reject {
Wed Jan 15 12:26:55 2014 : Debug:       attrsfile = "/usr/local/etc/raddb/attrs.access_reject"
Wed Jan 15 12:26:55 2014 : Debug:       key = "%{User-Name}"
Wed Jan 15 12:26:55 2014 : Debug:       relaxed = no
Wed Jan 15 12:26:55 2014 : Debug:   }
Wed Jan 15 12:26:55 2014 : Debug: reading pairlist file /usr/local/etc/raddb/attrs.access_reject
Wed Jan 15 12:26:55 2014 : Debug:  } # modules
Wed Jan 15 12:26:55 2014 : Debug: } # server
Wed Jan 15 12:26:55 2014 : Debug: server inner-tunnel { # from file /usr/local/etc/raddb/sites-enabled/inner-tunnel
Wed Jan 15 12:26:55 2014 : Debug:  modules {
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking authenticate {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking authorize {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking session {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking post-proxy {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  Module: Checking post-auth {...} for more modules to load
Wed Jan 15 12:26:55 2014 : Debug:  } # modules
Wed Jan 15 12:26:55 2014 : Debug: } # server
Wed Jan 15 12:26:55 2014 : Debug: radiusd: #### Opening IP addresses and Ports ####
Wed Jan 15 12:26:55 2014 : Debug: listen {
Wed Jan 15 12:26:55 2014 : Debug:       type = "auth"
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = *
Wed Jan 15 12:26:55 2014 : Debug:       port = 2812
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: listen {
Wed Jan 15 12:26:55 2014 : Debug:       type = "acct"
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = *
Wed Jan 15 12:26:55 2014 : Debug:       port = 2813
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: listen {
Wed Jan 15 12:26:55 2014 : Debug:       type = "auth"
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = 10.5.90.81
Wed Jan 15 12:26:55 2014 : Debug:       port = 1645
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: listen {
Wed Jan 15 12:26:55 2014 : Debug:       type = "acct"
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = 10.5.90.81
Wed Jan 15 12:26:55 2014 : Debug:       port = 1646
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: listen {
Wed Jan 15 12:26:55 2014 : Debug:       type = "control"
Wed Jan 15 12:26:55 2014 : Debug:  listen {
Wed Jan 15 12:26:55 2014 : Debug:       socket = "/var/run/radiusd/radiusd.sock"
Wed Jan 15 12:26:55 2014 : Debug:  }
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug: listen {
Wed Jan 15 12:26:55 2014 : Debug:       type = "auth"
Wed Jan 15 12:26:55 2014 : Debug:       ipaddr = 127.0.0.1
Wed Jan 15 12:26:55 2014 : Debug:       port = 18120
Wed Jan 15 12:26:55 2014 : Debug: }
Wed Jan 15 12:26:55 2014 : Debug:  ... adding new socket proxy address * port 44907
Wed Jan 15 12:26:55 2014 : Debug: Listening on authentication address * port 2812
Wed Jan 15 12:26:55 2014 : Debug: Listening on accounting address * port 2813
Wed Jan 15 12:26:55 2014 : Debug: Listening on authentication address 10.5.90.81 port 1645
Wed Jan 15 12:26:55 2014 : Debug: Listening on accounting address 10.5.90.81 port 1646
Wed Jan 15 12:26:55 2014 : Debug: Listening on command file /var/run/radiusd/radiusd.sock
Wed Jan 15 12:26:55 2014 : Debug: Listening on authentication address 127.0.0.1 port 18120 as server inner-tunnel
Wed Jan 15 12:26:55 2014 : Debug: Listening on proxy address * port 2814
Wed Jan 15 12:26:55 2014 : Info: Ready to process requests.


More information about the Freeradius-Users mailing list