Matt,
I uncomment the acct_unique,
accounting {
acct_unique
sql
# detail
# counter
# unix
# radutmp
# sradutmp
}
then I can record one times when I test it directly.
But when I test the proxy function, it still record 4 times! Then I trace radiusd -X
to a log file, it seems that the freeradius didn't send Accouting_Response which sent
by end radiuse server to NAS. So the NAS continued to send Accouting_Request! Why? Is
my config wrong? What should I do?
This is the log file:
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/sql.conf
main: prefix = "/usr/local/freeradius"
main: localstatedir = "/usr/local/freeradius/var"
main: logdir = "/var/log/radius"
main: libdir = "/usr/local/freeradius/lib"
main: radacctdir = "/var/log/radius/radacct"
main: hostname_lookups = no
read_config_files: reading dictionary
read_config_files: reading clients
read_config_files: reading realms
read_config_files: reading naslist
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_auth = no
main: log_auth_badpass = no
main: log_auth_goodpass = no
main: pidfile = "/usr/local/freeradius/var/run/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: proxy_requests = yes
proxy: retry_delay = 5
proxy: retry_count = 3
proxy: synchronous = yes
read_config_files: entering modules setup
Module: Library search path is /usr/local/freeradius/lib
Module: Loaded SQL
sql: driver = "rlm_sql_oracle"
sql: server = "localhost"
sql: port = ""
sql: login = "summer"
sql: password = "summer"
sql: radius_db = "summer"
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 = "/var/log/radius/sqltrace.sql"
sql: deletestalesessions = yes
sql: num_sql_socks = 5
sql: sql_user_name = "%{User-Name}"
sql: authorize_check_query = "SELECT id,UserName,Attribute,Value FROM radcheck WHERE
Username = '%{SQL-User-Name}' ORDER BY id"
sql: authorize_reply_query = "SELECT id,UserName,Attribute,Value FROM radreply WHERE
Username = '%{SQL-User-Name}' ORDER BY id"
sql: authorize_group_check_query = "SELECT
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value
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
FROM radgroupreply,usergroup WHERE usergroup.Username = '%{SQL-User-Name}' AND
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id"
sql: authenticate_query = "SELECT Value,Attribute FROM radcheck WHERE UserName =
'%{User-Name}' AND ( Attribute = 'Password' OR Attribute = 'Crypt-Password' ) ORDER BY
Attribute DESC"
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='' 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}'"
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-Id}',
'%{NAS-Port-Type}',to_date('%S','yyyy-mm-dd hh24:mi:ss'),'', '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 =
to_date('%S','yyyy-mm-dd hh24:mi:ss'), AcctStartDelay = '%{Acct-Delay-Time}',
ConnectInfo_start = '%{Connect-Info}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND
UserName = '%{SQL-User-Name}' AND NASIPAddress = '%{NAS-IP-Address}'"
sql: accounting_stop_query = "UPDATE radacct SET AcctStopTime =
to_date('%S','yyyy-mm-dd hh24:mi:ss'), 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}'"
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-Id}',
'%{NAS-Port-Type}', '', to_date('%S','yyyy-mm-dd hh24:mi:ss'), '%{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: connect_failure_retry_delay = 60
rlm_sql: Driver rlm_sql_oracle loaded and linked
rlm_sql: Attempting to connect to summer@localhost:/summer
rlm_sql: Connected new DB handle, #0
rlm_sql: Connected new DB handle, #1
rlm_sql: Connected new DB handle, #2
rlm_sql: Connected new DB handle, #3
rlm_sql: Connected new DB handle, #4
Module: Instantiated sql (sql)
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 = 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 Acct-Unique-Session-Id
acct_unique: key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address,
NAS-Port-Id"
Module: Instantiated acct_unique (acct_unique)
Module: Loaded radutmp
radutmp: filename = "/var/log/radius/radutmp"
radutmp: perm = 384
radutmp: callerid = yes
Module: Instantiated radutmp (radutmp)
Listening on IP address *, ports 1812/udp and 1813/udp, with proxy on 1814/udp.
Ready to process requests.
rad_recv: Access-Request packet from host 192.168.0.6:1645, id=193, length=96
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Password = "\225b\232\t(]G\326\032*\000~3O\000\264"
Service-Type = Framed-User
Framed-Protocol = PPP
modcall: entering group authorize
modcall[authorize]: module "preprocess" returns ok
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[authorize]: module "suffix" returns updated
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'SELECT id,UserName,Attribute,Value FROM radcheck WHERE Username =
'[EMAIL PROTECTED]' ORDER BY id'
sql_escape in: 'DEFAULT'
sql_escape out: 'DEFAULT'
sql_set_user: escaped user --> 'DEFAULT'
radius_xlat: 'SELECT
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'DEFAULT' AND
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id'
radius_xlat: 'SELECT
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value
FROM radgroupreply,usergroup WHERE usergroup.Username = 'DEFAULT' AND
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id'
rlm_sql: Released sql socket id: 4
rlm_sql: User DEFAULT not found and DEFAULT not found
modcall[authorize]: module "sql" returns notfound
modcall: group authorize returns updated
Sending Access-Request of id 1 to 192.168.0.88:1812
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Password = "\037\256F\233\264X\316\302U\257;\326\300\377\005\337"
Service-Type = Framed-User
Framed-Protocol = PPP
Proxy-State = "193"
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Access-Accept packet from host 192.168.0.88:1812, id=1, length=25
Proxy-State = 0x313933
modcall: entering group authorize
modcall[authorize]: module "preprocess" returns ok
modcall[authorize]: module "suffix" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'SELECT id,UserName,Attribute,Value FROM radcheck WHERE Username =
'[EMAIL PROTECTED]' ORDER BY id'
sql_escape in: 'DEFAULT'
sql_escape out: 'DEFAULT'
sql_set_user: escaped user --> 'DEFAULT'
radius_xlat: 'SELECT
radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value
FROM radgroupcheck,usergroup WHERE usergroup.Username = 'DEFAULT' AND
usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id'
radius_xlat: 'SELECT
radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value
FROM radgroupreply,usergroup WHERE usergroup.Username = 'DEFAULT' AND
usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id'
rlm_sql: Released sql socket id: 4
rlm_sql: User DEFAULT not found and DEFAULT not found
modcall[authorize]: module "sql" returns notfound
modcall: group authorize returns ok
rad_check_password: Found auth-type
rad_check_password: Auth-Type = Accept, accepting the user
Sending Access-Accept of id 193 to 192.168.0.6:1645
Proxy-State = 0x313933
Finished request 0
Going to the next request
rl_next: returning NULL
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=194, length=106
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 0
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', '', '192.168.0.6', '', 'Async',to_date('2001-09-14
15:19:50','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '', '', '0', '0', '', '', '',
'Framed-User', 'PPP', '', '0', '0')'
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 2 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 0
Proxy-State = "194"
Waking up in 6 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=2, length=25
Proxy-State = 0x313934
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', 'xxy.com', '192.168.0.6', '',
'Async',to_date('2001-09-14 15:19:50','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '',
'', '0', '0', '', '', '', 'Framed-User', 'PPP', '', '0', '0')'
rlm_sql: Couldn't update SQL accounting for ALIVE packet - ORA-00001: unique
constraint (SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
radius_xlat: 'UPDATE radacct SET AcctStartTime = to_date('2001-09-14
15:19:50','yyyy-mm-dd hh24:mi:ss'), AcctStartDelay = '0', ConnectInfo_start = '' WHERE
AcctSessionId = '0000008F' AND UserName = '[EMAIL PROTECTED]' AND NASIPAddress =
'192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 2
Going to the next request
Cleaning up request 2 ID 194 with timestamp 3ba1af96
rl_next: returning NULL
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=195, length=106
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 5
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', '', '192.168.0.6', '', 'Async',to_date('2001-09-14
15:19:55','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '', '', '0', '0', '', '', '',
'Framed-User', 'PPP', '', '5', '0')'
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 3 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 5
Proxy-State = "195"
--- Walking the entire request list ---
Waking up in 1 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=3, length=25
Proxy-State = 0x313935
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', 'xxy.com', '192.168.0.6', '',
'Async',to_date('2001-09-14 15:19:55','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '',
'', '0', '0', '', '', '', 'Framed-User', 'PPP', '', '5', '0')'
rlm_sql: Couldn't update SQL accounting for ALIVE packet - ORA-00001: unique
constraint (SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
radius_xlat: 'UPDATE radacct SET AcctStartTime = to_date('2001-09-14
15:19:55','yyyy-mm-dd hh24:mi:ss'), AcctStartDelay = '5', ConnectInfo_start = '' WHERE
AcctSessionId = '0000008F' AND UserName = '[EMAIL PROTECTED]' AND NASIPAddress =
'192.168.0.6''
rlm_sql: Couldn't update SQLaccounting START record - ORA-00001: unique constraint
(SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 4
Going to the next request
Cleaning up request 0 ID 193 with timestamp 3ba1af96
rl_next: returning NULL
Cleaning up request 4 ID 195 with timestamp 3ba1af9b
Waking up in 1 seconds...
--- Walking the entire request list ---
Nothing to do. Sleeping until we see a request.
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=196, length=106
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 10
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', '', '192.168.0.6', '', 'Async',to_date('2001-09-14
15:20:00','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '', '', '0', '0', '', '', '',
'Framed-User', 'PPP', '', '10', '0')'
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 4 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 10
Proxy-State = "196"
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=4, length=25
Proxy-State = 0x313936
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', 'xxy.com', '192.168.0.6', '',
'Async',to_date('2001-09-14 15:20:00','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '',
'', '0', '0', '', '', '', 'Framed-User', 'PPP', '', '10', '0')'
rlm_sql: Couldn't update SQL accounting for ALIVE packet - ORA-00001: unique
constraint (SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
radius_xlat: 'UPDATE radacct SET AcctStartTime = to_date('2001-09-14
15:20:00','yyyy-mm-dd hh24:mi:ss'), AcctStartDelay = '10', ConnectInfo_start = ''
WHERE AcctSessionId = '0000008F' AND UserName = '[EMAIL PROTECTED]' AND NASIPAddress =
'192.168.0.6''
rlm_sql: Couldn't update SQLaccounting START record - ORA-00001: unique constraint
(SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 6
Going to the next request
rl_next: returning NULL
Cleaning up request 6 ID 196 with timestamp 3ba1afa0
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=197, length=106
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 15
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', '', '192.168.0.6', '', 'Async',to_date('2001-09-14
15:20:05','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '', '', '0', '0', '', '', '',
'Framed-User', 'PPP', '', '15', '0')'
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 5 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Start
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Acct-Delay-Time = 15
Proxy-State = "197"
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=5, length=25
Proxy-State = 0x313937
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
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('', '0000008F',
'0fb92db3a0fea360', '[EMAIL PROTECTED]', 'xxy.com', '192.168.0.6', '',
'Async',to_date('2001-09-14 15:20:05','yyyy-mm-dd hh24:mi:ss'),'', '0', 'RADIUS', '',
'', '0', '0', '', '', '', 'Framed-User', 'PPP', '', '15', '0')'
rlm_sql: Couldn't update SQL accounting for ALIVE packet - ORA-00001: unique
constraint (SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
radius_xlat: 'UPDATE radacct SET AcctStartTime = to_date('2001-09-14
15:20:05','yyyy-mm-dd hh24:mi:ss'), AcctStartDelay = '15', ConnectInfo_start = ''
WHERE AcctSessionId = '0000008F' AND UserName = '[EMAIL PROTECTED]' AND NASIPAddress =
'192.168.0.6''
rlm_sql: Couldn't update SQLaccounting START record - ORA-00001: unique constraint
(SUMMER.RADACCT_IDX1) violated
sql_num_fields: error retrieving colun count: ORA-24338: statement handle not executed
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 8
Going to the next request
rl_next: returning NULL
Cleaning up request 8 ID 197 with timestamp 3ba1afa5
Waking up in 6 seconds...
--- Walking the entire request list ---
Nothing to do. Sleeping until we see a request.
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=198, length=148
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 0
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:01','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '0', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 6 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 0
Proxy-State = "198"
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=6, length=25
Proxy-State = 0x313938
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:01','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '0', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 10
Going to the next request
rl_next: returning NULL
Cleaning up request 10 ID 198 with timestamp 3ba1b66d
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=199, length=148
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 5
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:06','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '5', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 7 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 5
Proxy-State = "199"
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=7, length=25
Proxy-State = 0x313939
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:06','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '5', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 12
Going to the next request
rl_next: returning NULL
Cleaning up request 12 ID 199 with timestamp 3ba1b672
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=200, length=148
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 10
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:11','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '10', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Sending Accounting-Request of id 8 to 192.168.0.88:1813
User-Name = "88"
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 10
Proxy-State = "200"
--- Walking the entire request list ---
Waking up in 6 seconds...
rad_recv: Accounting-Response packet from host 192.168.0.88:1813, id=8, length=25
Proxy-State = 0x323030
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:11','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '10', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
rlm_sql: Released sql socket id: 4
modcall[accounting]: module "sql" returns ok
modcall: group accounting returns ok
Finished request 14
Going to the next request
rl_next: returning NULL
Cleaning up request 14 ID 200 with timestamp 3ba1b677
Waking up in 6 seconds...
rad_recv: Accounting-Request packet from host 192.168.0.6:1646, id=201, length=148
NAS-IP-Address = 192.168.0.6
NAS-Port = 1
Cisco-NAS-Port = "Async1"
NAS-Port-Type = Async
User-Name = "[EMAIL PROTECTED]"
Called-Station-Id = ""
Acct-Status-Type = Stop
Acct-Authentic = RADIUS
Service-Type = Framed-User
Acct-Session-Id = "0000008F"
Framed-Protocol = PPP
Framed-IP-Address = 10.192.168.19
Acct-Terminate-Cause = User-Request
Acct-Input-Octets = 14651
Acct-Output-Octets = 33140
Acct-Input-Packets = 169
Acct-Output-Packets = 181
Acct-Session-Time = 1751
Acct-Delay-Time = 15
modcall: entering group preacct
rlm_realm: Proxying request from user 88 to realm xxy.com
modcall[preacct]: module "suffix" returns ok
modcall[preacct]: module "preprocess" returns noop
modcall: group preacct returns ok
modcall: entering group accounting
rlm_acct_unique: Hashing ',Client-IP-Address = 192.168.0.6,NAS-IP-Address =
192.168.0.6,Acct-Session-Id = "0000008F",User-Name = "[EMAIL PROTECTED]"'
rlm_acct_unique: Acct-Unique-Session-ID = "0fb92db3a0fea360".
modcall[accounting]: module "acct_unique" returns ok
rlm_sql: Reserving sql socket id: 4
radius_xlat: '[EMAIL PROTECTED]'
sql_escape in: '[EMAIL PROTECTED]'
sql_escape out: '[EMAIL PROTECTED]'
sql_set_user: escaped user --> '[EMAIL PROTECTED]'
radius_xlat: 'UPDATE radacct SET AcctStopTime = to_date('2001-09-14
15:49:16','yyyy-mm-dd hh24:mi:ss'), AcctSessionTime = '1751', AcctInputOctets =
'14651', AcctOutputOctets = '33140', AcctTerminateCause = 'User-Request',
AcctStopDelay = '15', ConnectInfo_stop = '' WHERE AcctSessionId = '0000008F' AND
UserName = '[EMAIL PROTECTED]' AND NASIPAddress = '192.168.0.6''
MASTER: exit on signal (2)
Best regards!
Sunny Xu
-
List info/subscribe/unsubscribe? See http://www.freeradius.org/list/users.html
