<html><head><style type="text/css"><!-- DIV {margin:0px;} --></style></head><body><div style="font-family:times new roman,new york,times,serif;font-size:12pt"><div>This is some of the output of radiusd -X<br><br>Starting - reading configuration files ...<br>reread_config: reading radiusd.conf<br>Config: including file: /usr/local/etc/raddb/proxy.conf<br>Config: including file: /usr/local/etc/raddb/clients.conf<br>Config: including file: /usr/local/etc/raddb/snmp.conf<br>Config: including file: /usr/local/etc/raddb/eap.conf<br>Config: including file: /usr/local/etc/raddb/mssql.conf<br> main: prefix = "/usr/local"<br> main: localstatedir = "/usr/local/var"<br> main: logdir = "/usr/local/var/log/radius"<br> main: libdir = "/usr/local/lib"<br> main: radacctdir = "/usr/local/var/log/radius/radacct"<br> main: hostname_lookups = no<br> main: max_request_time =
30<br> main: cleanup_delay = 5<br> main: max_requests = 1024<br> main: delete_blocked_requests = 0<br> main: port = 1812<br> main: allow_core_dumps = no<br> main: log_stripped_names = no<br> main: log_file = "/usr/local/var/log/radius/radius.log"<br> main: log_auth = yes<br> main: log_auth_badpass = yes<br> main: log_auth_goodpass = yes<br> main: pidfile = "/usr/local/var/run/radiusd/radiusd.pid"<br> main: user = "(null)"<br> main: group = "(null)"<br> main: usercollide = no<br> main: lower_user = "no"<br> main: lower_pass = "no"<br> main: nospace_user = "no"<br> main: nospace_pass = "no"<br> main: checkrad = "/usr/local/sbin/checkrad"<br> main: proxy_requests = yes<br> proxy: retry_delay = 5<br> proxy: retry_count = 3<br> proxy: synchronous = no<br> proxy: default_fallback = yes<br> proxy: dead_time = 120<br> proxy:
post_proxy_authorize = no<br> proxy: wake_all_if_all_dead = no<br> security: max_attributes = 200<br> security: reject_delay = 1<br> security: status_server = no<br> main: debug_level = 0<br>read_config_files: reading dictionary<br>read_config_files: reading naslist<br>Using deprecated naslist file. Support for this will go away soon.<br>read_config_files: reading clients<br>read_config_files: reading realms<br> listen: port = 1645<br> listen: type = "auth"<br> listen: port = 1646<br> listen: type = "acct"<br>radiusd: entering modules setup<br>Module: Library search path is /usr/local/lib<br>Module: Loaded exec<br> exec: wait = yes<br> exec: program = "(null)"<br> exec: input_pairs = "request"<br> exec: output_pairs = "(null)"<br> exec: packet_type = "(null)"<br>rlm_exec: Wait=yes but no output defined. Did you mean output=none?<br>Module:
Instantiated exec (exec)<br>Module: Loaded expr<br>Module: Instantiated expr (expr)<br>Module: Loaded PAP<br> pap: encryption_scheme = "crypt"<br> pap: auto_header = yes<br>Module: Instantiated pap (pap)<br>Module: Loaded CHAP<br>Module: Instantiated chap (chap)<br>Module: Loaded MS-CHAP<br> mschap: use_mppe = yes<br> mschap: require_encryption = no<br> mschap: require_strong = no<br> mschap: with_ntdomain_hack = no<br> mschap: passwd = "(null)"<br> mschap: ntlm_auth = "(null)"<br>Module: Instantiated mschap (mschap)<br>Module: Loaded System<br> unix: cache = no<br> unix: passwd = "(null)"<br> unix: shadow = "(null)"<br> unix: group = "(null)"<br> unix: radwtmp = "/usr/local/var/log/radius/radwtmp"<br> unix: usegroup = no<br> unix: cache_reload = 600<br>Module: Instantiated unix (unix)<br>Module: Loaded eap<br> eap: default_eap_type = "md5"<br> eap: timer_expire =
60<br> eap: ignore_unknown_eap_types = no<br> eap: cisco_accounting_username_bug = no<br>rlm_eap: Loaded and initialized type md5<br>rlm_eap: Loaded and initialized type leap<br> gtc: challenge = "Password: "<br> gtc: auth_type = "PAP"<br>rlm_eap: Loaded and initialized type gtc<br> mschapv2: with_ntdomain_hack = no<br>rlm_eap: Loaded and initialized type mschapv2<br>Module: Instantiated eap (eap)<br>Module: Loaded preprocess<br> preprocess: huntgroups = "/usr/local/etc/raddb/huntgroups"<br> preprocess: hints = "/usr/local/etc/raddb/hints"<br> preprocess: with_ascend_hack = no<br> preprocess: ascend_channels_per_line = 23<br> preprocess: with_ntdomain_hack = no<br> preprocess: with_specialix_jetstream_hack = no<br> preprocess: with_cisco_vsa_hack = no<br> preprocess: with_alvarion_vsa_hack = no<br>Module: Instantiated preprocess (preprocess)<br>Module: Loaded realm<br> realm: format =
"suffix"<br> realm: delimiter = "@"<br> realm: ignore_default = no<br> realm: ignore_null = no<br>Module: Instantiated realm (suffix)<br>Module: Loaded files<br> files: usersfile = "/usr/local/etc/raddb/users"<br> files: acctusersfile = "/usr/local/etc/raddb/acct_users"<br> files: preproxy_usersfile = "/usr/local/etc/raddb/preproxy_users"<br> files: compat = "no"<br>Module: Instantiated files (files)<br>Module: Loaded SQL<br> sql: driver = "rlm_sql_unixodbc"<br> sql: server = "MSSQL"<br> sql: port = ""<br> sql: login = "xxxxx"<br> sql: password = "xxxxx"<br> sql: radius_db = "radius"<br> sql: nas_table = "nas"<br> sql: sqltrace = no<br> sql: sqltracefile = "/usr/local/var/log/radius/sqltrace.sql"<br> sql: readclients = no<br> sql: deletestalesessions = yes<br> sql: num_sql_socks = 35<br> sql: sql_user_name = "%{User-Name}"<br> sql:
default_user_profile = ""<br> sql: query_on_not_found = no<br> sql: authorize_check_query = "SELECT id,UserName,Attribute,Value,op FROM radcheck WHERE Username = '%{SQL-User-Name}' ORDER BY id"<br> sql: authorize_reply_query = "SELECT id,UserName,Attribute,Value,op FROM radreply WHERE Username = '%{SQL-User-Name}' ORDER BY id"<br> sql: authorize_group_check_query = "SELECT radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op FROM radgroupcheck,radusergroup WHERE radusergroup.Username = '%{SQL-User-Name}' AND radusergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id"<br> sql: authorize_group_reply_query = "SELECT radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op FROM radgroupreply,radusergroup WHERE radusergroup.Username = '%{SQL-User-Name}' AND radusergroup.GroupName = radgroupreply.GroupName ORDER BY
radgroupreply.id"<br> sql: accounting_onoff_query = ""<br> sql: accounting_update_query = "EXEC acct_update '%S', '%{Acct-Session-Time}', '%{Acct-Input-Octets}', '%{Acct-Output-Octets}', '%{Acct-Input-Gigawords}', '%{Acct-Output-Gigawords}', 'Acct-Update', '%{Acct-Delay-Time}', '%{Connect-Info}', '%{Acct-Session-Id}', '%{SQL-User-Name}', '%{NAS-IP-Address}', '%{Acct-Unique-Session-Id}', '%{Realm}', '0', '%{NAS-Port-Type}', '%{Acct-Authentic}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}';"<br> sql: accounting_update_query_alt = "INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctSessionTime, AcctAuthentic, ConnectInfo_start, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, XAscendSessionSvrKey) VALUES('%{Acct-Session-Id}',
'%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port-Id}', '%{NAS-Port-Type}', '%{Acct-Session-Time}', '%{Acct-Authentic}', '', '%{Acct-Input-Octets}', '%{Acct-Output-Octets}', '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '0', '%{X-Ascend-Session-Svr-Key}');INSERT into radlac values ('%{Acct-Unique-Session-Id}','%{Tunnel-Client-Auth-Id}', '%{SQL-User-Name}', '%S')"<br> sql: accounting_start_query = "INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, AcctStopDelay, XAscendSessionSvrKey) VALUES('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',
'%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', '%{NAS-Port-Id}', '%{NAS-Port-Type}', '%S', '0', '%{Acct-Authentic}', '%{Connect-Info}', '', '0', '0', '%{Called-Station-Id}', '%{Calling-Station-Id}', '', '%{Service-Type}', '%{Framed-Protocol}', '%{Framed-IP-Address}', '%{Acct-Delay-Time}', '0', '%{X-Ascend-Session-Svr-Key}'); INSERT into radlac values ('%{Acct-Unique-Session-Id}','%{Tunnel-Client-Auth-Id}', '%{SQL-User-Name}', '%S')"<br> sql: accounting_start_query_alt = ""<br> sql: accounting_stop_query = "EXEC acct_stop '%S', '%{Acct-Session-Time}', '%{Acct-Input-Octets}', '%{Acct-Output-Octets}', '%{Acct-Input-Gigawords}', '%{Acct-Output-Gigawords}', '%{Acct-Terminate-Cause}', '%{Acct-Delay-Time}', '%{Connect-Info}', '%{Acct-Session-Id}', '%{SQL-User-Name}', '%{NAS-IP-Address}';"<br> sql: accounting_stop_query_alt = ""<br> sql: group_membership_query = ""<br> sql: connect_failure_retry_delay = 60<br> sql:
simul_count_query = "SELECT COUNT(*) FROM radacct WHERE UserName='%{SQL-User-Name}' AND AcctStopTime = 0"<br> sql: simul_verify_query = "SELECT RadAcctId, AcctSessionId, UserName, NASIPAddress, NASPortId, FramedIPAddress, CallingStationId, FramedProtocol FROM radacct WHERE UserName='%{SQL-User-Name}' AND AcctStopTime = 0"<br> sql: postauth_query = ""<br> sql: safe-characters = "@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"<br>rlm_sql (sql): Driver rlm_sql_unixodbc (module rlm_sql_unixodbc) loaded and linked<br>rlm_sql (sql): Attempting to connect to xxxxx@MSSQL:/radius<br>rlm_sql (sql): starting 0<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #0<br>rlm_sql (sql): Connected new DB handle, #0<br>rlm_sql (sql): starting 1<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #1<br>rlm_sql (sql): Connected new DB handle, #1<br>rlm_sql (sql): starting 2<br>rlm_sql (sql): Attempting to connect
rlm_sql_unixodbc #2<br>rlm_sql (sql): Connected new DB handle, #2<br>rlm_sql (sql): starting 3<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #3<br>rlm_sql (sql): Connected new DB handle, #3<br>rlm_sql (sql): starting 4<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #4<br>rlm_sql (sql): Connected new DB handle, #4<br>rlm_sql (sql): starting 5<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #5<br>rlm_sql (sql): Connected new DB handle, #5<br>rlm_sql (sql): starting 6<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #6<br>rlm_sql (sql): Connected new DB handle, #6<br>rlm_sql (sql): starting 7<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #7<br>rlm_sql (sql): Connected new DB handle, #7<br>rlm_sql (sql): starting 8<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #8<br>rlm_sql (sql): Connected new DB handle, #8<br>rlm_sql (sql): starting 9<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc
#9<br>rlm_sql (sql): Connected new DB handle, #9<br>rlm_sql (sql): starting 10<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #10<br>rlm_sql (sql): Connected new DB handle, #10<br>rlm_sql (sql): starting 11<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #11<br>rlm_sql (sql): Connected new DB handle, #11<br>rlm_sql (sql): starting 12<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #12<br>rlm_sql (sql): Connected new DB handle, #12<br>rlm_sql (sql): starting 13<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #13<br>rlm_sql (sql): Connected new DB handle, #13<br>rlm_sql (sql): starting 14<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #14<br>rlm_sql (sql): Connected new DB handle, #14<br>rlm_sql (sql): starting 15<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #15<br>rlm_sql (sql): Connected new DB handle, #15<br>rlm_sql (sql): starting 16<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc
#16<br>rlm_sql (sql): Connected new DB handle, #16<br>rlm_sql (sql): starting 17<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #17<br>rlm_sql (sql): Connected new DB handle, #17<br>rlm_sql (sql): starting 18<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #18<br>rlm_sql (sql): Connected new DB handle, #18<br>rlm_sql (sql): starting 19<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #19<br>rlm_sql (sql): Connected new DB handle, #19<br>rlm_sql (sql): starting 20<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #20<br>rlm_sql (sql): Connected new DB handle, #20<br>rlm_sql (sql): starting 21<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #21<br>rlm_sql (sql): Connected new DB handle, #21<br>rlm_sql (sql): starting 22<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #22<br>rlm_sql (sql): Connected new DB handle, #22<br>rlm_sql (sql): starting 23<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc
#23<br>rlm_sql (sql): Connected new DB handle, #23<br>rlm_sql (sql): starting 24<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #24<br>rlm_sql (sql): Connected new DB handle, #24<br>rlm_sql (sql): starting 25<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #25<br>rlm_sql (sql): Connected new DB handle, #25<br>rlm_sql (sql): starting 26<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #26<br>rlm_sql (sql): Connected new DB handle, #26<br>rlm_sql (sql): starting 27<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #27<br>rlm_sql (sql): Connected new DB handle, #27<br>rlm_sql (sql): starting 28<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #28<br>rlm_sql (sql): Connected new DB handle, #28<br>rlm_sql (sql): starting 29<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #29<br>rlm_sql (sql): Connected new DB handle, #29<br>rlm_sql (sql): starting 30<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc
#30<br>rlm_sql (sql): Connected new DB handle, #30<br>rlm_sql (sql): starting 31<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #31<br>rlm_sql (sql): Connected new DB handle, #31<br>rlm_sql (sql): starting 32<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #32<br>rlm_sql (sql): Connected new DB handle, #32<br>rlm_sql (sql): starting 33<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #33<br>rlm_sql (sql): Connected new DB handle, #33<br>rlm_sql (sql): starting 34<br>rlm_sql (sql): Attempting to connect rlm_sql_unixodbc #34<br>rlm_sql (sql): Connected new DB handle, #34<br>Module: Instantiated sql (sql)<br>Module: Loaded Acct-Unique-Session-Id<br> acct_unique: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, NAS-Port"<br>Module: Instantiated acct_unique (acct_unique)<br>Module: Loaded detail<br> detail: detailfile =
"/usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d"<br> detail: detailperm = 384<br> detail: dirperm = 493<br> detail: locking = no<br>Module: Instantiated detail (detail)<br>Module: Loaded radutmp<br> radutmp: filename = "/usr/local/var/log/radius/radutmp"<br> radutmp: username = "%{User-Name}"<br> radutmp: case_sensitive = yes<br> radutmp: check_with_nas = yes<br> radutmp: perm = 384<br> radutmp: callerid = yes<br>Module: Instantiated radutmp (radutmp)<br>Module: Loaded IPPOOL<br> ippool: session-db = "/usr/local/etc/raddb/db.ippool"<br> ippool: ip-index = "/usr/local/etc/raddb/db.ipindex"<br> ippool: range-start = 192.168.8.1 IP address [192.168.8.1]<br> ippool: range-stop = 192.168.8.254 IP address [192.168.8.254]<br> ippool: netmask = 255.255.255.0 IP address [255.255.255.0]<br> ippool: cache-size = 25400<br> ippool: override = yes<br> ippool:
maximum-timeout = 0<br>Module: Instantiated ippool (pool)<br>Listening on authentication *:1645<br>Listening on accounting *:1646<br>Ready to process requests.<br>rad_recv: Accounting-Request packet from host xxx.xxx.xxx.xxx:1646, id=165, length=240<br> Acct-Session-Id = "00023865"<br> Tunnel-Type:0 = L2TP<br> Tunnel-Medium-Type:0 = IPv4<br> Tunnel-Server-Endpoint:0 = "xxx.xxx.xxx.xxx"<br> Tunnel-Client-Endpoint:0 = "xxx.xxx.xxx.xxx"<br> Tunnel-Assignment-Id:0 = "1"<br> Tunnel-Client-Auth-Id:0 = "xxxxxxx"<br> Tunnel-Server-Auth-Id:0 = "xxxxxxx"<br> Acct-Tunnel-Connection =
"739313803"<br> Framed-Protocol = PPP<br> Framed-IP-Address = xxx.xxx.xxx.xxx<br> User-Name = ""<br> Acct-Session-Time = 15270<br> Acct-Input-Octets = 193292<br> Acct-Output-Octets = 180794<br> Acct-Input-Packets = 3115<br> Acct-Output-Packets = 3120<br> Acct-Authentic = RADIUS<br> Acct-Status-Type = Interim-Update<br> NAS-Port-Type = Virtual<br> NAS-Port = 204<br> NAS-Port-Id =
"Uniq-Sess-ID204"<br> Connect-Info = "153354240"<br> Service-Type = Framed-User<br> NAS-IP-Address = xxx.xxx.xxx.xxx<br> Acct-Delay-Time = 0<br> Processing the preacct section of radiusd.conf<br>modcall: entering group preacct for request 46<br> modcall[preacct]: module "preprocess" returns noop for request 46<br>rlm_acct_unique: Hashing 'NAS-Port = 204,Client-IP-Address = xxx.xxx.xxx.xxx,NAS-IP-Address = xxx.xxx.xxx.xxx,Acct-Session-Id = "00023865",User-Name = "xxxxxxx@xxxx"'<br>rlm_acct_unique: Acct-Unique-Session-ID = "75aa601bea890dcd".<br> modcall[preacct]: module "acct_unique" returns ok for request 46<br> rlm_realm: Looking up realm "xxxx" for User-Name = "xxxxxxx@xxxx"<br> rlm_realm: Found realm "DEFAULT"<br>
rlm_realm: Adding Stripped-User-Name = "xxxxxxx"<br> rlm_realm: Proxying request from user xxxxxxx to realm DEFAULT<br> rlm_realm: Adding Realm = "DEFAULT"<br> rlm_realm: Accounting realm is LOCAL.<br> modcall[preacct]: module "suffix" returns noop for request 46<br> modcall[preacct]: module "files" returns noop for request 46<br>modcall: leaving group preacct (returns ok) for request 46<br> Processing the accounting section of radiusd.conf<br>modcall: entering group accounting for request 46<br>radius_xlat: '/usr/local/var/log/radius/radacct/xxx.xxx.xxx.xxx/detail-20090318'<br>rlm_detail: /usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /usr/local/var/log/radius/radacct/xxx.xxx.xxx.xxx/detail-20090318<br> modcall[accounting]: module "detail" returns ok for request 46<br> modcall[accounting]: module "unix" returns noop for request
46<br>radius_xlat: '/usr/local/var/log/radius/radutmp'<br>radius_xlat: 'royalceramic@aya.sy'<br> modcall[accounting]: module "radutmp" returns ok for request 46<br>rlm_ippool: This is not an Accounting-Stop. Return NOOP.<br> modcall[accounting]: module "pool" returns noop for request 46<br>radius_xlat: 'xxxxxx@xxxx'<br>rlm_sql (sql): sql_set_user escaped user --> 'xxxxxx@xxxx'<br>radius_xlat: 'EXEC acct_update '2009-03-18 22:45:41', '15270', '193292', '180794', '', '', 'Acct-Update', '0', '153354240', '00023865', 'xxxxxx@xxxx', 'xxx.xxx.xxx.xxx', '75aa601bea890dcd', 'DEFAULT', '0', 'Virtual', 'RADIUS', '', '', 'Framed-User', 'PPP', 'xxx.xxx.xxx.xxx';'<br>rlm_sql (sql): Reserving sql socket id: 19<br>rlm_sql (sql): Released sql socket id: 19<br> modcall[accounting]: module "sql" returns ok for request 46<br>modcall: leaving group accounting (returns ok) for request 46<br>Sending Accounting-Response of id 165
to 10.10.10.5 port 1646<br>Finished request 46<br>Going to the next request<br>--- Walking the entire request list ---<br>Waking up in 6 seconds...<br>rad_recv: Accounting-Request packet from host xxx.xxx.xxx.xxx:1646, id=166, length=237<br> Acct-Session-Id = "0002380B"<br> Tunnel-Type:0 = L2TP<br> Tunnel-Medium-Type:0 = IPv4<br> Tunnel-Server-Endpoint:0 = "xxx.xxx.xxx.xxx"<br> Tunnel-Client-Endpoint:0 = "xxx.xxx.xxx.xxx"<br> Tunnel-Assignment-Id:0 = "1"<br> Tunnel-Client-Auth-Id:0 = "xxxxxxxx"<br> Tunnel-Server-Auth-Id:0 = "xxxxxxxx"<br> Acct-Tunnel-Connection =
"3987015321"<br> Framed-Protocol = PPP<br> Framed-IP-Address = xxx.xxx.xxx.xxx<br> User-Name = "xxxxxxxx@xxxx"<br> Acct-Session-Time = 17096<br> Acct-Input-Octets = 253790<br> Acct-Output-Octets = 426579<br> Acct-Input-Packets = 3165<br> Acct-Output-Packets = 3240<br> Acct-Authentic = RADIUS<br> Acct-Status-Type = Interim-Update<br> NAS-Port-Type = Virtual<br> NAS-Port = 894<br> NAS-Port-Id =
"Uniq-Sess-ID894"<br> Connect-Info = "153354240"<br> Service-Type = Framed-User<br> NAS-IP-Address = xxx.xxx.xxx.xxx<br> Acct-Delay-Time = 0<br> Processing the preacct section of radiusd.conf<br>modcall: entering group preacct for request 47<br> modcall[preacct]: module "preprocess" returns noop for request 47<br>rlm_acct_unique: Hashing 'NAS-Port = 894,Client-IP-Address = xxx.xxx.xxx.xxx,NAS-IP-Address = xxx.xxx.xxx.xxx,Acct-Session-Id = "0002380B",User-Name = "xxxxxxxx@xxxx"'<br>rlm_acct_unique: Acct-Unique-Session-ID = "2aa4d04abb7544a1".<br> modcall[preacct]: module "acct_unique" returns ok for request 47<br> rlm_realm: Looking up realm "xxxx" for User-Name = "xxxxxxx@xxxxx"<br> rlm_realm: Found realm
"DEFAULT"<br> rlm_realm: Adding Stripped-User-Name = "xxxxxxxx"<br> rlm_realm: Proxying request from user xxxxxxxx to realm DEFAULT<br> rlm_realm: Adding Realm = "DEFAULT"<br> rlm_realm: Accounting realm is LOCAL.<br> modcall[preacct]: module "suffix" returns noop for request 47<br> modcall[preacct]: module "files" returns noop for request 47<br>modcall: leaving group preacct (returns ok) for request 47<br> Processing the accounting section of radiusd.conf<br>modcall: entering group accounting for request 47<br>radius_xlat: '/usr/local/var/log/radius/radacct/xxx.xxx.xxx.xxx/detail-20090318'<br>rlm_detail: /usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /usr/local/var/log/radius/radacct/xxx.xxx.xxx.xxx/detail-20090318<br> modcall[accounting]: module "detail" returns ok for request 47<br> modcall[accounting]: module
"unix" returns noop for request 47<br>radius_xlat: '/usr/local/var/log/radius/radutmp'<br>radius_xlat: 'xxxxxxxxx@xxxx'<br> modcall[accounting]: module "radutmp" returns ok for request 47<br>rlm_ippool: This is not an Accounting-Stop. Return NOOP.<br> modcall[accounting]: module "pool" returns noop for request 47<br>radius_xlat: 'xxxxxxxx@xxxx'<br>rlm_sql (sql): sql_set_user escaped user --> 'xxxxxxxxxx@xxxx'<br>radius_xlat: 'EXEC acct_update '2009-03-18 22:45:44', '17096', '253790', '426579', '', '', 'Acct-Update', '0', '153354240', '0002380B', 'xxxxxxx@xxxx', 'xxx.xxx.xxx.xxx', '2aa4d04abb7544a1', 'DEFAULT', '0', 'Virtual', 'RADIUS', '', '', 'Framed-User', 'PPP', 'xxx.xxx.xxx.xxx';'<br>rlm_sql (sql): Reserving sql socket id: 18<br>rlm_sql (sql): Couldn't update SQL accounting ALIVE record - 00000 <br>rlm_sql (sql): Released sql socket id: 18<br> modcall[accounting]: module "sql" returns fail for request
47<br>modcall: leaving group accounting (returns fail) for request 47<br>Finished request 47<br>Going to the next request<br>--- Walking the entire request list ---<br>Waking up in 3 seconds...Going to the next request<br>--- Walking the entire request list ---<br>Waking up in 1 seconds...<br>rad_recv: Access-Request packet from host xxx.xxx.xxx.xxx:1645, id=252, length=98<br> User-Name = "xxxxx@xxx"<br> User-Password = "xxxxxxx"<br> NAS-Port-Type = Virtual<br> NAS-Port = 799<br> NAS-Port-Id = "Uniq-Sess-ID799"<br> Connect-Info = "153354240"<br> Service-Type = Dialout-Framed-User<br> NAS-IP-Address =
xxx.xxx.xxx.xxx<br> Processing the authorize section of radiusd.conf<br>modcall: entering group authorize for request 193<br> modcall[authorize]: module "preprocess" returns ok for request 193<br> modcall[authorize]: module "chap" returns noop for request 193<br> modcall[authorize]: module "mschap" returns noop for request 193<br> rlm_realm: No '@' in User-Name = "xxxxxxx", looking up realm NULL<br> rlm_realm: No such realm "NULL"<br> modcall[authorize]: module "suffix" returns noop for request 193<br> rlm_eap: No EAP-Message, not doing EAP<br> modcall[authorize]: module "eap" returns noop for request 193<br> users: Matched entry DEFAULT at line 153<br> modcall[authorize]: module "files" returns ok for request 193<br>radius_xlat: 'xxxxxxxx'<br>rlm_sql (sql): sql_set_user escaped user --> 'xxxxxx'<br>radius_xlat: 'SELECT
id,UserName,Attribute,Value,op FROM radcheck WHERE Username = 'xxxxxx' ORDER BY id'<br>rlm_sql (sql): Reserving sql socket id: 6<br>rlm_sql_getvpdata: database query error<br>rlm_sql (sql): SQL query error; rejecting user<br>rlm_sql (sql): Released sql socket id: 6<br> modcall[authorize]: module "sql" returns fail for request 193<br>modcall: leaving group authorize (returns fail) for request 193<br><br><br>most of the output of the debug is normal but every now and then I get these errors also the authentications sometimes takes long time, about 30 seconds to get the accept packet from the radius<br><br>I really appreciate your help<br><br>Best Regards<br>Ahmed Adel<br></div><div style="font-family: times new roman,new york,times,serif; font-size: 12pt;"><br><div style="font-family: arial,helvetica,sans-serif; font-size: 13px;"><font face="Tahoma" size="2"><hr size="1"><b><span style="font-weight: bold;">From:</span></b> "tnt@kalik.net"
<tnt@kalik.net><br><b><span style="font-weight: bold;">To:</span></b> FreeRadius users mailing list <freeradius-users@lists.freeradius.org><br><b><span style="font-weight: bold;">Sent:</span></b> Tuesday, March 17, 2009 6:18:19 PM<br><b><span style="font-weight: bold;">Subject:</span></b> Re: Accounting error<br></font><br>
> I hope anyone can help me with these errors I have in the<br>>radius.log file:<br>><br>>Error: rlm_sql_getvpdata: database query error<br>>Error: rlm_sql (sql): SQL query error; rejecting user<br>>Error: rlm_sql (sql): Couldn't update SQL accounting ALIVE record -<br>>00000<br>><br>><br>> I am using freeradius 1.1.7 with freetds and MSSQL 2005 as the<br>>backend database, the radius receives interim accounting update packets<br>>to calculate users utlized bandwidth and the data is inserted into the<br>>MSSQL database using SQL Procedures.<br>><br>> Does anyone knows what may cause these errors to occure, note that<br>>when I run radius -X most of the update statements return with status<br>>ok and for the statements that return with this error, they run fine<br>>from the MSSQL console.<br>><br><br>Post the radiusd -X output showing those
errors.<br><br>Ivan Kalik<br>Kalik Informatika ISP<br><br>-<br><span>List info/subscribe/unsubscribe? See <a target="_blank" href="http://www.freeradius.org/list/users.html">http://www.freeradius.org/list/users.html</a></span><br></div></div></div><br>
</body></html>