> Run the server in debugging mode, like it suggests in the README and
>in the FAQ.
sorry...
i'll never ask before -X ...
and i don't see what's wrong :(
i have:
authorize {
preprocess
suffix
files
sql
}
authenticate {
}
preacct {
files
}
accounting {
radutmp
sql
sradutmp
}
session {
radutmp
sql
}
post-auth {
}
what group is writing in radutmp?
Starting - reading configuration files ...
reread_config: reading radiusd.conf
Config: including file: /usr/local/etc/raddb/proxy.conf
Config: including file: /usr/local/etc/raddb/clients.conf
Config: including file: /usr/local/etc/raddb/snmp.conf
Config: including file: /usr/local/etc/raddb/sql.conf
main: prefix = "/usr/local"
main: localstatedir = "/usr/local/var"
main: logdir = "/usr/local/var/log/radius"
main: libdir = "/usr/local/lib"
main: radacctdir = "/usr/local/var/log/radius/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 = yes
main: log_file = "/usr/local/var/log/radius/radius.log"
main: log_auth = yes
main: log_auth_badpass = yes
main: log_auth_goodpass = no
main: pidfile = "/usr/local/var/run/radiusd/radiusd.pid"
main: user = "(null)"
main: group = "(null)"
main: usercollide = no
main: lower_user = "no"
main: lower_pass = "no"
main: nospace_user = "no"
main: nospace_pass = "no"
main: checkrad = "/usr/local/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: servers_per_realm = 15
security: max_attributes = 200
security: reject_delay = 1
main: debug_level = 0
read_config_files: reading dictionary
read_config_files: reading naslist
read_config_files: reading clients
read_config_files: reading realms
radiusd: entering modules setup
Module: Library search path is /usr/local/lib
Module: Loaded expr
Module: Instantiated expr (expr)
Module: Loaded preprocess
preprocess: huntgroups = "/usr/local/etc/raddb/huntgroups"
preprocess: hints = "/usr/local/etc/raddb/hints"
preprocess: with_ascend_hack = no
preprocess: ascend_channels_per_line = 23
preprocess: with_ntdomain_hack = no
preprocess: with_specialix_jetstream_hack = no
preprocess: with_cisco_vsa_hack = no
Module: Instantiated preprocess (preprocess)
Module: Loaded realm
realm: format = "suffix"
realm: delimiter = "@"
Module: Instantiated realm (suffix)
Module: Loaded files
files: usersfile = "/usr/local/etc/raddb/users"
files: acctusersfile = "/usr/local/etc/raddb/acct_users"
files: preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"
files: compat = "no"
[/usr/local/etc/raddb/users]:368 WARNING! Changing 'Service-Type =' to
'Service-Type ==' ?for comparing RADIUS attribute in check item list for
user DEFAULT
[/usr/local/etc/raddb/users]:380 WARNING! Changing 'Framed-Protocol =' to
'Framed-Protocol ==' ?for comparing RADIUS attribute in check item list
for user DEFAULT
Module: Instantiated files (files)
Module: Loaded SQL
sql: driver = "rlm_sql_mysql"
sql: server = "localhost"
sql: port = ""
sql: login = "rad"
sql: password = "rad"
sql: radius_db = "radius"
sql: acct_table = "radacct"
sql: acct_table2 = "radacct"
sql: authcheck_table = "radcheck"
sql: authreply_table = "radreply"
sql: groupcheck_table = "radgroupcheck"
sql: groupreply_table = "radgroupreply"
sql: usergroup_table = "usergroup"
sql: nas_table = "nas"
sql: dict_table = "dictionary"
sql: sqltrace = no
sql: sqltracefile = "/usr/local/var/log/radius/sqltrace.sql"
sql: deletestalesessions = yes
sql: num_sql_socks = 5
sql: sql_user_name = "%{User-Name}"
sql: default_user_profile = ""
sql: query_on_not_found = no
sql: authorize_check_query = "SELECT id,UserName,Attribute,Value,op FROM
radcheck WHERE Username = '%{SQL-User-Name}' ORDER BY id"
sql: authorize_reply_query = "SELECT id,UserName,Attribute,Value,op FROM
radreply WHERE Username = '%{SQL-User-Name}' ORDER BY id"
sql: authorize_group_check_query = "SELECT
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op
FROM radgroupcheck,usergroup WHERE usergroup.Username = '%{SQL-User-Name}'
AND usergroup.GroupName = radgroupcheck.GroupName ORDER BY
radgroupcheck.id"
sql: authorize_group_reply_query = "SELECT
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op
FROM radgroupreply,usergroup WHERE usergroup.Username = '%{SQL-User-Name}'
AND usergroup.GroupName = radgroupreply.GroupName ORDER BY
radgroupreply.id"
sql: accounting_onoff_query = "UPDATE radacct SET AcctStopTime='%S',
AcctSessionTime=unix_timestamp('%S') - unix_timestamp(AcctStartTime),
AcctTerminateCause='%{Acct-Terminate-Cause}', AcctStopDelay =
%{Acct-Delay-Time} WHERE AcctSessionTime=0 AND AcctStopTime=0 AND
NASIPAddress= '%{NAS-IP-Address}' AND AcctStartTime <= '%S'"
sql: accounting_update_query = "UPDATE radacct SET FramedIPAddress =
'%{Framed-IP-Address}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND
UserName = '%{SQL-User-Name}' AND NASIPAddress= '%{NAS-IP-Address}' AND
AcctStopTime = 0"
sql: 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) values('',
'%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}',
'%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port}', '%{NAS-Port-Type}', '%S',
'0', '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0',
'%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}',
'%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Acct-Delay-Time}', '0')"
sql: accounting_start_query_alt = "UPDATE radacct SET AcctStartTime =
'%S', AcctStartDelay = '%{Acct-Delay-Time}', ConnectInfo_start =
'%{Connect-Info}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND UserName
= '%{SQL-User-Name}' AND NASIPAddress = '%{NAS-IP-Address}' AND
AcctStopTime = 0"
sql: accounting_stop_query = "UPDATE radacct SET AcctStopTime = '%S',
AcctSessionTime = '%{Acct-Session-Time}', AcctInputOctets =
'%{Acct-Input-Octets}', AcctOutputOctets = '%{Acct-Output-Octets}',
AcctTerminateCause = '%{Acct-Terminate-Cause}', AcctStopDelay =
'%{Acct-Delay-Time}', ConnectInfo_stop = '%{Connect-Info}' WHERE
AcctSessionId = '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' AND
NASIPAddress = '%{NAS-IP-Address}' AND AcctStopTime = 0"
sql: accounting_stop_query_alt = "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}', '0',
'%S', '%{Acct-Session-Time}', '%{Acct-Authentic}', '', '%{Connect-Info}',
'%{Acct-Input-Octets}', '%{Acct-Output-Octets}', '%{Called-Station-Id}',
'%{Calling-Station-Id}', '%{Acct-Terminate-Cause}', '%{Service-Type}',
'%{Framed-Protocol}', '%{Framed-IP-Address}', '0', '%{Acct-Delay-Time}')"
sql: group_membership_query = "SELECT GroupName FROM usergroup WHERE
UserName='%{SQL-User-Name}'"
sql: connect_failure_retry_delay = 60
sql: simul_count_query = ""
sql: simul_verify_query = "SELECT RadAcctId, AcctSessionId, UserName,
NASIPAddress, NASPortId, FramedIPAddress, CallingStationId, FramedProtocol
FROM radacct WHERE UserName='%{SQL-User-Name}' AND AcctStopTime = 0"
rlm_sql (sql): Driver rlm_sql_mysql (module rlm_sql_mysql) loaded and
linked
rlm_sql (sql): Attempting to connect to root@localhost:/radius
rlm_sql (sql): starting 0
rlm_sql (sql): Attempting to connect rlm_sql_mysql #0
rlm_sql_mysql: Starting connect to MySQL server for #0
rlm_sql (sql): Connected new DB handle, #0
rlm_sql (sql): starting 1
rlm_sql (sql): Attempting to connect rlm_sql_mysql #1
rlm_sql_mysql: Starting connect to MySQL server for #1
rlm_sql (sql): Connected new DB handle, #1
rlm_sql (sql): starting 2
rlm_sql (sql): Attempting to connect rlm_sql_mysql #2
rlm_sql_mysql: Starting connect to MySQL server for #2
rlm_sql (sql): Connected new DB handle, #2
rlm_sql (sql): starting 3
rlm_sql (sql): Attempting to connect rlm_sql_mysql #3
rlm_sql_mysql: Starting connect to MySQL server for #3
rlm_sql (sql): Connected new DB handle, #3
rlm_sql (sql): starting 4
rlm_sql (sql): Attempting to connect rlm_sql_mysql #4
rlm_sql_mysql: Starting connect to MySQL server for #4
rlm_sql (sql): Connected new DB handle, #4
Module: Instantiated sql (sql)
Module: Loaded detail
detail: detailfile =
"/usr/local/var/log/radius/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 = "/usr/local/var/log/radius/radutmp"
radutmp: username = "%{User-Name}"
radutmp: perm = 384
radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
radutmp: filename = "/usr/local/var/log/radius/sradutmp"
radutmp: username = "%{User-Name}"
radutmp: perm = 420
radutmp: callerid = no
Module: Instantiated radutmp (sradutmp)
Listening on IP address *, ports 1812/udp and 1813/udp.
Ready to process requests.
rad_recv: Accounting-Request packet from host 194.102.223.7:1813, id=9,
length=176
User-Name = "alpina"
NAS-IP-Address = 194.102.223.7
NAS-Port = 213
NAS-Port-Type = Async
Acct-Authentic = RADIUS
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 80.97.8.78
Acct-Delay-Time = 0
Acct-Status-Type = Stop
Acct-Session-Id =
"\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000"
Acct-Session-Time = 45
Acct-Input-Octets = 3568
Acct-Output-Octets = 40701
Acct-Input-Packets = 65
Acct-Output-Packets = 73
Acct-Terminate-Cause = Host-Request
Calling-Station-Id = "254221036"
Connect-Info = "V34 31200 33600 LAPM/V42Bis"
modcall: entering group preacct
modcall[preacct]: module "files" returns noop
modcall: group preacct returns noop
modcall: entering group accounting
radius_xlat: '/usr/local/var/log/radius/radacct//detail-20021114'
rlm_detail:
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /usr/local/var/log/radius/radacct//detail-20021114
modcall[accounting]: module "detail" returns ok
radius_xlat: 'alpina'
Accounting: logout: login entry for NAS cyclades port 213 not found
modcall[accounting]: module "radutmp" returns ok
radius_xlat: 'alpina'
rlm_sql (sql): sql_set_user escaped user --> 'alpina'
radius_xlat: 'UPDATE radacct SET AcctStopTime = '2002-11-14 09:57:29',
AcctSessionTime = '45', AcctInputOctets = '3568', AcctOutputOctets =
'40701', AcctTerminateCause = 'Host-Request', AcctStopDelay = '0',
ConnectInfo_stop = 'V34 31200 33600 LAPM/V42Bis' WHERE AcctSessionId =
'=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000'
AND UserName = 'alpina' AND NASIPAddress = '194.102.223.7' AND
AcctStopTime = 0'
rlm_sql (sql): Reserving sql socket id: 4
radius_xlat: '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('',
'=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000',
'', 'alpina', '', '194.102.223.7', '213', 'Async', '0', '2002-11-14
09:57:29', '45', 'RADIUS', '', 'V34 31200 33600 LAPM/V42Bis', '3568',
'40701', '', '254221036', 'Host-Request', 'Framed-User', 'PPP',
'80.97.8.78', '0', '0')'
rlm_sql (sql): Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
radius_xlat: 'alpina'
Accounting: logout: login entry for NAS cyclades port 213 not found
modcall[accounting]: module "sradutmp" returns ok
modcall: group accounting returns ok
Sending Accounting-Response of id 9 to 194.102.223.7:1813
Finished request 0
Going to the next request
--- Walking the entire request list ---
Cleaning up request 0 ID 9 with timestamp 3dd35769
Nothing to do. Sleeping until we see a request.
rad_recv: Accounting-Request packet from host 194.102.223.7:1813, id=10,
length=174
User-Name = "test"
NAS-IP-Address = 194.102.223.7
NAS-Port = 214
NAS-Port-Type = Async
Acct-Authentic = RADIUS
Service-Type = Framed-User
Framed-Protocol = PPP
Framed-IP-Address = 80.97.8.79
Acct-Delay-Time = 0
Acct-Status-Type = Stop
Acct-Session-Id =
"\000\000\000\000\000\000\000\000\000\000\000\000\000\000\000"
Acct-Session-Time = 48
Acct-Input-Octets = 2983
Acct-Output-Octets = 773
Acct-Input-Packets = 38
Acct-Output-Packets = 23
Acct-Terminate-Cause = Host-Request
Calling-Station-Id = "254214121"
Connect-Info = "V34 28800 28800 LAPM/V42Bis"
modcall: entering group preacct
modcall[preacct]: module "files" returns noop
modcall: group preacct returns noop
modcall: entering group accounting
radius_xlat: '/usr/local/var/log/radius/radacct//detail-20021114'
rlm_detail:
/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d
expands to /usr/local/var/log/radius/radacct//detail-20021114
modcall[accounting]: module "detail" returns ok
radius_xlat: 'test'
Accounting: logout: login entry for NAS cyclades port 214 not found
modcall[accounting]: module "radutmp" returns ok
radius_xlat: 'test'
rlm_sql (sql): sql_set_user escaped user --> 'test'
radius_xlat: 'UPDATE radacct SET AcctStopTime = '2002-11-14 09:57:35',
AcctSessionTime = '48', AcctInputOctets = '2983', AcctOutputOctets =
'773', AcctTerminateCause = 'Host-Request', AcctStopDelay = '0',
ConnectInfo_stop = 'V34 28800 28800 LAPM/V42Bis' WHERE AcctSessionId =
'=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000'
AND UserName = 'test' AND NASIPAddress = '194.102.223.7' AND AcctStopTime
= 0'
rlm_sql (sql): Reserving sql socket id: 3
radius_xlat: '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('',
'=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000=5C000',
'', 'test', '', '194.102.223.7', '214', 'Async', '0', '2002-11-14
09:57:35', '48', 'RADIUS', '', 'V34 28800 28800 LAPM/V42Bis', '2983',
'773', '', '254214121', 'Host-Request', 'Framed-User', 'PPP',
'80.97.8.79', '0', '0')'
rlm_sql (sql): Released sql socket id: 3
modcall[accounting]: module "sql" returns ok
radius_xlat: 'test'
Accounting: logout: login entry for NAS cyclades port 214 not found
modcall[accounting]: module "sradutmp" returns ok
modcall: group accounting returns ok
Sending Accounting-Response of id 10 to 194.102.223.7:1813
Finished request 1
Going to the next request
--- Walking the entire request list ---
Cleaning up request 1 ID 10 with timestamp 3dd3576f
Nothing to do. Sleeping until we see a request.
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
