Starting - 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.