rad_recv: Accounting-Request packet from host xx.xx.xx.xx:32785, id=99, length=54
        Acct-Status-Type = Start
        User-Name = "test"
        Acct-Session-Id = "00000048"
        Framed-IP-Address = pp.pp.pp.pp
        NAS-IP-Address = 0.0.0.0
rad_lowerpair:  User-Name now 'test'
  Processing the preacct section of radiusd.conf
modcall: entering group preacct for request 1
  modcall[preacct]: module "preprocess" returns noop for request 1
rlm_acct_unique: WARNING: Attribute NAS-Port was not found in request, unique ID MAY be inconsistent
rlm_acct_unique: Hashing ',Client-IP-Address = xx.xx.xx.xx,NAS-IP-Address = 0.0.0.0,Acct-Session-Id = "00000048",User-Name = "test"'
rlm_acct_unique: Acct-Unique-Session-ID = "14d411f29e86efa5".
  modcall[preacct]: module "acct_unique" returns ok for request 1
    rlm_realm: No '@' in User-Name = "test", looking up realm NULL
    rlm_realm: No such realm "NULL"
  modcall[preacct]: module "suffix" returns noop for request 1
  modcall[preacct]: module "files" returns noop for request 1
modcall: leaving group preacct (returns ok) for request 1
  Processing the accounting section of radiusd.conf
modcall: entering group accounting for request 1
radius_xlat:  '/var/log/radius/radacct/82.137.216.159/detail-20070626'
rlm_detail: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /var/log/radius/radacct/82.137.216.159/detail-20070626
  modcall[accounting]: module "detail" returns ok for request 1
  modcall[accounting]: module "unix" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "users_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "adsl_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "public_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "nofilter_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "nonsub_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "upload_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "zip_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "kulilk_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "souriaheart_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "spacetoon_pool" returns noop for request 1
rlm_ippool: This is not an Accounting-Stop. Return NOOP.
  modcall[accounting]: module "guest_pool" returns noop for request 1
radius_xlat:  'test'
rlm_sql (sql): sql_set_user escaped user --> 'test'
radius_xlat:  'INSERT into radacct ??(AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctAuthentic, ??ConnectInfo_start, CalledStationId, CallingStationId, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay) ??values('00000048', '14d411f29e86efa5', 'test', '', '0.0.0.0', ??'', '', (now() - '0'::interval), '', '', ??'', '', '', '', ??NULLIF('pp.pp.pp.pp', '')::inet, '0')'
radius_xlat:  '/var/log/radius/sqltrace.sql'
rlm_sql (sql): Reserving sql socket id: 32
rlm_sql_postgresql: query: INSERT into radacct ??(AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctAuthentic, ??ConnectInfo_start, CalledStationId, CallingStationId, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay) ??values('00000048', '14d411f29e86efa5', 'test', '', '0.0.0.0', ??'', '', (now() - '0'::interval), '', '', ??'', '', '', '', ??NULLIF('pp.pp.pp.pp', '')::inet, '0')
rlm_sql_postgresql: Status: PGRES_FATAL_ERROR
rlm_sql_postgresql: affected rows =
rlm_sql_postgresql: Postgresql check_error: PGRES_FATAL_ERROR, returning SQL_DOWN
rlm_sql (sql): Attempting to connect rlm_sql_postgresql #32
rlm_sql (sql): Connected new DB handle, #32
rlm_sql_postgresql: query: INSERT into radacct ??(AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctAuthentic, ??ConnectInfo_start, CalledStationId, CallingStationId, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay) ??values('00000048', '14d411f29e86efa5', 'test', '', '0.0.0.0', ??'', '', (now() - '0'::interval), '', '', ??'', '', '', '', ??NULLIF('pp.pp.pp.pp', '')::inet, '0')
rlm_sql_postgresql: Status: PGRES_FATAL_ERROR
rlm_sql_postgresql: affected rows =
rlm_sql_postgresql: Postgresql check_error: PGRES_FATAL_ERROR, returning SQL_DOWN
rlm_sql (sql): failed after re-connect
rlm_sql (sql): Couldn't insert SQL accounting START record - ERROR:  invalid input syntax for integer: ""
radius_xlat:  'UPDATE radacct ??SET AcctStartTime = (now() - '0'::interval), AcctStartDelay = '0', ??ConnectInfo_start = '' WHERE AcctSessionId = '00000048' AND UserName = 'test' ??AND NASIPAddress = '0.0.0.0' AND AcctStopTime IS NULL'
radius_xlat:  '/var/log/radius/sqltrace.sql'
rlm_sql_postgresql: query: UPDATE radacct ??SET AcctStartTime = (now() - '0'::interval), AcctStartDelay = '0', ??ConnectInfo_start = '' WHERE AcctSessionId = '00000048' AND UserName = 'test' ??AND NASIPAddress = '0.0.0.0' AND AcctStopTime IS NULL
rlm_sql_postgresql: Status: PGRES_COMMAND_OK
rlm_sql_postgresql: affected rows = 0
rlm_sql (sql): Released sql socket id: 32
  modcall[accounting]: module "sql" returns ok for request 1
modcall: leaving group accounting (returns ok) for request 1
Sending Accounting-Response of id 99 to xx.xx.xx.xx port 32785
Finished request 1
Going to the next request
Cleaning up request 1 ID 99 with timestamp 4680e80e
Waking up in 6 seconds...
--- Walking the entire request list ---
Cleaning up request 0 ID 159 with timestamp 4680e80e
Nothing to do.  Sleeping until we see a request.