BiTel

Packet type: Access-Request
Identifier: 86
Authenticator: {48 C4 6E 53 F1 9C 72 69 E7 2E C4 47 3D 45 3B 12}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  User-Password=���\u27(�-\u19���]\u19�$
  NAS-Port-Id=701
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697766
  Service-Type=1
  Framed-IP-Address=10.5.72.120
  Acct-Session-Id=80f0df9a
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  NAS-Port-Type=15
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120
  WISPr-Logoff-URL=http://10.5.76.1/logout

Packet type: Access-Reject
Identifier: 86
Authenticator: {2C 5C E5 03 50 EE 20 95 43 B5 27 75 3C 52 12 CB}
Attributes:
  Reply-Message=30

Process time auth: 0

Time: 10.09.2013 13:47:20
Packet type: Access-Request
Identifier: 174
Authenticator: {DB F4 CE 00 75 54 B4 23 05 EB 0B 11 DB AD 11 5E}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  User-Password=�v�������Q��X%�
  NAS-Port-Id=701
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697765
  Service-Type=1
  Framed-IP-Address=10.5.72.120
  Acct-Session-Id=80f0df9b
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  NAS-Port-Type=15
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120
  WISPr-Logoff-URL=http://10.5.76.1/logout

Time: 10.09.2013 13:47:20
Packet type: Access-Accept
Identifier: 174
Authenticator: {87 32 A8 4C E4 14 FB 1F 1F A0 C8 EA A3 A6 BE 4A}
Attributes:
  Mikrotik-Rate-Limit=4096k/4096k

Process time auth: 0


Time: 10.09.2013 13:47:20
Packet type: Accounting-Request
Identifier: 175
Authenticator: {AD A1 96 AD AD 5B 02 E1 27 9A 13 12 B8 A0 3E 25}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697765
  Framed-IP-Address=10.5.72.120
  Acct-Status-Type=1
  Acct-Delay-Time=0
  Acct-Session-Id=80f0df9b
  NAS-Port-Id=701
  Event-Timestamp=1378784840
  NAS-Port-Type=15
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120


Time: 10.09.2013 13:47:32
Packet type: Disconnect-Request
Identifier: 2
Authenticator: {20 46 3E D9 A6 BD 42 3C 39 72 E8 CF FB AC 97 3F}
Attributes:
  User-Name=444444
  Framed-IP-Address=10.5.72.120

Time: 10.09.2013 13:47:32
Packet type: Disconnect-ACK
Identifier: 2
Authenticator: {33 34 94 46 66 B4 B5 A0 18 39 FA C4 CF 08 25 0E}
Attributes:
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120


Time: 10.09.2013 13:47:32
Packet type: Accounting-Request
Identifier: 9
Authenticator: {B3 A2 1A EE 77 E0 0C 88 67 01 AC 3E 28 EC 86 0E}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697765
  Framed-IP-Address=10.5.72.120
  Acct-Input-Octets=15678
  Acct-Output-Octets=93310
  Acct-Status-Type=2
  Acct-Delay-Time=0
  Acct-Session-Time=11
  Acct-Input-Packets=136
  Acct-Session-Id=80f0df9b
  Acct-Terminate-Cause=6
  NAS-Port-Id=701
  Acct-Output-Packets=133
  Event-Timestamp=1378784852
  Acct-Output-Gigawords=0
  Acct-Input-Gigawords=0
  NAS-Port-Type=15
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120

Time: 10.09.2013 13:53:08
Packet type: Access-Request
Identifier: 218
Authenticator: {29 E0 1D 20 7E B1 8D 1D 12 E6 DB 77 9B C0 35 10}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  User-Password=\u17K �(s�$C�\u25[ȳ�
  NAS-Port-Id=701
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697757
  Service-Type=1
  Framed-IP-Address=10.5.72.120
  Acct-Session-Id=80f0dfa3
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  NAS-Port-Type=15
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120
  WISPr-Logoff-URL=http://10.5.76.1/logout

Time: 10.09.2013 13:53:08
Packet type: Access-Accept
Identifier: 218
Authenticator: {B8 02 CF D6 9F C3 26 13 43 40 99 9C EC DB 0E BB}
Attributes:
  Mikrotik-Rate-Limit=4096k/4096k

Process time auth: 0


Time: 10.09.2013 13:53:08
Packet type: Accounting-Request
Identifier: 219
Authenticator: {E5 15 DA 09 D5 71 44 17 82 89 28 4E C3 A9 E3 FD}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697757
  Framed-IP-Address=10.5.72.120
  Acct-Status-Type=1
  Acct-Delay-Time=0
  Acct-Session-Id=80f0dfa3
  NAS-Port-Id=701
  Event-Timestamp=1378785188
  NAS-Port-Type=15
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120


Time: 10.09.2013 13:53:22
Packet type: Disconnect-Request
Identifier: 3
Authenticator: {30 E9 E3 0D 87 4A DE A3 B5 99 40 F7 33 F7 AD 4F}
Attributes:
  User-Name=444444
  Framed-IP-Address=10.5.72.120

Time: 10.09.2013 13:53:22
Packet type: Disconnect-ACK
Identifier: 3
Authenticator: {3B C3 23 35 12 51 27 21 24 E9 49 4A 53 D0 FE DB}
Attributes:
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120


Time: 10.09.2013 13:53:22
Packet type: Accounting-Request
Identifier: 47
Authenticator: {5B A2 F6 23 ED AB A7 FB B0 DB 8D D5 CC 6B AA 3B}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697757
  Framed-IP-Address=10.5.72.120
  Acct-Input-Octets=20609
  Acct-Output-Octets=128720
  Acct-Status-Type=2
  Acct-Delay-Time=0
  Acct-Session-Time=14
  Acct-Input-Packets=204
  Acct-Session-Id=80f0dfa3
  Acct-Terminate-Cause=6
  NAS-Port-Id=701
  Acct-Output-Packets=190
  Event-Timestamp=1378785202
  Acct-Output-Gigawords=0
  Acct-Input-Gigawords=0
  NAS-Port-Type=15
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120

radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 86
Authenticator: {48 C4 6E 53 F1 9C 72 69 E7 2E C4 47 3D 45 3B 12}
Attributes:
  User-Name=444444
  NAS-Identifier=MikroTik
  User-Password=4444444444
  NAS-Port-Id=701
  NAS-IP-Address=10.10.1.120
  NAS-Port=-2131697766
  Service-Type=1
  Framed-IP-Address=10.5.72.120
  Acct-Session-Id=80f0df9a
  Calling-Station-Id=CC:08:E0:2B:9C:3E
  NAS-Port-Type=15
  Called-Station-Id=hs701
  Mikrotik-Host-IP=10.5.72.120
  WISPr-Logoff-URL=http://10.5.76.1/logout

connection 09-10/13:47:06  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaServModifyEvent] moduleId: 2; pluginId: no; cid: global; scid: -1; userId: -1; oldInetServ: null; newInetServ: LOGIN: 444444; newInetServState: 1; newInetServOptionList: ; timestamp: 1378784826271
connection 09-10/13:47:06  INFO [sa-p-12-t-1] InetServRuntimeMap - Adding new InetServRuntime: 954
connection 09-10/13:47:06  INFO [sa-p-12-t-1] InetServRuntimeMap - Loading inetServ from database.
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetNas - Search by username=444444
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - [username=444444] InetServ not found. Try find in cards...
connection 09-10/13:47:06  INFO [sa-p-12-t-1] InetServRuntimeMap - Adding InetServRuntime: 954
connection 09-10/13:47:06  INFO [sa-p-12-t-1] InetServRuntimeMap - ContractId: 925; servId: 954
        LOGIN: 444444
        Options [] TariffModuleTreeSet [6:10.09.2013-08.12.2013; ]
        Device state: -1; optionSet:
connection 09-10/13:47:06  WARN [sa-p-12-t-1] InetApplication - Accounting period not found for inetServ:954
connection 09-10/13:47:06  INFO [sa-p-12-t-1] InetApplication - OptionSet: []
connection 09-10/13:47:06  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=1; inetServId: 954; call: true; oldState: -1; newState: 1; oldOptionSet: ; newOptionSet:
connection 09-10/13:47:06  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=1; inetServId: 954; call: true; oldState: -1; newState: 1; oldOptionSet: ; newOptionSet:
connection 09-10/13:47:06  INFO [sa-p-12-t-1] ServiceActivatorSet - Invoking serviceCreate
connection 09-10/13:47:06  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Process event type[1] result=true
connection 09-10/13:47:06  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Changing InetServ:954 state and/or options
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - Card accessCode: 30
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - Return code=30
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Reject
Identifier: 86
Authenticator: {}
Attributes:
  Reply-Message=30

Process time auth: 8

mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 2; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 2; inetServId: 954; state: 1; optionSet: ; timestamp: 1378784826476
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-2] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Reject
Identifier: 86
Authenticator: {2C 5C E5 03 50 EE 20 95 43 B5 27 75 3C 52 12 CB}
Attributes:
  Reply-Message=30

Process time auth: 11

mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] ContractRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusModifiedEvent] moduleId: 0; pluginId: no; cid: 925; scid: -1; userId: 0; timestamp: 1378784826254
mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusModifiedEvent] moduleId: 0; pluginId: no; cid: 925; scid: -1; userId: 0; timestamp: 1378784826254
mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.api.server.event.InetServModifiedEvent] moduleId: 2; pluginId: no; cid: 925; scid: -1; userId: 0; timestamp: 1378784826271
mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Reload InetServRuntime: 954
mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Removing InetServRuntime: 954
mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Adding InetServRuntime: 954
mq 09-10/13:47:06  INFO [event-proc-p-2-t-1] InetServRuntimeMap - ContractId: 925; servId: 954
        LOGIN: 444444
        Options [] TariffModuleTreeSet [6:10.09.2013-08.12.2013; ]
        Device state: 1; optionSet:
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetRadiusProcessor - [username=444444] Authenticated as inetServId:954
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetApplication - inetServ[id=954] balance ok: 50.00 [0]
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetApplication - TariffOptionMap: {}
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetApplication - OptionSet: [6]
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetRadiusProcessor - Return code=0
radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 86
Authenticator: {}
Attributes:
  Mikrotik-Rate-Limit=4096k/4096k

Process time auth: 331

radius 09-10/13:47:06  INFO [rdsLstnr-p-8-t-10] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 86
Authenticator: {2E 1F 6C 35 46 92 4D 9A 98 F6 C6 57 5D 6A 48 F8}
Attributes:
  Mikrotik-Rate-Limit=4096k/4096k

Process time auth: 332

connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorSet - Connecting to device
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEvent] moduleId: 2; pluginId: no; cid: 925; scid: -1; userId: 0; deviceId: 2; inetServId: 954; connectionId: 120158; state: 0; accessCode: 10; timestamp: 1378784852464
connection 09-10/13:47:32  INFO [sa-p-12-t-1] InetApplication - TariffOptionMap: {}
connection 09-10/13:47:32  INFO [sa-p-12-t-1] InetApplication - OptionSet: [6]
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 954; call: true; oldState: 1; newState: 0; oldOptionSet: 6; newOptionSet: 6
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=2; inetServId: 954; call: true; oldState: 1; newState: 0; oldOptionSet: 6; newOptionSet: 6
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorSet - Invoking connectionModify
connection 09-10/13:47:32  INFO [sa-p-12-t-1] CoAServiceActivator - Connection modify: oldState: 1; newState: 0; oldOptionSet: [6]; newOptionSet: [6]
connection 09-10/13:47:32  INFO [sa-p-12-t-1] CoAServiceActivator - Connection close
connection 09-10/13:47:32  INFO [sa-p-12-t-1] CoAServiceActivator - Send PoD:
Packet type: Disconnect-Request
Identifier: 2
Authenticator: {F4 9A 30 13 09 15 57 E4 23 7A B8 26 FC 62 16 CC}
Attributes:
  User-Name=444444
  Framed-IP-Address=10.5.72.120

connection 09-10/13:47:32  INFO [sa-p-12-t-1] RadiusClient - Sending to /10.10.1.120:1700
Packet type: Disconnect-Request
Identifier: 2
Authenticator: {F4 9A 30 13 09 15 57 E4 23 7A B8 26 FC 62 16 CC}
Attributes:
  User-Name=444444
  Framed-IP-Address=10.5.72.120

connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Process event type[2] result=true
connection 09-10/13:47:32  INFO [rds-clnt-/10.10.1.120-1700] RadiusClient - Recieved from /10.10.1.120:1700
Packet type: Disconnect-ACK
Identifier: 2
Authenticator: {33 34 94 46 66 B4 B5 A0 18 39 FA C4 CF 08 25 0E}
Attributes:
  NAS-Identifier=MikroTik
  NAS-IP-Address=10.10.1.120

connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 2; pluginId: no; cid: 925; scid: -1; userId: 0; type: 2; deviceId: 2; connectionId: 120158; timestamp: 1378784852471
connection 09-10/13:47:32  INFO [sa-p-12-t-1] InetApplication - TariffOptionMap: {}
connection 09-10/13:47:32  INFO [sa-p-12-t-1] InetApplication - OptionSet: [6]
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=5; inetServId: 954; call: true; oldState: 1; newState: 1; oldOptionSet: 6; newOptionSet: 6
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=5; inetServId: 954; call: true; oldState: 1; newState: 1; oldOptionSet: 6; newOptionSet: 6
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorSet - Invoking onAccountingStop
connection 09-10/13:47:32  INFO [sa-p-12-t-1] ServiceActivatorDeviceWorker - Process event type[5] result=true
mq 09-10/13:47:32  INFO [event-proc-p-2-t-1] InetConnectionManager - Remove connection: InetConnection [id=120158-0, iface=2:-2131697765, sessId=80f0df9b, start=10.09.2013 13:47:20, uname=444444, addr=10.5.72.120]
radius 09-10/13:47:36  INFO [rdsLstnr-p-8-t-10] RadiusListenerWorker - REQUEST:

radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] InetNas - Search by username=444444
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] InetRadiusProcessor - Creating NasConnection from radius accounting packet
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] InetRadiusProcessor - Parent: null
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] InetApplication - TariffOptionMap: {}
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] InetApplication - OptionSet: [6]
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] Accounting - Write new connection to DB
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] Accounting - New connection id=121209
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] Accounting - Write new session to DB
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] Accounting - New session's id=156331
radius 09-11/09:36:35  INFO [rdsLstnr-p-7-t-4] connection - 121209:156331 Register ip: 10.5.72.199

accounting 09-11/09:36:39  INFO [accwrkr-1-p-13-t-1] connection - 121209:156331 inetServ[id=954] not active.
accounting 09-11/09:36:39  INFO [accwrkr-1-p-13-t-1] connection - 121209:156331 Found error 10
accounting 09-11/09:36:39  INFO [accwrkr-1-p-13-t-1] connection - 121209:156331 Sending event to stop session...