FreeRADIUS Version 2.1.8, for host i386-redhat-linux-gnu, built on Jan 19 2010 at 18:23:59 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/clients.conf including files in directory /etc/raddb/modules/ including configuration file /etc/raddb/modules/mac2vlan including configuration file /etc/raddb/modules/acct_unique including configuration file /etc/raddb/modules/files including configuration file /etc/raddb/modules/detail including configuration file /etc/raddb/modules/smbpasswd including configuration file /etc/raddb/modules/ippool including configuration file /etc/raddb/modules/inner-eap including configuration file /etc/raddb/modules/sqlcounter_expire_on_login including configuration file /etc/raddb/modules/counter including configuration file /etc/raddb/modules/preprocess including configuration file /etc/raddb/modules/sradutmp including configuration file /etc/raddb/modules/chap including configuration file /etc/raddb/modules/digest including configuration file /etc/raddb/modules/otp including configuration file /etc/raddb/modules/etc_group including configuration file /etc/raddb/modules/smsotp including configuration file /etc/raddb/modules/detail.example.com including configuration file /etc/raddb/modules/passwd including configuration file /etc/raddb/modules/mac2ip including configuration file /etc/raddb/modules/sql_log including configuration file /etc/raddb/modules/always including configuration file /etc/raddb/modules/perl including configuration file /etc/raddb/modules/wimax including configuration file /etc/raddb/modules/logintime including configuration file /etc/raddb/modules/checkval including configuration file /etc/raddb/modules/realm including configuration file /etc/raddb/modules/expiration including configuration file /etc/raddb/modules/ntlm_auth including configuration file /etc/raddb/modules/policy including configuration file /etc/raddb/modules/pam including configuration file /etc/raddb/modules/cui including configuration file /etc/raddb/modules/detail.log including configuration file /etc/raddb/modules/pap including configuration file /etc/raddb/modules/linelog including configuration file /etc/raddb/modules/mschap including configuration file /etc/raddb/modules/unix including configuration file /etc/raddb/modules/attr_rewrite including configuration file /etc/raddb/modules/echo including configuration file /etc/raddb/modules/exec including configuration file /etc/raddb/modules/attr_filter including configuration file /etc/raddb/modules/radutmp including configuration file /etc/raddb/modules/expr including configuration file /etc/raddb/sql.conf including configuration file /etc/raddb/sql/mysql/dialup.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/proxy-accounting main { user = "radiusd" group = "radiusd" allow_core_dumps = no } including dictionary file /etc/raddb/dictionary main { prefix = "/usr" localstatedir = "/var" logdir = "/var/log/radius" 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 = yes } security { max_attributes = 200 reject_delay = 1 status_server = yes } } radiusd: #### Loading Realms and Home Servers #### home_server obsidian2.mindspring.co.za { ipaddr = *****.102.32 port = 1646 type = "acct" secret = "*****" response_window = 6 max_outstanding = 65536 zombie_period = 40 status_check = "request" ping_interval = 30 check_interval = 30 num_answers_to_alive = 3 num_pings_to_alive = 3 revive_interval = 300 status_check_timeout = 4 username = "*****" irt = 2 mrt = 16 mrc = 5 mrd = 30 } home_server_pool acct_pool.mindspring.co.za { type = fail-over home_server = obsidian2.mindspring.co.za } realm msp.co.za { acct_pool = acct_pool.mindspring.co.za } realm dslmsp.co.za { acct_pool = acct_pool.mindspring.co.za } radiusd: #### Loading Clients #### client localhost { ipaddr = 127.0.0.1 require_message_authenticator = no secret = "*****" nastype = "other" } client 196.25.102.32 { require_message_authenticator = no secret = "***" } client 196.41.123.202 { require_message_authenticator = no secret = "***" } client 196.43.3.70 { require_message_authenticator = no secret = "***" } client 196.43.3.71 { require_message_authenticator = no secret = "***" } client 196.43.3.76 { require_message_authenticator = no secret = "***" } client 196.43.3.77 { require_message_authenticator = no secret = "***" } client 196.43.3.86 { require_message_authenticator = no secret = "***" } client 196.43.3.87 { require_message_authenticator = no secret = "***" } client 196.43.1.86 { require_message_authenticator = no secret = "***" } client 196.43.1.87 { require_message_authenticator = no secret = "***" } client 196.43.1.88 { require_message_authenticator = no secret = "***" } client 196.43.1.89 { require_message_authenticator = no secret = "***" } client 196.43.1.91 { require_message_authenticator = no secret = "***" } client 196.43.1.92 { require_message_authenticator = no secret = "***" } client 196.43.1.93 { require_message_authenticator = no secret = "***" } client 196.41.124.10 { require_message_authenticator = no secret = "***" } client 196.41.124.11 { require_message_authenticator = no secret = "***" } radiusd: #### Instantiating modules #### instantiate { Module: Linked to module rlm_exec Module: Instantiating exec exec { wait = no input_pairs = "request" shell_escape = yes } Module: Linked to module rlm_expr Module: Instantiating expr Module: Linked to module rlm_expiration Module: Instantiating expiration expiration { reply-message = "Password Has Expired " } Module: Linked to module rlm_logintime Module: Instantiating logintime logintime { reply-message = "You are calling outside your allowed timespan " minimum-timeout = 60 } } radiusd: #### Loading Virtual Servers #### server { modules { Module: Checking authenticate {...} for more modules to load Module: Linked to module rlm_pap Module: Instantiating pap pap { encryption_scheme = "auto" auto_header = no } Module: Linked to module rlm_chap Module: Instantiating chap Module: Linked to module rlm_mschap Module: Instantiating mschap mschap { use_mppe = yes require_encryption = no require_strong = no with_ntdomain_hack = no } Module: Checking authorize {...} for more modules to load Module: Linked to module rlm_sql Module: Instantiating sql sql { driver = "rlm_sql_mysql" server = "localhost" port = "" login = "***" password = "***" radius_db = "freeradius" read_groups = yes sqltrace = yes readclients = no deletestalesessions = yes num_sql_socks = 32 lifetime = 0 max_queries = 1024 sql_user_name = "%{User-Name}" default_user_profile = "" nas_query = "SELECT id, nasname, shortname, type, secret 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_failover (acctsessionid, acctuniqueid, username, realm, nasipaddress, nasportid, nasporttype, acctstarttime, acctsessiontime, acctauthentic, connectinfo_start, acctinputoctets, acctoutputoctets, calledstationid, callingstationid, servicetype, framedprotocol, framedipaddress, acctstartdelay, xascendsessionsvrkey, class) 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}', '%{Class}')" accounting_start_query = " INSERT INTO radacct (radacctid, 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, class) 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}','%{Class}')" 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 IFNULL(( SELECT groupname FROM radusergroup WHERE username = '%{SQL-User-Name}' AND groupname = '%{%{Telkom-Access-Type}:-DIAL}' ), 'REJECT' )" connect_failure_retry_delay = 60 simul_count_query = "" simul_verify_query = "SELECT radacctid, acctsessionid, username, nasipaddress, nasportid, framedipaddress, callingstationid, framedprotocol FROM radacct WHERE username = '%{SQL-User-Name}' AND acctstoptime IS NULL" postauth_query = "INSERT INTO radpostauth (username, pass, reply, authdate) VALUES ( '%{User-Name}', '%{%{User-Password}:-%{Chap-Password}}', '%{reply:Packet-Type}', '%S')" safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /" } rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and linked rlm_sql (sql): Attempting to connect to freeradius@localhost:/freeradius 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 rlm_sql (sql): starting 5 rlm_sql (sql): Attempting to connect rlm_sql_mysql #5 rlm_sql_mysql: Starting connect to MySQL server for #5 rlm_sql (sql): Connected new DB handle, #5 rlm_sql (sql): starting 6 rlm_sql (sql): Attempting to connect rlm_sql_mysql #6 rlm_sql_mysql: Starting connect to MySQL server for #6 rlm_sql (sql): Connected new DB handle, #6 rlm_sql (sql): starting 7 rlm_sql (sql): Attempting to connect rlm_sql_mysql #7 rlm_sql_mysql: Starting connect to MySQL server for #7 rlm_sql (sql): Connected new DB handle, #7 rlm_sql (sql): starting 8 rlm_sql (sql): Attempting to connect rlm_sql_mysql #8 rlm_sql_mysql: Starting connect to MySQL server for #8 rlm_sql (sql): Connected new DB handle, #8 rlm_sql (sql): starting 9 rlm_sql (sql): Attempting to connect rlm_sql_mysql #9 rlm_sql_mysql: Starting connect to MySQL server for #9 rlm_sql (sql): Connected new DB handle, #9 rlm_sql (sql): starting 10 rlm_sql (sql): Attempting to connect rlm_sql_mysql #10 rlm_sql_mysql: Starting connect to MySQL server for #10 rlm_sql (sql): Connected new DB handle, #10 rlm_sql (sql): starting 11 rlm_sql (sql): Attempting to connect rlm_sql_mysql #11 rlm_sql_mysql: Starting connect to MySQL server for #11 rlm_sql (sql): Connected new DB handle, #11 rlm_sql (sql): starting 12 rlm_sql (sql): Attempting to connect rlm_sql_mysql #12 rlm_sql_mysql: Starting connect to MySQL server for #12 rlm_sql (sql): Connected new DB handle, #12 rlm_sql (sql): starting 13 rlm_sql (sql): Attempting to connect rlm_sql_mysql #13 rlm_sql_mysql: Starting connect to MySQL server for #13 rlm_sql (sql): Connected new DB handle, #13 rlm_sql (sql): starting 14 rlm_sql (sql): Attempting to connect rlm_sql_mysql #14 rlm_sql_mysql: Starting connect to MySQL server for #14 rlm_sql (sql): Connected new DB handle, #14 rlm_sql (sql): starting 15 rlm_sql (sql): Attempting to connect rlm_sql_mysql #15 rlm_sql_mysql: Starting connect to MySQL server for #15 rlm_sql (sql): Connected new DB handle, #15 rlm_sql (sql): starting 16 rlm_sql (sql): Attempting to connect rlm_sql_mysql #16 rlm_sql_mysql: Starting connect to MySQL server for #16 rlm_sql (sql): Connected new DB handle, #16 rlm_sql (sql): starting 17 rlm_sql (sql): Attempting to connect rlm_sql_mysql #17 rlm_sql_mysql: Starting connect to MySQL server for #17 rlm_sql (sql): Connected new DB handle, #17 rlm_sql (sql): starting 18 rlm_sql (sql): Attempting to connect rlm_sql_mysql #18 rlm_sql_mysql: Starting connect to MySQL server for #18 rlm_sql (sql): Connected new DB handle, #18 rlm_sql (sql): starting 19 rlm_sql (sql): Attempting to connect rlm_sql_mysql #19 rlm_sql_mysql: Starting connect to MySQL server for #19 rlm_sql (sql): Connected new DB handle, #19 rlm_sql (sql): starting 20 rlm_sql (sql): Attempting to connect rlm_sql_mysql #20 rlm_sql_mysql: Starting connect to MySQL server for #20 rlm_sql (sql): Connected new DB handle, #20 rlm_sql (sql): starting 21 rlm_sql (sql): Attempting to connect rlm_sql_mysql #21 rlm_sql_mysql: Starting connect to MySQL server for #21 rlm_sql (sql): Connected new DB handle, #21 rlm_sql (sql): starting 22 rlm_sql (sql): Attempting to connect rlm_sql_mysql #22 rlm_sql_mysql: Starting connect to MySQL server for #22 rlm_sql (sql): Connected new DB handle, #22 rlm_sql (sql): starting 23 rlm_sql (sql): Attempting to connect rlm_sql_mysql #23 rlm_sql_mysql: Starting connect to MySQL server for #23 rlm_sql (sql): Connected new DB handle, #23 rlm_sql (sql): starting 24 rlm_sql (sql): Attempting to connect rlm_sql_mysql #24 rlm_sql_mysql: Starting connect to MySQL server for #24 rlm_sql (sql): Connected new DB handle, #24 rlm_sql (sql): starting 25 rlm_sql (sql): Attempting to connect rlm_sql_mysql #25 rlm_sql_mysql: Starting connect to MySQL server for #25 rlm_sql (sql): Connected new DB handle, #25 rlm_sql (sql): starting 26 rlm_sql (sql): Attempting to connect rlm_sql_mysql #26 rlm_sql_mysql: Starting connect to MySQL server for #26 rlm_sql (sql): Connected new DB handle, #26 rlm_sql (sql): starting 27 rlm_sql (sql): Attempting to connect rlm_sql_mysql #27 rlm_sql_mysql: Starting connect to MySQL server for #27 rlm_sql (sql): Connected new DB handle, #27 rlm_sql (sql): starting 28 rlm_sql (sql): Attempting to connect rlm_sql_mysql #28 rlm_sql_mysql: Starting connect to MySQL server for #28 rlm_sql (sql): Connected new DB handle, #28 rlm_sql (sql): starting 29 rlm_sql (sql): Attempting to connect rlm_sql_mysql #29 rlm_sql_mysql: Starting connect to MySQL server for #29 rlm_sql (sql): Connected new DB handle, #29 rlm_sql (sql): starting 30 rlm_sql (sql): Attempting to connect rlm_sql_mysql #30 rlm_sql_mysql: Starting connect to MySQL server for #30 rlm_sql (sql): Connected new DB handle, #30 rlm_sql (sql): starting 31 rlm_sql (sql): Attempting to connect rlm_sql_mysql #31 rlm_sql_mysql: Starting connect to MySQL server for #31 rlm_sql (sql): Connected new DB handle, #31 Module: Checking preacct {...} for more modules to load Module: Linked to module rlm_preprocess Module: Instantiating 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_acct_unique Module: Instantiating acct_unique acct_unique { key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port" } Module: Linked to module rlm_realm Module: Instantiating suffix realm suffix { format = "suffix" delimiter = "@" ignore_default = no ignore_null = no } Module: Linked to module rlm_files Module: Instantiating files files { usersfile = "/etc/raddb/users" acctusersfile = "/etc/raddb/acct_users" preproxy_usersfile = "/etc/raddb/preproxy_users" compat = "no" } Module: Checking accounting {...} for more modules to load Module: Linked to module rlm_radutmp Module: Instantiating 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_sql_log Module: Instantiating sql_log sql_log { path = "/var/log/radius/sql_log" Post-Auth = "" sql_user_name = "%{%{User-Name}:-DEFAULT}" utf8 = no safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /" } Module: Linked to module rlm_attr_filter Module: Instantiating attr_filter.accounting_response attr_filter attr_filter.accounting_response { attrsfile = "/etc/raddb/attrs.accounting_response" key = "%{User-Name}" } Module: Checking session {...} for more modules to load Module: Checking pre-proxy {...} for more modules to load Module: Linked to module rlm_always Module: Instantiating noop always noop { rcode = "noop" simulcount = 0 mpp = no } Module: Checking post-auth {...} for more modules to load Module: Instantiating attr_filter.access_reject attr_filter attr_filter.access_reject { attrsfile = "/etc/raddb/attrs.access_reject" key = "%{User-Name}" } } # modules } # server radiusd: #### Opening IP addresses and Ports #### listen { type = "auth" ipaddr = 196.25.102.53 port = 0 } listen { type = "acct" ipaddr = 196.25.102.53 port = 0 } Listening on authentication address 196.25.102.53 port 1645 Listening on accounting address 196.25.102.53 port 1646 Listening on proxy address 196.25.102.53 port 1647 Ready to process requests. rad_recv: Access-Request packet from host 196.43.3.86 port 1820, id=0, length=160 Framed-Protocol = PPP User-Name = "pramodhomelite@msp.co.za" User-Password = "***" NAS-Port-Type = Ethernet NAS-Port = 1611059974 NAS-Port-Id = "6/0/0/774.109" Calling-Station-Id = "0216716399" Service-Type = Framed-User NAS-IP-Address = 196.43.27.46 Acct-Session-Id = "6/0/0/774.109_01531E85" Telkom-Access-Type = "DSL" Proxy-State = 0x3635 +- entering group authorize {...} [sql] expand: %{User-Name} -> pramodhomelite@msp.co.za [sql] sql_set_user escaped user --> 'pramodhomelite@msp.co.za' rlm_sql (sql): Reserving sql socket id: 31 [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 = 'pramodhomelite@msp.co.za' ORDER BY id rlm_sql_mysql: query: SELECT id, username, attribute, value, op FROM radcheck WHERE username = 'pramodhomelite@msp.co.za' ORDER BY id [sql] User found in radcheck table [sql] expand: SELECT id, username, attribute, value, op FROM radreply WHERE username = '%{SQL-User-Name}' ORDER BY id -> SELECT id, username, attribute, value, op FROM radreply WHERE username = 'pramodhomelite@msp.co.za' ORDER BY id rlm_sql_mysql: query: SELECT id, username, attribute, value, op FROM radreply WHERE username = 'pramodhomelite@msp.co.za' ORDER BY id [sql] expand: %{Telkom-Access-Type} -> DSL [sql] expand: SELECT IFNULL(( SELECT groupname FROM radusergroup WHERE username = '%{SQL-User-Name}' AND groupname = '%{%{Telkom-Access-Type}:-DIAL}' ), 'REJECT' ) -> SELECT IFNULL(( SELECT groupname FROM radusergroup WHERE username = 'pramodhomelite@msp.co.za' AND groupname = 'DSL' ), 'REJECT' ) rlm_sql_mysql: query: SELECT IFNULL(( SELECT groupname FROM radusergroup WHERE username = 'pramodhomelite@msp.co.za' AND groupname = 'DSL' ), 'REJECT' ) [sql] expand: SELECT id, groupname, attribute, Value, op FROM radgroupcheck WHERE groupname = '%{Sql-Group}' ORDER BY id -> SELECT id, groupname, attribute, Value, op FROM radgroupcheck WHERE groupname = 'DSL' ORDER BY id rlm_sql_mysql: query: SELECT id, groupname, attribute, Value, op FROM radgroupcheck WHERE groupname = 'DSL' ORDER BY id [sql] User found in group DSL [sql] expand: SELECT id, groupname, attribute, value, op FROM radgroupreply WHERE groupname = '%{Sql-Group}' ORDER BY id -> SELECT id, groupname, attribute, value, op FROM radgroupreply WHERE groupname = 'DSL' ORDER BY id rlm_sql_mysql: query: SELECT id, groupname, attribute, value, op FROM radgroupreply WHERE groupname = 'DSL' ORDER BY id rlm_sql (sql): Released sql socket id: 31 ++[sql] returns ok ++[expiration] returns noop ++[logintime] returns noop ++[pap] returns updated Found Auth-Type = PAP +- entering group PAP {...} [pap] login attempt with password "***" [pap] Using CRYPT encryption. [pap] User authenticated successfully ++[pap] returns ok Login OK: [pramodhomelite@msp.co.za/***] (from client 196.43.3.86 port 1611059974 cli 0216716399) +- entering group post-auth {...} ++? if ("%{request:Telkom-Access-Type}" == "DSL") expand: %{request:Telkom-Access-Type} -> DSL ? Evaluating ("%{request:Telkom-Access-Type}" == "DSL") -> TRUE ++? if ("%{request:Telkom-Access-Type}" == "DSL") -> TRUE ++- entering if ("%{request:Telkom-Access-Type}" == "DSL") {...} +++? if ("%{sql:SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'}" != '86400') sql_xlat expand: %{User-Name} -> pramodhomelite@msp.co.za sql_set_user escaped user --> 'pramodhomelite@msp.co.za' expand: SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout' -> SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = 'pramodhomelite@msp.co.za' AND attribute = 'Session-Timeout' rlm_sql (sql): xlat failed. rlm_sql (sql): Reserving sql socket id: 30 rlm_sql_mysql: query: SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = 'pramodhomelite@msp.co.za' AND attribute = 'Session-Timeout' sql_xlat finished rlm_sql (sql): Released sql socket id: 30 expand: %{sql:SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'} -> 1 ? Evaluating ("%{sql:SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'}" != '86400') -> TRUE +++? if ("%{sql:SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'}" != '86400') -> TRUE +++- entering if ("%{sql:SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'}" != '86400') {...} sql_xlat expand: %{User-Name} -> pramodhomelite@msp.co.za sql_set_user escaped user --> 'pramodhomelite@msp.co.za' expand: SELECT value*3600 AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout' -> SELECT value*3600 AS timeout from radcheck WHERE username = 'pramodhomelite@msp.co.za' AND attribute = 'Session-Timeout' rlm_sql (sql): xlat failed. rlm_sql (sql): Reserving sql socket id: 29 rlm_sql_mysql: query: SELECT value*3600 AS timeout from radcheck WHERE username = 'pramodhomelite@msp.co.za' AND attribute = 'Session-Timeout' sql_xlat finished rlm_sql (sql): Released sql socket id: 29 expand: %{sql:SELECT value*3600 AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'} -> 3600 ++++[reply] returns noop +++- if ("%{sql:SELECT IFNULL(value, 86400) AS timeout from radcheck WHERE username = '%{request:User-Name}' AND attribute = 'Session-Timeout'}" != '86400') returns noop +++? if ("%{sql:SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = '%{request:User-Name}' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1}" != '') sql_xlat expand: %{User-Name} -> pramodhomelite@msp.co.za sql_set_user escaped user --> 'pramodhomelite@msp.co.za' expand: SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = '%{request:User-Name}' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1 -> SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = 'pramodhomelite@msp.co.za' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1 rlm_sql (sql): xlat failed. rlm_sql (sql): Reserving sql socket id: 28 rlm_sql_mysql: query: SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = 'pramodhomelite@msp.co.za' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1 SQL query did not return any results rlm_sql (sql): Released sql socket id: 28 expand: %{sql:SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = '%{request:User-Name}' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1} -> ? Evaluating ("%{sql:SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = '%{request:User-Name}' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1}" != '') -> FALSE +++? if ("%{sql:SELECT ceil((240000-CURTIME())/10000)*3600 AS timeout from radacct WHERE username = '%{request:User-Name}' AND LAST_DAY(NOW()) = date(acctstoptime) order by acctstoptime DESC LIMIT 1}" != '') -> FALSE +++? if ("%{control:DSL-LOCAL-CAP}") expand: %{control:DSL-LOCAL-CAP} -> ? Evaluating ("%{control:DSL-LOCAL-CAP}") -> FALSE +++? if ("%{control:DSL-LOCAL-CAP}") -> FALSE +++? if ("%{control:DSL-SHAPED-CAP}") expand: %{control:DSL-SHAPED-CAP} -> ? Evaluating ("%{control:DSL-SHAPED-CAP}") -> FALSE +++? if ("%{control:DSL-SHAPED-CAP}") -> FALSE +++? if ("%{control:DSL-UNSHAPED-CAP}") expand: %{control:DSL-UNSHAPED-CAP} -> ? Evaluating ("%{control:DSL-UNSHAPED-CAP}") -> FALSE +++? if ("%{control:DSL-UNSHAPED-CAP}") -> FALSE +++? if ("%{control:DSL-LITE-CAP}") expand: %{control:DSL-LITE-CAP} -> 1 ? Evaluating ("%{control:DSL-LITE-CAP}") -> TRUE +++? if ("%{control:DSL-LITE-CAP}") -> TRUE +++- entering if ("%{control:DSL-LITE-CAP}") {...} ++++? if ("%{sql:SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap}"==1) sql_xlat expand: %{User-Name} -> pramodhomelite@msp.co.za sql_set_user escaped user --> 'pramodhomelite@msp.co.za' expand: SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap -> SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = 'pramodhomelite@msp.co.za' OR username = CONCAT('pramodhomelite@msp.co.za','@','' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer rlm_sql (sql): xlat failed. rlm_sql (sql): Reserving sql socket id: 27 rlm_sql_mysql: query: SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = 'pramodhomelite@msp.co.za' OR username = CONCAT('pramodhomelite@msp.co.za','@','' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = 'pramodhomelite@msp.co.za' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = 'pramodhomelite@msp.co.za' OR CONCAT(`username`,'@',`realm`)='pramodhomelite@msp.co.za') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap sql_xlat finished rlm_sql (sql): Released sql socket id: 27 expand: %{sql:SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap} -> 1 ? Evaluating ("%{sql:SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap}"==1) -> TRUE ++++? if ("%{sql:SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap}"==1) -> TRUE ++++- entering if ("%{sql:SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap}"==1) {...} +++++[reply] returns noop ++++- if ("%{sql:SELECT IF ((SELECT (( SELECT (value*1024*1024*1024) AS value FROM radcheck WHERE attribute = 'DSL-LITE-CAP' AND (username = '%{request:User-Name}' OR username = CONCAT('%{request:User-Name}','@','%{request:Realm}' ))) + (SELECT IFNULL(SUM(`value`)*1024*1024*1024,0) AS adhoc FROM `adsl_adhoc` WHERE `bwtype`='LITE' AND `customer` = '%{request:User-Name}' AND month(`time`) = month(now())) - IFNULL( SUM( `acctinputoctets` ) , 0 ) - IFNULL( SUM( `acctoutputoctets` ) , 0 )) AS used FROM `radacct` WHERE (username = '%{request:User-Name}' OR CONCAT(`username`,'@',`realm`)='%{request:User-Name}') AND class = 'NI1' AND month( `acctstarttime` ) = month( now( ) ) LIMIT 1 ) >0, '1', '0') AS cap}"==1) returns noop +++- if ("%{control:DSL-LITE-CAP}") returns noop ++- if ("%{request:Telkom-Access-Type}" == "DSL") returns noop ++[exec] returns noop Sending Access-Accept of id 0 to 196.43.3.86 port 1820 Session-Timeout = 3600 Acct-Interim-Interval = 3600 Configuration-Token = "LITE_NORMAL" Cisco-AVPair = "ip:ip-unnumbered=Loopback1074" Cisco-AVPair = "ip:addr-pool=cysm001ipc2" Cisco-AVPair = "ip:dns-servers=196.41.124.10 196.41.124.11" Proxy-State = 0x3635 Finished request 0. Going to the next request Waking up in 4.9 seconds. rad_recv: Accounting-Request packet from host 196.43.3.87 port 1820, id=1, length=188 Acct-Session-Id = "6/0/0/774.109_01531E85" Framed-Protocol = PPP Framed-IP-Address = 41.247.39.35 User-Name = "pramodhomelite@msp.co.za" X-Ascend-Connect-Progress = LAN-Session-Up Acct-Authentic = RADIUS Acct-Status-Type = Start NAS-Port-Type = Ethernet NAS-Port = 1611059974 NAS-Port-Id = "6/0/0/774.109" Calling-Station-Id = "0216716399" Class = "NI1" Service-Type = Framed-User NAS-IP-Address = 196.43.27.46 X-Ascend-Session-Svr-Key = "7CB19BE4" Acct-Delay-Time = 0 Telkom-Access-Type = "DSL" Proxy-State = 0x313739 +- entering group preacct {...} ++[preprocess] returns ok [acct_unique] Hashing 'NAS-Port = 1611059974,Client-IP-Address = 196.43.3.87,NAS-IP-Address = 196.43.27.46,Acct-Session-Id = "6/0/0/774.109_01531E85",User-Name = "pramodhomelite@msp.co.za"' [acct_unique] Acct-Unique-Session-ID = "258709235b92437c". ++[acct_unique] returns ok [suffix] Looking up realm "msp.co.za" for User-Name = "pramodhomelite@msp.co.za" [suffix] Found realm "msp.co.za" [suffix] Adding Stripped-User-Name = "pramodhomelite" [suffix] Adding Realm = "msp.co.za" [suffix] Proxying request from user pramodhomelite to realm msp.co.za [suffix] Preparing to proxy accounting request to realm "msp.co.za" ++[suffix] returns updated ++[files] returns noop +- entering group accounting {...} [radutmp] expand: /var/log/radius/radutmp -> /var/log/radius/radutmp [radutmp] expand: %{User-Name} -> pramodhomelite@msp.co.za ++[radutmp] returns ok [sql] expand: %{User-Name} -> pramodhomelite@msp.co.za [sql] sql_set_user escaped user --> 'pramodhomelite@msp.co.za' [sql] expand: %{Acct-Delay-Time} -> 0 [sql] expand: INSERT INTO radacct (radacctid, 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, class) 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}', '%{Fram rlm_sql (sql): xlat failed. rlm_sql (sql): Reserving sql socket id: 26 rlm_sql_mysql: query: INSERT INTO radacct (radacctid, 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, class) VALUES ('', '6/0/0/774.109_01531E85', '258709235b92437c', 'pramodhomelite@msp.co.za', 'msp.co.za', '196.43.27.46', '1611059974', 'Ethernet', '2011-04-12 14:37:41', NULL, '0', 'RADIUS', '', '', '0', '0', '', '0216716399', '', 'Framed-User', 'PPP', '41.247.39.35', '0', '0', '7CB19BE4','NI1') rlm_sql (sql): Released sql socket id: 26 ++[sql] returns ok [sql_log] Processing sql_log_accounting [sql_log] expand: %{User-Name} -> pramodhomelite@msp.co.za [sql_log] expand: %{%{User-Name}:-DEFAULT} -> pramodhomelite@msp.co.za [sql_log] sql_set_user escaped user --> 'pramodhomelite@msp.co.za' [sql_log] expand: %{Acct-Delay-Time} -> 0 [sql_log] expand: INSERT INTO radacct (RadAcctId, 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}', '%S', '0000-00-00 00:00:00', '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', [sql_log] expand: /var/log/radius/sql_log -> /var/log/radius/sql_log ++[sql_log] returns ok [attr_filter.accounting_response] expand: %{User-Name} -> pramodhomelite@msp.co.za attr_filter: Matched entry DEFAULT at line 12 ++[attr_filter.accounting_response] returns updated +- entering group pre-proxy {...} ++? if ((Client-IP-Address == "196.25.102.32") || (Client-IP-Address == "196.25.102.53") || (Client-IP-Address == "196.41.123.202") ) expand: %{Client-IP-Address} -> 196.43.3.87 ?? Evaluating (Client-IP-Address == "196.25.102.32") -> FALSE expand: %{Client-IP-Address} -> 196.43.3.87 ?? Evaluating (Client-IP-Address == "196.25.102.53") -> FALSE expand: %{Client-IP-Address} -> 196.43.3.87 ?? Evaluating (Client-IP-Address == "196.41.123.202") -> FALSE ++? if ((Client-IP-Address == "196.25.102.32") || (Client-IP-Address == "196.25.102.53") || (Client-IP-Address == "196.41.123.202") ) -> FALSE ++? if ((Packet-Src-IP-Address == "196.25.102.32") || (Paket-Src-IP-Address == "196.25.102.53") || (Paket-Src-IP-Address == "196.41.123.202") ) expand: %{Packet-Src-IP-Address} -> 196.43.3.87 ?? Evaluating (Packet-Src-IP-Address == "196.25.102.32") -> FALSE ?? Evaluating (Paket-Src-IP-Address == "196.25.102.53") -> FALSE ?? Evaluating (Paket-Src-IP-Address == "196.41.123.202") -> FALSE ++? if ((Packet-Src-IP-Address == "196.25.102.32") || (Paket-Src-IP-Address == "196.25.102.53") || (Paket-Src-IP-Address == "196.41.123.202") ) -> FALSE Sending Accounting-Request of id 148 to 196.25.102.32 port 1646 Acct-Session-Id = "6/0/0/774.109_01531E85" Framed-Protocol = PPP Framed-IP-Address = 41.247.39.35 User-Name = "pramodhomelite" X-Ascend-Connect-Progress = LAN-Session-Up Acct-Authentic = RADIUS Acct-Status-Type = Start NAS-Port-Type = Ethernet NAS-Port = 1611059974 NAS-Port-Id = "6/0/0/774.109" Calling-Station-Id = "0216716399" Class = "NI1" Service-Type = Framed-User NAS-IP-Address = 196.43.27.46 X-Ascend-Session-Svr-Key = "7CB19BE4" Acct-Delay-Time = 0 Telkom-Access-Type = "DSL" Proxy-State = 0x313739 Proxy-State = 0x31 Proxying request 1 to home server 196.25.102.32 port 1646 Sending Accounting-Request of id 148 to 196.25.102.32 port 1646 Acct-Session-Id = "6/0/0/774.109_01531E85" Framed-Protocol = PPP Framed-IP-Address = 41.247.39.35 User-Name = "pramodhomelite" X-Ascend-Connect-Progress = LAN-Session-Up Acct-Authentic = RADIUS Acct-Status-Type = Start NAS-Port-Type = Ethernet NAS-Port = 1611059974 NAS-Port-Id = "6/0/0/774.109" Calling-Station-Id = "0216716399" Class = "NI1" Service-Type = Framed-User NAS-IP-Address = 196.43.27.46 X-Ascend-Session-Svr-Key = "7CB19BE4" Acct-Delay-Time = 0 Telkom-Access-Type = "DSL" Proxy-State = 0x313739 Proxy-State = 0x31 Going to the next request Waking up in 0.9 seconds. rad_recv: Accounting-Response packet from host 196.25.102.32 port 1646, id=148, length=28 Proxy-State = 0x313739 Proxy-State = 0x31 WARNING: Empty section. Using default return values. Sending Accounting-Response of id 1 to 196.43.3.87 port 1820 Proxy-State = 0x313739 Finished request 1. Cleaning up request 1 ID 1 with timestamp +217 Going to the next request Waking up in 4.9 seconds. Cleaning up request 0 ID 0 with timestamp +217 Ready to process requests. rad_recv: Accounting-Request packet from host 196.43.3.86 port 1820, id=0, length=276 Acct-Session-Id = "4/0/0/29.544_00892B05" Framed-Protocol = PPP Framed-IP-Address = 41.177.37.9 User-Name = "samwastecontrol@dslmsp.co.za" X-Ascend-Connect-Progress = LAN-Session-Up X-Ascend-PreSession-Time = 3 X-Ascend-Xmit-Rate = 512000 X-Ascend-Data-Rate = 512000 Acct-Session-Time = 48840 Acct-Input-Octets = 1012397 Acct-Output-Octets = 1878093 X-Ascend-Pre-Input-Octets = 90 X-Ascend-Pre-Output-Octets = 73 Acct-Input-Packets = 13775 Acct-Output-Packets = 19423 X-Ascend-Pre-Input-Packets = 4 X-Ascend-Pre-Output-Packets = 5 Acct-Authentic = RADIUS Acct-Status-Type = Interim-Update NAS-Port-Type = Virtual NAS-Port = 1075642912 NAS-Port-Id = "4/0/0/29.544" Connect-Info = "AutoShapedVC" Calling-Station-Id = "0215340162" Class = "IXX" Service-Type = Framed-User NAS-IP-Address = 196.43.27.16 X-Ascend-Session-Svr-Key = "4C771D82" Acct-Delay-Time = 0 Telkom-Access-Type = "DSL" Proxy-State = 0x313930 +- entering group preacct {...} ++[preprocess] returns ok [acct_unique] Hashing 'NAS-Port = 1075642912,Client-IP-Address = 196.43.3.86,NAS-IP-Address = 196.43.27.16,Acct-Session-Id = "4/0/0/29.544_00892B05",User-Name = "samwastecontrol@dslmsp.co.za"' [acct_unique] Acct-Unique-Session-ID = "a8469b118e12a9c1". ++[acct_unique] returns ok [suffix] Looking up realm "dslmsp.co.za" for User-Name = "samwastecontrol@dslmsp.co.za" [suffix] Found realm "dslmsp.co.za" [suffix] Adding Stripped-User-Name = "samwastecontrol" [suffix] Adding Realm = "dslmsp.co.za" [suffix] Proxying request from user samwastecontrol to realm dslmsp.co.za [suffix] Preparing to proxy accounting request to realm "dslmsp.co.za" ++[suffix] returns updated ++[files] returns noop +- entering group accounting {...} [radutmp] expand: /var/log/radius/radutmp -> /var/log/radius/radutmp [radutmp] expand: %{User-Name} -> samwastecontrol@dslmsp.co.za ++[radutmp] returns ok [sql] expand: %{User-Name} -> samwastecontrol@dslmsp.co.za [sql] sql_set_user escaped user --> 'samwastecontrol@dslmsp.co.za' [sql] expand: %{Acct-Input-Gigawords} -> [sql] ... expanding second conditional [sql] expand: %{Acct-Input-Octets} -> 1012397 [sql] expand: %{Acct-Output-Gigawords} -> [sql] ... expanding second conditional [sql] expand: %{Acct-Output-Octets} -> 1878093 [sql] expand: 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}' -> UPDATE radacct SET framedipaddress = '41.177.37.9', acctsessiontime = '48840', acctinputoctets = '0' << 32 | '1012397', acctoutputoctets = '0' << 32 | '1878093' WHERE acctsessionid = '4/0/0/29.544_00892B05' AND username = 'samwastecontrol rlm_sql (sql): xlat failed. rlm_sql (sql): Reserving sql socket id: 25 rlm_sql_mysql: query: UPDATE radacct SET framedipaddress = '41.177.37.9', acctsessiontime = '48840', acctinputoctets = '0' << 32 | '1012397', acctoutputoctets = '0' << 32 | '1878093' WHERE acctsessionid = '4/0/0/29.544_00892B05' AND username = 'samwastecontrol@dslmsp.co.za' AND nasipaddress = '196.43.27.16' rlm_sql (sql): Released sql socket id: 25 ++[sql] returns ok [sql_log] Processing sql_log_accounting [sql_log] expand: %{User-Name} -> samwastecontrol@dslmsp.co.za [sql_log] expand: %{%{User-Name}:-DEFAULT} -> samwastecontrol@dslmsp.co.za [sql_log] sql_set_user escaped user --> 'samwastecontrol@dslmsp.co.za' [sql_log] expand: %{Acct-Input-Gigawords} -> [sql_log] ... expanding second conditional [sql_log] expand: %{Acct-Input-Octets} -> 1012397 [sql_log] expand: %{Acct-Output-Gigawords} -> [sql_log] ... expanding second conditional [sql_log] expand: %{Acct-Output-Octets} -> 1878093 [sql_log] expand: 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}'; -> UPDATE radacct SET FramedIPAddress = '41.177.37.9', AcctSessionTime = '48840', AcctInputOctets = '0' << 32 | '1012397', AcctOutputOctets = '0' << 32 | '1878093' WHERE AcctSessionId = '4/0/0/29.544_00892B05' AND Username = 'sa [sql_log] expand: /var/log/radius/sql_log -> /var/log/radius/sql_log ++[sql_log] returns ok [attr_filter.accounting_response] expand: %{User-Name} -> samwastecontrol@dslmsp.co.za attr_filter: Matched entry DEFAULT at line 12 ++[attr_filter.accounting_response] returns updated +- entering group pre-proxy {...} ++? if ((Client-IP-Address == "196.25.102.32") || (Client-IP-Address == "196.25.102.53") || (Client-IP-Address == "196.41.123.202") ) expand: %{Client-IP-Address} -> 196.43.3.86 ?? Evaluating (Client-IP-Address == "196.25.102.32") -> FALSE expand: %{Client-IP-Address} -> 196.43.3.86 ?? Evaluating (Client-IP-Address == "196.25.102.53") -> FALSE expand: %{Client-IP-Address} -> 196.43.3.86 ?? Evaluating (Client-IP-Address == "196.41.123.202") -> FALSE ++? if ((Client-IP-Address == "196.25.102.32") || (Client-IP-Address == "196.25.102.53") || (Client-IP-Address == "196.41.123.202") ) -> FALSE ++? if ((Packet-Src-IP-Address == "196.25.102.32") || (Paket-Src-IP-Address == "196.25.102.53") || (Paket-Src-IP-Address == "196.41.123.202") ) expand: %{Packet-Src-IP-Address} -> 196.43.3.86 ?? Evaluating (Packet-Src-IP-Address == "196.25.102.32") -> FALSE ?? Evaluating (Paket-Src-IP-Address == "196.25.102.53") -> FALSE ?? Evaluating (Paket-Src-IP-Address == "196.41.123.202") -> FALSE ++? if ((Packet-Src-IP-Address == "196.25.102.32") || (Paket-Src-IP-Address == "196.25.102.53") || (Paket-Src-IP-Address == "196.41.123.202") ) -> FALSE Sending Accounting-Request of id 47 to 196.25.102.32 port 1646 Acct-Session-Id = "4/0/0/29.544_00892B05" Framed-Protocol = PPP Framed-IP-Address = 41.177.37.9 User-Name = "samwastecontrol" X-Ascend-Connect-Progress = LAN-Session-Up X-Ascend-PreSession-Time = 3 X-Ascend-Xmit-Rate = 512000 X-Ascend-Data-Rate = 512000 Acct-Session-Time = 48840 Acct-Input-Octets = 1012397 Acct-Output-Octets = 1878093 X-Ascend-Pre-Input-Octets = 90 X-Ascend-Pre-Output-Octets = 73 Acct-Input-Packets = 13775 Acct-Output-Packets = 19423 X-Ascend-Pre-Input-Packets = 4 X-Ascend-Pre-Output-Packets = 5 Acct-Authentic = RADIUS Acct-Status-Type = Interim-Update NAS-Port-Type = Virtual NAS-Port = 1075642912 NAS-Port-Id = "4/0/0/29.544" Connect-Info = "AutoShapedVC" Calling-Station-Id = "0215340162" Class = "IXX" Service-Type = Framed-User NAS-IP-Address = 196.43.27.16 X-Ascend-Session-Svr-Key = "4C771D82" Acct-Delay-Time = 0 Telkom-Access-Type = "DSL" Proxy-State = 0x313930 Proxy-State = 0x30 Proxying request 2 to home server 196.25.102.32 port 1646 Sending Accounting-Request of id 47 to 196.25.102.32 port 1646 Acct-Session-Id = "4/0/0/29.544_00892B05" Framed-Protocol = PPP Framed-IP-Address = 41.177.37.9 User-Name = "samwastecontrol" X-Ascend-Connect-Progress = LAN-Session-Up X-Ascend-PreSession-Time = 3 X-Ascend-Xmit-Rate = 512000 X-Ascend-Data-Rate = 512000 Acct-Session-Time = 48840 Acct-Input-Octets = 1012397 Acct-Output-Octets = 1878093 X-Ascend-Pre-Input-Octets = 90 X-Ascend-Pre-Output-Octets = 73 Acct-Input-Packets = 13775 Acct-Output-Packets = 19423 X-Ascend-Pre-Input-Packets = 4 X-Ascend-Pre-Output-Packets = 5 Acct-Authentic = RADIUS Acct-Status-Type = Interim-Update NAS-Port-Type = Virtual NAS-Port = 1075642912 NAS-Port-Id = "4/0/0/29.544" Connect-Info = "AutoShapedVC" Calling-Station-Id = "0215340162" Class = "IXX" Service-Type = Framed-User NAS-IP-Address = 196.43.27.16 X-Ascend-Session-Svr-Key = "4C771D82" Acct-Delay-Time = 0 Telkom-Access-Type = "DSL" Proxy-State = 0x313930 Proxy-State = 0x30 Going to the next request Waking up in 0.9 seconds. rad_recv: Accounting-Response packet from host 196.25.102.32 port 1646, id=47, length=28 Proxy-State = 0x313930 Proxy-State = 0x30 WARNING: Empty section. Using default return values. Sending Accounting-Response of id 0 to 196.43.3.86 port 1820 Proxy-State = 0x313930 Finished request 2. Cleaning up request 2 ID 0 with timestamp +256 Going to the next request Ready to process requests.