BiTel

 Acct-Authentic=1
  NAS-Port-Id=9/112/4/0
  Acct-Terminate-Cause=1
  Acct-Output-Packets=4
  NAS-Port-Type=5
  cisco-avpair=ppp-disconnect-cause=Lower Layer disconnected
  cisco-avpair=connect-progress=LAN Ses Up
  cisco-avpair=nas-tx-speed=100000000
  cisco-avpair=nas-rx-speed=100000000
  cisco-avpair=disc-cause-ext=Call Disconnect
  cisco-avpair=client-mac-address=000b.2b47.291c
  cisco-NAS-Port=9/112/4/0

: failed to remove session from switching hash table.
*Mar 20 10:14:00.599: %IP_VFR-7-FEATURE_DISABLE_IN: VFR(in) is manually disabled through CLI; VFR support for features that have internally enabled, will be made available only when VFR is enabled manually on interface Virtual-Access25

*Mar 20 10:14:03.011: [726]PPPoE 4960: Error adjusting nas port format did
*Mar 20 10:14:03.163: [478]PPPoE 4961: Error adjusting nas port format did
*Mar 20 10:14:04.315: %LINK-3-UPDOWN: Interface Virtual-Access45, changed state to

03-20/22:21:44  INFO [rdsLstnr-p-9-t-8] RadiusListenerWorker - REQUEST:
Packet type: Accounting-Request
Identifier: 137
Authenticator: {F3 FE 7C 7A FC 50 82 E5 08 5E EE 0D 90 8B 2C 58}
Attributes:
  User-Name=tralala4569
  NAS-Identifier=c1800-gw
  NAS-IP-Address=3.6.7.1
  NAS-Port=-1811939328
  Service-Type=2
  Framed-Protocol=1
  Framed-IP-Address=10.100.1.105
  Acct-Input-Octets=857
  Acct-Output-Octets=814
  Acct-Status-Type=2
  Acct-Delay-Time=0
  Acct-Session-Time=77
  Acct-Input-Packets=81
  Acct-Session-Id=9/112/4/0_000498DD
  Acct-Authentic=1
  NAS-Port-Id=9/112/4/0
  Acct-Terminate-Cause=1
  Acct-Output-Packets=80
  NAS-Port-Type=5
  cisco-avpair=ppp-disconnect-cause=Received LCP TERMREQ from peer
  cisco-avpair=connect-progress=LAN Ses Up
  cisco-avpair=nas-tx-speed=100000000
  cisco-avpair=nas-rx-speed=100000000
  cisco-avpair=disc-cause-ext=PPP Receive Term
  cisco-avpair=client-mac-address=f07d.6815.3b7f
  cisco-NAS-Port=9/112/4/0

03-20/22:21:44  INFO [rdsLstnr-p-9-t-8] InetRadiusProcessor - Session 9/112/4/0_000498DD found.
03-20/22:21:44  INFO [rdsLstnr-p-9-t-8] InetNas - Stopping NasConnection
03-20/22:21:44  INFO [rdsLstnr-p-9-t-8] connection - 14926:29463 Stopping NasConnection
03-20/22:21:44  INFO [rdsLstnr-p-9-t-8] InetConnectionRuntime - Stopping connection with id=14926
03-20/22:21:44 DEBUG [rdsLstnr-p-9-t-8] ProcessorRequest - Sending to /3.6.7.1:1646
03-20/22:21:44  INFO [rdsLstnr-p-9-t-8] RadiusListenerWorker - RESPONSE:
Packet type: Accounting-Response
Identifier: 137
Authenticator: {7B 6F C9 92 46 87 1D 07 40 99 55 30 65 22 4A 9A}
Attributes:
Process time stop: 72

03-20/22:21:44  INFO [sa-p-11-t-2] ServiceActivatorSet - Connecting to device
03-20/22:21:44 DEBUG [sa-p-11-t-2] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 15; pluginId: no; cid: 2707; scid: -1; userId: 0; type: 2; deviceId: 4; connectionId: 14926; timestamp: 1395325304239
03-20/22:21:44  INFO [sa-p-11-t-2] ServiceActivatorDeviceWorker - Do task deviceId: 4; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 15; pluginId: no; cid: 2707; scid: -1; userId: 0; type: 2; deviceId: 4; connectionId: 14926; timestamp: 1395325304239
03-20/22:21:44  INFO [sa-p-11-t-2] InetApplication - TariffOptionMap: {}
03-20/22:21:44 DEBUG [sa-p-11-t-2] TrafficRangeManager - Add to RangeKey[519691044628:2:131989506] 0 (1004331729, 1048576000)
03-20/22:21:44  INFO [sa-p-11-t-2] InetApplication - OptionSet: [5, 13]
03-20/22:21:44  INFO [sa-p-11-t-2] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=5; inetServId: 357; call: true; oldState: 1; newState: 1; oldOptionSet: 5,13; newOptionSet: 5,13
03-20/22:21:44  INFO [sa-p-11-t-2] ServiceActivatorDeviceWorker - Processing deviceId:4; command ServiceActivatorEvent type=5; inetServId: 357; call: true; oldState: 1; newState: 1; oldOptionSet: 5,13; newOptionSet: 5,13
03-20/22:21:44  INFO [sa-p-11-t-2] ServiceActivatorSet - Invoking onAccountingStop
03-20/22:21:44  INFO [sa-p-11-t-2] ServiceActivatorDeviceWorker - Process event type[5] result=true
03-20/22:21:49  INFO [sa-p-11-t-2] ServiceActivatorSet - Disconnecting from device

03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 230
Authenticator: {E7 DB 6A 4D 31 29 EE 10 D7 F9 CF CC 63 4E 08 C7}
Attributes:
  User-Name=tralala4569
  NAS-Identifier=c1800-gw
  NAS-Port-Id=9/112/4/0
  CHAP-Password={11 73 62 53 4D E8 09 60 07 A0 7E 8A CC 07 5A 6D 09}
  NAS-IP-Address=3.6.7.1
  NAS-Port=-1811939328
  Service-Type=2
  Framed-Protocol=1
  Acct-Session-Id=9/112/4/0_00049E7E
  NAS-Port-Type=5
  cisco-avpair=client-mac-address=f07d.6815.3b7f
  cisco-NAS-Port=9/112/4/0

03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] RadiusProcessor - Create new radius session.
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetNas - Search by username=tralala4569
03-20/22:21:56 TRACE [rdsLstnr-p-8-t-19] RadiusUtils - try checkDigestMD5Password
03-20/22:21:56 TRACE [rdsLstnr-p-8-t-19] RadiusUtils - digestAttributes=null
03-20/22:21:56 TRACE [rdsLstnr-p-8-t-19] RadiusUtils - digestResponse=null
03-20/22:21:56 TRACE [rdsLstnr-p-8-t-19] RadiusUtils - user =null realm =null nonce = null method = null uri = null
03-20/22:21:56 TRACE [rdsLstnr-p-8-t-19] RadiusUtils - try all in one string
03-20/22:21:56 TRACE [rdsLstnr-p-8-t-19] RadiusUtils - digestAttribute=null
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetRadiusProcessor - [username=tralala4569] Authenticated as inetServId:357
03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] InetNas - agentRemoteId=null
03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] InetRadiusProcessor - Identifier from request: null
03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] InetRadiusProcessor - MAC-address from request:
03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] InetAccountingPeriodList - Create:
 AccountingPeriod: 516: 07.03.2014 00:00:00.0 - 31.03.2014 23:59:59.999
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetApplication - inetServ[id=357] balance ok: -49380.53 [-6000000.00]
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetApplication - TariffOptionMap: {}
03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] TrafficRangeManager - Add to RangeKey[519691044628:2:131989506] 0 (1004332543, 1048576000)
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetApplication - OptionSet: [5, 13]
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetRadiusProcessor - Set ip from serv
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetRadiusProcessor - Write new waiting connection to DB
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetRadiusProcessor - New connection id=14927
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetRadiusProcessor - Return code=0
03-20/22:21:56 DEBUG [rdsLstnr-p-8-t-19] ProcessorRequest - Sending to /3.6.7.1:1645
03-20/22:21:56  INFO [rdsLstnr-p-8-t-19] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 230
Authenticator: {C8 D3 CE F1 E7 9A E9 90 32 95 74 44 06 BE 71 CE}
Attributes:
  Framed-IP-Address=10.100.1.105
  cisco-avpair=lcp:interface-config=rate-limit input 512000 96000 192000 conform-action transmit exceed-action drop
  cisco-avpair=lcp:interface-config=rate-limit output 512000 96000 192000 conform-action transmit exceed-action drop

03-20/22:21:57  INFO [sa-p-11-t-72] ServiceActivatorSet - Connecting to device
03-20/22:21:57 DEBUG [sa-p-11-t-72] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 15; pluginId: no; cid: 2707; scid: -1; userId: 0; type: 1; deviceId: 4; connectionId: 14927; timestamp: 1395325317623
03-20/22:21:57  INFO [sa-p-11-t-72] ServiceActivatorDeviceWorker - Do task deviceId: 4; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 15; pluginId: no; cid: 2707; scid: -1; userId: 0; type: 1; deviceId: 4; connectionId: 14927; timestamp: 1395325317623
03-20/22:21:57  INFO [sa-p-11-t-72] InetApplication - TariffOptionMap: {}
03-20/22:21:57 DEBUG [sa-p-11-t-72] TrafficRangeManager - Add to RangeKey[519691044628:2:131989506] 0 (1004332543, 1048576000)
03-20/22:21:57  INFO [sa-p-11-t-72] InetApplication - OptionSet: [5, 13]
03-20/22:21:57  INFO [sa-p-11-t-72] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=4; inetServId: 357; call: true; oldState: 1; newState: 1; oldOptionSet: 5,13; newOptionSet: 5,13
03-20/22:21:57  INFO [sa-p-11-t-72] ServiceActivatorDeviceWorker - Processing deviceId:4; command ServiceActivatorEvent type=4; inetServId: 357; call: true; oldState: 1; newState: 1; oldOptionSet: 5,13; newOptionSet: 5,13
03-20/22:21:57  INFO [sa-p-11-t-72] ServiceActivatorSet - Invoking onAccountingStart
03-20/22:21:57  INFO [sa-p-11-t-72] ServiceActivatorDeviceWorker - Process event type[4] result=true
03-20/22:22:02  INFO [sa-p-11-t-72] ServiceActivatorSet - Disconnecting from device