Используем СоА, sa.radius.log=1 включен. Стоп пакета не увидел,в 22:21:44 пришел Accounting-Request, тут же сессия разорвалась, 22:21:56 создается сессия,в 22:21:57 поднялась сессия
Accounting radius.log
Код:
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
закрывается сессия connection.log
Код:
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
Access radius.log
Код:
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
создана сессия connection.log
Код:
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