rendered paste bodyStarting - reading configuration files ...
reread_config: reading radiusd.conf
Config: including file: /etc/raddb/proxy.conf
Config: including file: /etc/raddb/clients.conf
Config: including file: /etc/raddb/snmp.conf
Config: including file: /etc/raddb/eap.conf
Config: including file: /etc/raddb/nibs.conf
main: prefix = "/usr"
main: localstatedir = "/var"
main: logdir = "/var/log/freeradius"
main: libdir = "@libdir@"
main: radacctdir = "/var/log/freeradius/radacct"
main: hostname_lookups = no
main: max_request_time = 30
main: cleanup_delay = 5
main: max_requests = 1024
main: delete_blocked_requests = 0
main: port = 0
main: allow_core_dumps = no
main: log_stripped_names = no
main: log_file = "/var/log/freeradius/radius.log"
main: log_auth = no
main: log_auth_badpass = no
main: log_auth_goodpass = no
main: pidfile = "/var/run/radiusd/radiusd.pid"
main: user = "nobody"
main: group = "nobody"
main: usercollide = no
main: lower_user = "no"
main: lower_pass = "no"
main: nospace_user = "no"
main: nospace_pass = "no"
main: checkrad = "/usr/sbin/checkrad"
main: proxy_requests = no
proxy: retry_delay = 5
proxy: retry_count = 3
proxy: synchronous = no
proxy: default_fallback = yes
proxy: dead_time = 120
proxy: post_proxy_authorize = no
proxy: wake_all_if_all_dead = no
security: max_attributes = 200
security: reject_delay = 1
security: status_server = no
main: debug_level = 0
read_config_files: reading dictionary
read_config_files: reading naslist
Using deprecated naslist file. Support for this will go away soon.
read_config_files: reading clients
read_config_files: reading realms
radiusd: entering modules setup
Module: Library search path is @libdir@
Module: Loaded exec
exec: wait = yes
exec: program = "(null)"
exec: input_pairs = "request"
exec: output_pairs = "(null)"
exec: packet_type = "(null)"
rlm_exec: Wait=yes but no output defined. Did you mean output=none?
Module: Instantiated exec (exec)
Module: Loaded expr
Module: Instantiated expr (expr)
Module: Loaded NIBS
nibs: driver = "rlm_nibs_mysql"
nibs: server = "localhost"
nibs: port = "3306"
nibs: login = "root"
nibs: password = ""
nibs: nibs_db = "mabill"
nibs: auth_table = "users"
nibs: packets_table = "packets"
nibs: prices_table = "prices"
nibs: acct_table = "actions"
nibs: blacklist_table = "blacklist"
nibs: holidays_table = "holidays"
nibs: sqltrace = no
nibs: sqltracefile = "/var/log/freeradius/nibs_mysqltrace.sql"
nibs: num_socks = 16
nibs: connect_failure_retry = 100
nibs: connect_failure_retry_delay = 3
nibs: sql_user_name = "%{User-Name}"
nibs: group_select_query = "SELECT gid, prefix, tos, do_with_tos, direction, fixed, fixed_cost, activation_time, total_time_limit, month_time_limit, week_time_limit, day_time_limit, total_traffic_limit, month_traffic_limit, week_traffic_limit, day_traffic_limit, total_money_limit, month_money_limit, week_money_limit, day_money_limit, session_timeout, idle_timeout, simultaneous_use, port_limit, login_time, other_params, huntgroup_name, allow_callback, allowed_prefixes, framed_ip, framed_mask, no_acct, no_pass FROM packets"
nibs: group_select_query_prof = "SELECT allowed_servers FROM packets WHERE gid='%d'"
nibs: holyday_select_query = "SELECT holiday_date FROM holidays"
nibs: prices_select_query = "SELECT h0, input0, output0, h1, input1, output1, h2, input2, output2, h3, input3, output3, h4, input4, output4, h5, input5, output5, h6, input6, output6, h7, input7, output7, h8, input8, output8, h9, input9, output9, h10, input10, output10, h11, input11, output11, h12, input12, output12, h13, input13, output13, h14, input14, output14, h15, input15, output15, h16, input16, output16, h17, input17, output17, h18, input18, output18, h19, input19, output19, h20, input20, output20, h21, input21, output21, h22, input22, output22, h23, input23, output23, week_day, gid, type, servers, uid FROM prices"
nibs: blacklist_select_query = "SELECT uid, gid, phone, permit FROM blacklist order by id"
nibs: authenticate_query = "SELECT user, password, gid, crypt_method FROM users WHERE user = '%{SQL-User-Name}'"
nibs: authorize_query = "SELECT user, password, crypt_method, uid, gid, deposit, credit, unix_timestamp(add_date), blocked, activated, unix_timestamp(expired), total_time, total_traffic, total_money, unix_timestamp(last_connection), framed_ip, framed_mask, callback_number FROM users WHERE user = '%{SQL-User-Name}'"
nibs: authorize_query_prof = "SELECT tos, do_with_tos, direction, fixed, fixed_cost, activation_time, total_time_limit, month_time_limit, week_time_limit, day_time_limit, total_traffic_limit, month_traffic_limit, week_traffic_limit, day_traffic_limit, total_money_limit, month_money_limit, week_money_limit, day_money_limit, login_time, huntgroup_name, simultaneous_use, port_limit, session_timeout, idle_timeout, allowed_prefixes, no_pass, no_acct, allow_callback, other_params, allowed_servers FROM users WHERE user = '%{SQL-User-Name}'"
nibs: month_time_query = "SELECT sum(time_on) FROM actions WHERE user = '%{SQL-User-Name}' and start_time >= CONCAT(YEAR(NOW()),'-',MONTH(NOW()),'-01 00:00:00') AND start_time <= ADDDATE(CONCAT(YEAR(NOW()),'-',MONTH(NOW()),'-01 00:00:00'), INTERVAL 1 MONTH)"
nibs: week_time_query = "SELECT sum(time_on) FROM actions WHERE user = '%{SQL-User-Name}' and YEAR(start_time) = YEAR(NOW()) AND WEEK(start_time,1) = WEEK(NOW(), 1)"
nibs: day_time_query = "SELECT sum(time_on) FROM actions WHERE user = '%{SQL-User-Name}' and TO_DAYS(start_time) = TO_DAYS(NOW())"
nibs: month_traffic_query = "SELECT sum(in_bytes), sum(out_bytes) FROM actions WHERE user = '%{SQL-User-Name}' and MONTH(start_time)=MONTH(NOW())"
nibs: month_traffic_and_money_query = "SELECT sum(in_bytes), sum(out_bytes), sum(billing_minus) FROM actions WHERE user = '%{SQL-User-Name}' and MONTH(start_time)=MONTH(NOW())"
nibs: week_traffic_query = "SELECT sum(in_bytes), sum(out_bytes) FROM actions WHERE user = '%{SQL-User-Name}' and YEAR(start_time) = YEAR(NOW()) AND WEEK(start_time,1) = WEEK(NOW(), 1)"
nibs: day_traffic_query = "SELECT sum(in_bytes), sum(out_bytes) FROM actions WHERE user = '%{SQL-User-Name}' and TO_DAYS(start_time) = TO_DAYS(NOW())"
nibs: month_money_query = "SELECT sum(billing_minus) FROM actions WHERE user = '%{SQL-User-Name}' and MONTH(start_time)=MONTH(NOW())"
nibs: week_money_query = "SELECT sum(billing_minus) FROM actions WHERE user = '%{SQL-User-Name}' and YEAR(start_time) = YEAR(NOW()) AND WEEK(start_time,1) = WEEK(NOW(), 1)"
nibs: day_money_query = "SELECT sum(billing_minus) FROM actions WHERE user = '%{SQL-User-Name}' and TO_DAYS(start_time) = TO_DAYS(NOW())"
nibs: activation_update_query = "UPDATE users SET add_date = %%s, expired = %%s, activated = '%%d', last_connection = now() WHERE user = '%{SQL-User-Name}'"
nibs: fixed_select_query = "SELECT to_days('%S'), to_days(last_connection) FROM users WHERE user = '%{SQL-User-Name}'"
nibs: statistic_select_query = "SELECT time_on, in_bytes, out_bytes, unix_timestamp(start_time), billing_minus, hour_in_bytes, hour_out_bytes, hour_traffic_money, last_change FROM actions WHERE id = '%{Acct-Session-Id}' AND unique_id = '%{Acct-Unique-Session-Id}' AND server = '%{NAS-IP-Address}' AND port = %{NAS-Port} AND user = '%{SQL-User-Name}' AND terminate_cause='Online'"
nibs: accounting_start_query = "INSERT INTO actions (before_billing, gid, id, unique_id, user, server, port, client_ip, protocol, ip, call_to, call_from, terminate_cause, connect_info, start_time, last_change) VALUES ('%%16.2f', '%%d', '%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{NAS-IP-Address}', %{NAS-Port}, '%{Client-IP-Address}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Called-Station-Id}', '%{Calling-Station-Id}', 'Online', '%{Connect-Info}', '%S', unix_timestamp())"
nibs: accounting_start_query_alt = "INSERT INTO actions (before_billing, gid, id, unique_id, user, server, port, client_ip, protocol, ip, call_to, call_from, terminate_cause, connect_info, start_time, last_change) VALUES ('%%16.2f', '%%d', '%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{NAS-IP-Address}', %{NAS-Port}, '%{Client-IP-Address}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Called-Station-Id}', '%{Calling-Station-Id}', 'Online', '%{Connect-Info}', unix_timestamp() - '%%d', unix_timestamp())"
nibs: accounting_reopen = "UPDATE actions SET terminate_cause = 'Online', stop_time = 0, id = '%{Acct-Session-Id}', unique_id = '%{Acct-Unique-Session-Id}' WHERE id LIKE '%%%{Acct-Session-Id}' AND unique_id LIKE '%%%{Acct-Unique-Session-Id}' AND server = '%{NAS-IP-Address}' AND port = %{NAS-Port} AND user = '%{SQL-User-Name}' AND terminate_cause != 'Online' AND (-10 <= (unix_timestamp(start_time) - (unix_timestamp() - %{Acct-Session-Time}))) AND ((unix_timestamp(start_time) - (unix_timestamp() - %{Acct-Session-Time})) <= 10)"
nibs: accounting_alive_auth_query = "UPDATE users SET deposit = deposit - %%16.2f, total_traffic = total_traffic + %%llu, total_time = total_time + %%llu, total_money = total_money + %%16.2f, last_connection = now() WHERE user = '%{SQL-User-Name}'"
nibs: accounting_stop_auth_query = "UPDATE users SET deposit = deposit - %%16.2f, total_traffic = total_traffic + %%llu, total_time = total_time + %%llu, total_money = total_money + %%16.2f, last_connection = now() WHERE user = '%{SQL-User-Name}'"
nibs: accounting_update_query = "UPDATE actions SET time_on = if(%{Acct-Session-Time},%{Acct-Session-Time},time_on), in_bytes = %%llu, out_bytes = %%llu, last_change = unix_timestamp(), billing_minus = billing_minus + %%16.2f, ip = '%{Framed-IP-Address}', hour_in_bytes = '%%llu', hour_out_bytes = '%%llu', hour_traffic_money = %%16.6f WHERE id='%{Acct-Session-Id}' AND unique_id = '%{Acct-Unique-Session-Id}' AND server = '%{NAS-IP-Address}' AND port = %{NAS-Port} AND user = '%{SQL-User-Name}' AND terminate_cause = 'Online'"
nibs: accounting_stop_query = "UPDATE actions SET time_on = if(%{Acct-Session-Time},%{Acct-Session-Time},time_on), stop_time = '%S', in_bytes = %%llu, out_bytes = %%llu, billing_minus = billing_minus + %%16.2f, terminate_cause='%{Acct-Terminate-Cause}', connect_info = '%{Connect-Info}', id = concat(unix_timestamp(start_time),'-%{Acct-Session-Id}'), unique_id = concat(unix_timestamp(start_time),'-%{Acct-Unique-Session-Id}'), ip = '%{Framed-IP-Address}' WHERE id='%{Acct-Session-Id}' AND unique_id = '%{Acct-Unique-Session-Id}' AND server = '%{NAS-IP-Address}' AND port = %{NAS-Port} AND user = '%{SQL-User-Name}' AND terminate_cause = 'Online'"
nibs: accounting_onoff_query = ""
nibs: simul_count_query = "SELECT id, user, server, port, ip, call_from FROM actions WHERE user = '%{SQL-User-Name}' AND terminate_cause = 'Online'"
nibs: simul_verify_query = "SELECT id, user, server, port, ip, protocol, last_change FROM actions WHERE terminate_cause = 'Online' ORDER BY user"
nibs: deletestalesessions_by_checkrad = no
nibs: deletestalesessions_by_alive = no
nibs: alive_max_interval = 60
nibs: set_session_timeout = yes
nibs: use_black_list = yes
nibs: use_ext_price_attr = yes
nibs: accounting_on_alive = yes
nibs: ignore_localhost_pkt = yes
nibs: kill_by_alive = yes
nibs: kill_program = "/usr/sbin/dialup.userkill %{User-Name} %{NAS-IP-Address} %{Client-IP-Address} %{NAS-Port} %{Framed-IP-Address}"
nibs: safe_characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
nibs: allowed_service = ""
nibs: bytes_per_megabyte = 1048576
nibs: round_to_minute = no
nibs: set_max_octet_limit = no
nibs: max_octet_limit = 2147483647
rlm_nibs (rlm_nibs_instantiate): Driver rlm_nibs_mysql (module rlm_nibs_mysql) loaded and linked
rlm_nibs (rlm_nibs_instantiate): Attempting to connect to root@localhost:3306/mabill
sql_als->sql_init_socketpool: starting 0
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #0
rlm_nibs_mysql: Starting connect to MySQL server for #0
sql_als->connect_single_socket: Connected new DB handle, #0
sql_als->sql_init_socketpool: starting 1
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #1
rlm_nibs_mysql: Starting connect to MySQL server for #1
sql_als->connect_single_socket: Connected new DB handle, #1
sql_als->sql_init_socketpool: starting 2
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #2
rlm_nibs_mysql: Starting connect to MySQL server for #2
sql_als->connect_single_socket: Connected new DB handle, #2
sql_als->sql_init_socketpool: starting 3
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #3
rlm_nibs_mysql: Starting connect to MySQL server for #3
sql_als->connect_single_socket: Connected new DB handle, #3
sql_als->sql_init_socketpool: starting 4
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #4
rlm_nibs_mysql: Starting connect to MySQL server for #4
sql_als->connect_single_socket: Connected new DB handle, #4
sql_als->sql_init_socketpool: starting 5
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #5
rlm_nibs_mysql: Starting connect to MySQL server for #5
sql_als->connect_single_socket: Connected new DB handle, #5
sql_als->sql_init_socketpool: starting 6
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #6
rlm_nibs_mysql: Starting connect to MySQL server for #6
sql_als->connect_single_socket: Connected new DB handle, #6
sql_als->sql_init_socketpool: starting 7
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #7
rlm_nibs_mysql: Starting connect to MySQL server for #7
sql_als->connect_single_socket: Connected new DB handle, #7
sql_als->sql_init_socketpool: starting 8
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #8
rlm_nibs_mysql: Starting connect to MySQL server for #8
sql_als->connect_single_socket: Connected new DB handle, #8
sql_als->sql_init_socketpool: starting 9
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #9
rlm_nibs_mysql: Starting connect to MySQL server for #9
sql_als->connect_single_socket: Connected new DB handle, #9
sql_als->sql_init_socketpool: starting 10
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #10
rlm_nibs_mysql: Starting connect to MySQL server for #10
sql_als->connect_single_socket: Connected new DB handle, #10
sql_als->sql_init_socketpool: starting 11
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #11
rlm_nibs_mysql: Starting connect to MySQL server for #11
sql_als->connect_single_socket: Connected new DB handle, #11
sql_als->sql_init_socketpool: starting 12
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #12
rlm_nibs_mysql: Starting connect to MySQL server for #12
sql_als->connect_single_socket: Connected new DB handle, #12
sql_als->sql_init_socketpool: starting 13
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #13
rlm_nibs_mysql: Starting connect to MySQL server for #13
sql_als->connect_single_socket: Connected new DB handle, #13
sql_als->sql_init_socketpool: starting 14
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #14
rlm_nibs_mysql: Starting connect to MySQL server for #14
sql_als->connect_single_socket: Connected new DB handle, #14
sql_als->sql_init_socketpool: starting 15
sql_als->connect_single_socket: Attempting to connect rlm_nibs_mysql #15
rlm_nibs_mysql: Starting connect to MySQL server for #15
sql_als->connect_single_socket: Connected new DB handle, #15
rlm_nibs (nibs_init): Initializing main structures `mabill'
sql_als->sql_get_socket (nibs): Reserving sql socket id: 15
sql_als->sql_get_socket (nibs): Reserving sql socket id: 14
rlm_nibs_mysql: MYSQL check_error: 1054 received
sql_als->sql_release_socket: Released sql socket id: 15
rlm_nibs (sql_init_groups): Couldn't get set of groups - in prof mode
sql_als->sql_release_socket: Released sql socket id: 14
sql_als->sql_get_socket (nibs): Reserving sql socket id: 13
rlm_nibs (sql_init_holidays): No holidays configured
sql_als->sql_release_socket: Released sql socket id: 13
sql_als->sql_get_socket (nibs): Reserving sql socket id: 12
rlm_nibs_mysql: MYSQL check_error: 1054 received
rlm_anibs (sql_init_prices): Couldn't get set of prices - Unknown column 'type' in 'field list'
sql_als->sql_release_socket: Released sql socket id: 12
Module: Instantiated nibs (nibs)
Module: Loaded preprocess
preprocess: huntgroups = "/etc/raddb/huntgroups"
preprocess: hints = "/etc/raddb/hints"
preprocess: with_ascend_hack = no
preprocess: ascend_channels_per_line = 23
preprocess: with_ntdomain_hack = yes
preprocess: with_specialix_jetstream_hack = no
preprocess: with_cisco_vsa_hack = no
preprocess: with_alvarion_vsa_hack = no
Module: Instantiated preprocess (preprocess)
Module: Loaded Acct-Unique-Session-Id
acct_unique: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"
Module: Instantiated acct_unique (acct_unique)
Module: Loaded realm
realm: format = "suffix"
realm: delimiter = "@"
realm: ignore_default = no
realm: ignore_null = no
Module: Instantiated realm (suffix)
Module: Loaded files
files: usersfile = "/etc/raddb/users"
files: acctusersfile = "/etc/raddb/acct_users"
files: preproxy_usersfile = "/etc/raddb/preproxy_users"
files: compat = "no"
Module: Instantiated files (files)
Module: Loaded detail
detail: detailfile = "/var/log/freeradius/radacct/%{Client-IP-Address}/detail-%Y%m%d"
detail: detailperm = 384
detail: dirperm = 493
detail: locking = no
Module: Instantiated detail (detail)
Module: Loaded radutmp
radutmp: filename = "/var/log/freeradius/radutmp"
radutmp: username = "%{User-Name}"
radutmp: case_sensitive = yes
radutmp: check_with_nas = yes
radutmp: perm = 384
radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
Listening on authentication *:1812
Listening on accounting *:1813
Ready to process requests.
rad_recv: Access-Request packet from host 127.0.0.1:1063, id=81, length=138
Service-Type = Framed-User
Framed-Protocol = PPP
User-Name = "max"
MS-CHAP-Challenge = 0x4b9a9878843cb359
MS-CHAP-Response = 0xd401000000000000000000000000000000000000000000000000edcabe13a08072fd66a2e352feb94cfce5318402d0166d33
Calling-Station-Id = "172.20.20.120"
NAS-IP-Address = 127.0.0.1
NAS-Port = 0
Processing the authorize section of radiusd.conf
modcall: entering group authorize for request 0
modcall[authorize]: module "preprocess" returns ok for request 0
radius_xlat: 'max'
rlm_nibs (sql_set_user): sql_set_user escaped user --> 'max'
rlm_nibs (nibs_fill_user): begin for user `max' ------------
radius_xlat: 'SELECT user, password, crypt_method, uid, gid, deposit, credit, unix_timestamp(add_date), blocked, activated, unix_timestamp(expired), total_time, total_traffic, total_money, unix_timestamp(last_connection), framed_ip, framed_mask, callback_number FROM users WHERE user = 'max''
sql_als->sql_get_socket (nibs): Reserving sql socket id: 11
rlm_nibs (nibs_fill_user): No packet (group) for user 'max' with gid = 1
sql_als->sql_release_socket: Released sql socket id: 11
modcall[authorize]: module "nibs" returns notfound for request 0
modcall: leaving group authorize (returns ok) for request 0
auth: No authenticate method (Auth-Type) configuration found for the request: Rejecting the user
auth: Failed to validate the user.
Delaying request 0 for 1 seconds
Finished request 0
Going to the next request
--- Walking the entire request list ---
Waking up in 1 seconds...
--- Walking the entire request list ---
Waking up in 1 seconds...
--- Walking the entire request list ---
Sending Access-Reject of id 81 to 127.0.0.1 port 1063
Waking up in 4 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 81 with timestamp 474dc7bf
Nothing to do. Sleeping until we see a request.