09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] RadiusProcessor - Create new radius session.
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetNas - Search by username=1
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - [username=1] Authenticated as inetServId:5
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] InetRadiusProcessor - MAC-address from request:
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] InetAccountingPeriodList - Create:
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] InetAccountingPeriodList - AccountingPeriod: 3: 11.09.2012 00:00:00.0 - 30.09.2012 23:59:59.999
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetApplication - inetServ[id=5] balance ok: 100.00 [0]
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetApplication - TariffOptionMap: {}
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] InetNas - agentRemoteId=null
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - Set ip from pool
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - Write new waiting connection to DB
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - New connection id=53
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - Return code=0
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 145
Authenticator: {}
Attributes:
  Acct-Interim-Interval=60
  Service-Type=2
  Framed-Protocol=1
  Framed-IP-Address=172.21.31.202
  Dynamic-QoS-Param=police-class-rate INET rate-absolute 64
  Dynamic-QoS-Param=police-class-burst INET 125000
  Dynamic-QoS-Param=meter-class-rate INET rate-absolute 64
  Dynamic-QoS-Param=meter-class-burst INET 125000

Process time auth: 8

09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] HourlyDataLogger - Create log file...
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] HourlyLogFileUtils - Next log file: /usr/local/BGInetAccess/data/radius/source_2/2012/2012-09/2012-09-12/log_2012-09-12-23.000.bgdl
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] HourlyDataLogEntry - Create dataLog file: /usr/local/BGInetAccess/data/radius/source_2/2012/2012-09/2012-09-12/log_2012-09-12-23.000.bgdl
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] BGDataLog - DataLog file [data/radius/source_2/2012/2012-09/2012-09-12/log_2012-09-12-23.000.bgdl] was opened
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] DataLog - Writing TLV finished(3)=[0]
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] DataLog - Writing TLV buffer(4)=[type=chunked, size=524288]
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] DataLog - Writing TLV compression(5)=[type=none, level=1, strategy=1]
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] DataLog - Writing TLV distributed(6)=[1]
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] DataLog - Writing TLV streaming(7)=[1]
09-12/23:11:03 DEBUG [rdsLstnr-p-7-t-2] BGDataLog - Header length=51
09-12/23:11:03  INFO [rdsLstnr-p-7-t-2] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 145
Authenticator: {E6 06 31 44 48 28 C2 81 D0 63 6E F6 25 63 63 35}
Attributes:
  Acct-Interim-Interval=60
  Service-Type=2
  Framed-Protocol=1
  Dynamic-QoS-Param=police-class-rate INET rate-absolute 64
  Dynamic-QoS-Param=police-class-burst INET 125000
  Dynamic-QoS-Param=meter-class-rate INET rate-absolute 64
  Dynamic-QoS-Param=meter-class-burst INET 125000

Process time auth: 15