[BGInetAccess] Ошибка работы обработчика активации сервисов [57] C7200-IPN: Pandora-IPN

ID события: inet.sa.error.count
Время регистрации события: 08.04.2018 11:51:05
Хост сервера: bill.garantia.tv/127.0.0.1

Error on device:57 - java.util.concurrent.TimeoutException
ru.bitel.bgbilling.common.BGException: Error on device:57 - java.util.concurrent.TimeoutException
   at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doTask(ServiceActivatorDeviceWorker.java:504)
   at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.internalDoTaskImpl(AsyncEventWorker.java:211)
   at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.internalDoTask(AsyncEventWorker.java:183)
   at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.doTasks(AsyncEventWorker.java:109)
   at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.runWorker(ServiceActivatorDeviceWorker.java:246)
   at ru.bitel.bgbilling.kernel.event.EventWorker.internalRunWorker(EventWorker.java:139)
   at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.runImpl(ServiceActivatorDeviceWorker.java:152)
   at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86)
   at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
   at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
   at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
   at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   at java.lang.Thread.run(Thread.java:748)
   at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:46)
Caused by: java.util.concurrent.TimeoutException
   at bitel.billing.server.util.terminal.TerminalManager.readFromInput(TerminalManager.java:74)
   at bitel.billing.server.util.terminal.TerminalManager.doCommand(TerminalManager.java:39)
   at bitel.billing.server.util.terminal.AbstractTerminalSession.doCommand(AbstractTerminalSession.java:107)
   at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.TelnetServiceActivator.executeCommand(TelnetServiceActivator.java:174)
   at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.AbstractTerminalServiceActivator.executeCommand0(AbstractTerminalServiceActivator.java:1180)
   at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.AbstractTerminalServiceActivator.executeCommands(AbstractTerminalServiceActivator.java:1162)
   at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.AbstractTerminalServiceActivator.serviceCreate(AbstractTerminalServiceActivator.java:1225)
   at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorSet.serviceCreate(ServiceActivatorSet.java:386)
   at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doCommand(ServiceActivatorDeviceWorker.java:564)
   at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doTask(ServiceActivatorDeviceWorker.java:500)
   ... 15 more

connection 04-08/11:48:03 ERROR [sa-p-14-t-57] EventWorker - Error on device:57 - java.util.concurrent.TimeoutException
ru.bitel.bgbilling.common.BGException: Error on device:57 - java.util.concurrent.TimeoutException
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doTask(ServiceActivatorDeviceWorker.java:504)
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.internalDoTaskImpl(AsyncEventWorker.java:211)
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.internalDoTask(AsyncEventWorker.java:183)
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.doTasks(AsyncEventWorker.java:109)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.runWorker(ServiceActivatorDeviceWorker.java:246)
        at ru.bitel.bgbilling.kernel.event.EventWorker.internalRunWorker(EventWorker.java:139)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.runImpl(ServiceActivatorDeviceWorker.java:152)
        at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
        at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:46)
Caused by: java.util.concurrent.TimeoutException
        at bitel.billing.server.util.terminal.TerminalManager.readFromInput(TerminalManager.java:74)
        at bitel.billing.server.util.terminal.TerminalManager.doCommand(TerminalManager.java:39)
        at bitel.billing.server.util.terminal.AbstractTerminalSession.doCommand(AbstractTerminalSession.java:107)
        at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.TelnetServiceActivator.executeCommand(TelnetServiceActivator.java:174)
        at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.AbstractTerminalServiceActivator.executeCommand0(AbstractTerminalServiceActivator.java:1180)
        at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.AbstractTerminalServiceActivator.executeCommands(AbstractTerminalServiceActivator.java:1162)
        at ru.bitel.bgbilling.modules.inet.dyn.device.terminal.AbstractTerminalServiceActivator.serviceCreate(AbstractTerminalServiceActivator.java:1225)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorSet.serviceCreate(ServiceActivatorSet.java:386)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doCommand(ServiceActivatorDeviceWorker.java:564)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doTask(ServiceActivatorDeviceWorker.java:500)
        ... 15 more
connection 04-08/11:48:03  INFO [sa-p-14-t-57] ServiceActivatorSet - Disconnecting from device
connection 04-08/11:48:03  INFO [sa-p-14-t-57] TelnetServiceActivator - execute: exit
radius 04-08/11:48:04  INFO [rdsLstnr-p-9-t-7] RadiusListenerWorker - REQUEST:
Packet type: Access-Request

/usr/local/inet/BGInetAccess/access.sh status
Java Runtime: Oracle Corporation Java HotSpot(TM) 64-Bit Server VM [1.8.0_161] /opt/java/jdk1.8.0_161/jre
  Runtime name: 11416@bill.garantia.tv
  Java endorsed dirs: /lib/endorsed:/opt/java/jdk/lib/endorsed
  OS: Linux 4.0.8-300.fc22.x86_64 [amd64], file.encoding: UTF-8, user.name: root
  Heap sizes: current=741888k  free=734146k  max=10990080k
Kernel version 6.2.1210 / 29.03.2018 17:39:36
Inet version 6.2.742 / 29.03.2018 17:39:45

IP pool [radius.realm.default.ipCategories] ipCategoryIds: [35], size: 6858, occupied: 5648, reserved: 66, localRsrvd: 9, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [99], size: 1016, occupied: 0, reserved: 0, localRsrvd: 0, fetchSize: 50
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [38], size: 7112, occupied: 5296, reserved: 70, localRsrvd: 33, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [40], size: 1016, occupied: 662, reserved: 32, localRsrvd: 20, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [43], size: 1142, occupied: 686, reserved: 29, localRsrvd: 28, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [45], size: 1142, occupied: 691, reserved: 36, localRsrvd: 27, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [46], size: 1016, occupied: 709, reserved: 20, localRsrvd: 12, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [48], size: 1016, occupied: 588, reserved: 29, localRsrvd: 22, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [50], size: 1016, occupied: 460, reserved: 59, localRsrvd: 34, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [53], size: 1270, occupied: 498, reserved: 57, localRsrvd: 34, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [55], size: 1016, occupied: 548, reserved: 20, localRsrvd: 15, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [57], size: 1016, occupied: 333, reserved: 20, localRsrvd: 15, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [60], size: 762, occupied: 469, reserved: 44, localRsrvd: 40, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [62], size: 762, occupied: 487, reserved: 19, localRsrvd: 10, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [64], size: 1270, occupied: 695, reserved: 27, localRsrvd: 25, fetchSize: 20
IP pool [radius.disable.ipCategories] ipCategoryIds: [2], size: 0, occupied: 0, reserved: 0, localRsrvd: 0, fetchSize: 130
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [67], size: 1142, occupied: 785, reserved: 23, localRsrvd: 17, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [70], size: 762, occupied: 171, reserved: 30, localRsrvd: 29, fetchSize: 30
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [71], size: 188, occupied: 118, reserved: 0, localRsrvd: 40, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [74], size: 106, occupied: 67, reserved: 10, localRsrvd: 39, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [77], size: 62, occupied: 24, reserved: 19, localRsrvd: 38, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [79], size: 254, occupied: 64, reserved: 30, localRsrvd: 29, fetchSize: 30
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [82], size: 62, occupied: 46, reserved: 16, localRsrvd: 16, fetchSize: 20
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [89], size: 1014, occupied: 0, reserved: 0, localRsrvd: 0, fetchSize: 50
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [93], size: 1016, occupied: 0, reserved: 0, localRsrvd: 0, fetchSize: 50
IP pool [radius.realm.default.ipCategories] ipCategoryIds: [32], size: 126, occupied: 1, reserved: 0, localRsrvd: 0, fetchSize: 50

Radius:
  accounting-requests per minute start: 0; stop: 0; update: 0
  access-requests per minute accept: 34; reject: 2601
  ignore per minute access-request: 0; accounting: 0; accounting-update: 0
Antispam ban count: 0; used per minute: 0

Started: 08.04.2018 08:44:50    Uptime: 0 d 03:21:10
Memory total: 1 478 492 160; max: 4 772 593 664; free: 831 587 696
Memory pools:
  Non-heap memory[Code Cache]: max: 251 658 240; used: 35 237 184; peek: 35 247 936
  Non-heap memory[Metaspace]: max: -1; used: 40 601 688; peek: 40 955 824
  Non-heap memory[Compressed Class Space]: max: 1 073 741 824; used: 4 437 440; peek: 4 549 632
  Heap memory[PS Eden Space]: max: 1 749 024 768; used: 66 851 440; peek: 525 336 576
  Heap memory[PS Survivor Space]: max: 19 922 944; used: 1 769 504; peek: 146 066 056
  Heap memory[PS Old Gen]: max: 3 579 314 176; used: 578 283 520; peek: 578 283 520
Thread count: 312
Trees in cache: 0
Connections pool to Master status Idle: 15; Active: 6; maxActive: 300; maxIdle: 20

Информация о версии:

  Клиент: вер. 6.2.878 / 29.03.2018 17:37:54
    os: Windows 10; java: Java HotSpot(TM) Client VM, v.1.8.0_161
  Сервер: вер. 6.2.1210 / 29.03.2018 17:39:36
    os: Linux; java: Java HotSpot(TM) 64-Bit Server VM, v.1.8.0_161

  assist: вер. 6.2.92 / 08.02.2018 15:50:13
  bill: вер. 6.2.114 / 29.03.2018 17:40:12
  card: вер. 6.2.47 / 08.02.2018 15:50:28
  cerbercrypt: вер. 6.2.237 / 08.02.2018 15:50:31
  dba: вер. 6.2.165 / 08.02.2018 15:50:56
  dialup: вер. 6.2.320 / 08.02.2018 15:49:44
  drweb: вер. 6.2.74 / 08.02.2018 15:50:58
  email: вер. 6.2.180 / 08.02.2018 15:51:00
  gorod: вер. 6.2.167 / 08.02.2018 15:51:03
  inet: вер. 6.2.742 / 29.03.2018 17:39:45
  ipn: вер. 6.2.270 / 16.03.2018 00:58:58
  mps: вер. 6.2.229 / 08.02.2018 15:51:14
  npay: вер. 6.2.205 / 08.02.2018 15:51:19
  payonline: вер. 6.2.69 / 08.02.2018 15:51:27
  phone: вер. 6.2.297 / 08.02.2018 15:51:32
  rbkmoney: вер. 6.2.36 / 08.02.2018 15:51:47
  reports: вер. 6.2.220 / 08.02.2018 15:51:52
  rscm: вер. 6.2.182 / 08.02.2018 15:51:57
  ru.bitel.bgbilling.plugins.cashcheck: вер. 6.2.116 / 08.02.2018 15:49:00
  ru.bitel.bgbilling.plugins.cladr: вер. 6.2.121 / 08.02.2018 15:50:55
  ru.bitel.bgbilling.plugins.crm: вер. 6.2.197 / 08.02.2018 15:50:56
  ru.bitel.bgbilling.plugins.dispatch: вер. 6.2.101 / 08.02.2018 15:50:57
  ru.bitel.bgbilling.plugins.documents: вер. 6.2.180 / 08.02.2018 15:50:57
  ru.bitel.bgbilling.plugins.helpdesk: вер. 6.2.185 / 08.02.2018 15:51:04
  ru.bitel.bgbilling.plugins.organizer: вер. 6.2.74 / 08.02.2018 15:51:22
  simplepay: вер. 6.2.39 / 08.02.2018 15:52:05
  trayinfo: вер. 6.2.174 / 08.02.2018 15:52:13
  voiceip: вер. 6.2.196 / 08.02.2018 15:49:51
  wm: вер. 6.2.191 / 08.02.2018 15:52:25

Окружение:

  Клиент:
    os: Windows 10 x86, 10.0
    java: Java HotSpot(TM) Client VM, v.1.8.0_161
    jre home: C:\Program Files (x86)\Java\jre1.8.0_161
    default tz: 08.04.2018 12:06 MSK +0300 (Europe/Moscow)
    user tz: 08.04.2018 12:06 MSK +0300 (Europe/Moscow)
    locale: ru_RU
  Сервер:
    os: Linux amd64, 4.0.8-300.fc22.x86_64
    java: Java HotSpot(TM) 64-Bit Server VM, v.1.8.0_161
    jre home: /opt/java/jdk1.8.0_161/jre
    default tz: 08.04.2018 12:06 MSK +0300 (Europe/Moscow)
    db time: master: 08.04.2018 12:06 MSK
    db charset: connection: cp1251(cp1251_general_ci), database: cp1251(cp1251_general_ci)
    locale: ru_RU
    uptime: Started: 05.04.2018 15:09:19 Uptime: 2 d 20:57:38

Третьи стороны:

  Fugue Icons. Copyright © 2011 Yusuke Kamiyamane. All rights reserved.
    http://p.yusukekamiyamane.com/
  Silk icon set. Copyright © Mark James. All rights reserved.
    http://www.famfamfam.com/lab/icons/silk/

flow.agent.type=netflow
#flow.agent.link={@deviceId}:-1
#timeout соединения (в миллисекундах).
#sa.command.timeout=10
#команды завершения соединения
sa.command.exit=exit
#Команды, выполняемые при подключении к терминалу( например configure treminal для cisco)
sa.command.connect=conf t
#Команды, выполняемые перед отключением от терминала.
sa.command.disconnect=exit
sa.endSequence=#
#------------------------------------------------------
#Команды включения сервиса на устройстве
#Команды создания сервиса на устройстве.
sa.command.serv.create.1=ip access-list extended acl-out-$contractId-$servId
sa.command.serv.create.2=permit ip any $ip $netmaskWild
sa.command.serv.create.3=ip access-list extended acl-in-$contractId-$servId
sa.command.serv.create.4=permit ip $ip $netmaskWild any
sa.command.serv.create.5=exit
sa.command.serv.create.6=class-map match-all user-out-$contractId-$servId
sa.command.serv.create.7=match access-group name acl-out-$contractId-$servId
sa.command.serv.create.8=exit
sa.command.serv.create.9=class-map match-all user-in-$contractId-$servId
sa.command.serv.create.10=match access-group name acl-in-$contractId-$servId
sa.command.serv.create.11=exit
sa.command.serv.create.12=policy-map ratelimit-out
sa.command.serv.create.13=class user-out-$contractId-$servId
sa.command.serv.create.14=$param($option,speed,10)
sa.command.serv.create.15=exit
sa.command.serv.create.16=exit
sa.command.serv.create.17=exit
sa.command.serv.create.18=policy-map ratelimit-in
sa.command.serv.create.19=class user-in-$contractId-$servId
sa.command.serv.create.20=$param($option,speed,10)
sa.command.serv.create.21=exit
sa.command.serv.create.22=exit
sa.command.serv.create.23=exit
sa.command.serv.create.24=ip access-list extended access-in
sa.command.serv.create.25=permit ip $ip $netmaskWild any
sa.command.serv.create.26=exit
#sa.command.serv.create.27=do write
#Команды включения сервиса на устройстве
sa.command.serv.enable.1=ip access-list extended acl-out-$contractId-$servId
sa.command.serv.enable.2=permit ip any $ip $netmaskWild
sa.command.serv.enable.3=ip access-list extended acl-in-$contractId-$servId
sa.command.serv.enable.4=permit ip $ip $netmaskWild any
sa.command.serv.enable.5=exit
sa.command.serv.enable.6=class-map match-all user-out-$contractId-$servId
sa.command.serv.enable.7=match access-group name acl-out-$contractId-$servId
sa.command.serv.enable.8=exit
sa.command.serv.enable.9=class-map match-all user-in-$contractId-$servId
sa.command.serv.enable.10=match access-group name acl-in-$contractId-$servId
sa.command.serv.enable.11=exit
sa.command.serv.enable.12=policy-map ratelimit-out
sa.command.serv.enable.13=class user-out-$contractId-$servId
sa.command.serv.enable.14=$param($option,speed,10)
sa.command.serv.enable.15=exit
sa.command.serv.enable.16=exit
sa.command.serv.enable.17=exit
sa.command.serv.enable.18=policy-map ratelimit-in
sa.command.serv.enable.19=class user-in-$contractId-$servId
sa.command.serv.enable.20=$param($option,speed,10)
sa.command.serv.enable.21=exit
sa.command.serv.enable.22=exit
sa.command.serv.enable.23=exit
sa.command.serv.enable.24=ip access-list extended access-in
sa.command.serv.enable.25=permit ip $ip $netmaskWild any
sa.command.serv.enable.26=exit
#sa.command.serv.enable.27=do write
#Команды удаления сервиса с устройства.
sa.command.serv.cancel.1=no ip access-list extended acl-out-$contractId-$servId
sa.command.serv.cancel.2=no ip access-list extended acl-in-$contractId-$servId
sa.command.serv.cancel.3=policy-map ratelimit-out
sa.command.serv.cancel.4=no class user-out-$contractId-$servId
sa.command.serv.cancel.5=policy-map ratelimit-in
sa.command.serv.cancel.6=no class user-in-$contractId-$servId
sa.command.serv.cancel.7=exit
sa.command.serv.cancel.8=no class-map match-all user-out-$contractId-$servId
sa.command.serv.cancel.9=no class-map match-all user-in-$contractId-$servId
sa.command.serv.cancel.10=ip access-list extended access-in
sa.command.serv.cancel.11=no permit ip $ip $netmaskWild any
sa.command.serv.cancel.12=exit
#sa.command.serv.cancel.13=do write
#Команды выключения сервиса на устройстве
sa.command.serv.disable.1=no ip access-list extended acl-out-$contractId-$servId
sa.command.serv.disable.2=no ip access-list extended acl-in-$contractId-$servId
sa.command.serv.disable.3=policy-map ratelimit-out
sa.command.serv.disable.4=no class user-out-$contractId-$servId
sa.command.serv.disable.5=policy-map ratelimit-in
sa.command.serv.disable.6=no class user-in-$contractId-$servId
sa.command.serv.disable.7=exit
sa.command.serv.disable.8=no class-map match-all user-out-$contractId-$servId
sa.command.serv.disable.9=no class-map match-all user-in-$contractId-$servId
sa.command.serv.disable.10=ip access-list extended access-in
sa.command.serv.disable.11=no permit ip $ip $netmaskWild any
sa.command.serv.disable.12=exit
#sa.command.serv.disable.13=do write
#

connection 04-11/09:46:29  INFO [sa-p-14-t-48] TelnetServiceActivator - conf t
Enter configuration commands, one per line.  End with CNTL/Z.
Calipso-1(config)#
connection 04-11/09:46:29  INFO [sa-p-14-t-48] ServiceActivatorDeviceWorker - Do task deviceId: 53; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEvent] moduleId: 22; pluginId: no; cid: 54852; scid: -1; userId: 6
6; deviceId: 53; inetServId: 835; connectionId: 722; state: 0; accessCode: 3; timestamp: 1523426645875
connection 04-11/09:46:29  INFO [sa-p-14-t-48] InetApplication - TariffOptionMap: {}
connection 04-11/09:46:29  INFO [sa-p-14-t-48] InetApplication - OptionSet: [24]
connection 04-11/09:46:29  INFO [sa-p-14-t-48] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 835; call: false; oldState: 1; newState: 0; oldOptionSet: ; newOptionSet: 24
connection 04-11/09:46:29  INFO [sa-p-14-t-48] ServiceActivatorDeviceWorker - Processing deviceId:53; command ServiceActivatorEvent type=2; inetServId: 835; call: false; oldState: 1; newState: 0; oldOptionSet: ; newOptionSet: 24
connection 04-11/09:46:29  INFO [sa-p-14-t-48] ServiceActivatorSet - Invoking connectionClose
connection 04-11/09:46:29  INFO [sa-p-14-t-48] CoAServiceActivator - Connection close
connection 04-11/09:46:29 ERROR [sa-p-14-t-48] EventWorker - Error on device:53 - java.lang.NullPointerException
ru.bitel.bgbilling.common.BGException: Error on device:53 - java.lang.NullPointerException
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.doTask(ServiceActivatorDeviceWorker.java:504)
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.internalDoTaskImpl(AsyncEventWorker.java:211)
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.internalDoTask(AsyncEventWorker.java:183)
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.doTasks(AsyncEventWorker.java:109)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.runWorker(ServiceActivatorDeviceWorker.java:246)
        at ru.bitel.bgbilling.kernel.event.EventWorker.internalRunWorker(EventWorker.java:139)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorDeviceWorker.runImpl(ServiceActivatorDeviceWorker.java:152)
        at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
        at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:46)
Caused by: java.lang.NullPointerException

[BGInetAccess] Ошибка работы обработчика активации сервисов [57] C7200-IPN: Pandora-IPN [78.25.155.9]

sa.command.disconnect=

radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 241
Authenticator: {28 20 CE 10 7B 13 96 2E C9 7E 7D CD F8 08 66 A5}
Attributes:
  NAS-Identifier=Gararina-70-1
  User-Name=216-4044999
  CHAP-Password={01 7A 0A 83 A4 4B 53 74 EE A0 66 8F 71 43 DB 7B 3D}
  NAS-IP-Address=78.XXX.XXX.XXX
  NAS-Port=27538700
  Service-Type=2
  Framed-Protocol=1
  NAS-Port-Id=0/1/0/2457
  NAS-Port-Type=15
  Calling-Station-Id=c8-5b-76-13-d9-4f
  cisco-avpair=client-mac-address=c85b.7613.d94f

radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 241
Authenticator: {28 20 CE 10 7B 13 96 2E C9 7E 7D CD F8 08 66 A5}
Attributes:
  NAS-Identifier=Gararina-70-1
  User-Name=216-4044999
  CHAP-Password={01 7A 0A 83 A4 4B 53 74 EE A0 66 8F 71 43 DB 7B 3D}
  NAS-IP-Address=78.XXX.XXX.XXX
  NAS-Port=27538700
  Service-Type=2
  Framed-Protocol=1
  NAS-Port-Id=0/1/0/2457
  NAS-Port-Type=15
  Calling-Station-Id=c85b.7613.d94f
  cisco-avpair=client-mac-address=c85b.7613.d94f

radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] InetNas - Search by username=216-4044999
radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] InetRadiusProcessor - [username=216-4044999] Authenticated as inetServId:81030
radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] InetApplication - inetServ[id=81030] contract status not active.
radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] InetRadiusProcessor - Return code=11
radius 04-11/15:12:27  INFO [rdsLstnr-p-9-t-21] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Reject
Identifier: 241
Authenticator: {}
Attributes:
  Reply-Message=11

Process time auth: 1

.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:ip access-list extended acl-out-53276-80953
.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:permit ip any 172.17.83.80 0.0.0.3
.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:ip access-list extended acl-in-53276-80953
.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:permit ip 172.17.83.80 0.0.0.3 any
.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class-map match-all user-out-53276-80953
.Apr 11 17:36:25: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:match access-group name acl-out-53276-80953
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class-map match-all user-in-53276-80953
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:match access-group name acl-in-53276-80953
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:policy-map ratelimit-out
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class user-out-53276-80953
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:36:26: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:36:26: %SYS-5-CONFIG_I: Configured from console by manad on vty1 (78.XXX.XXX.XXX)
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:ip access-list extended acl-out-53276-80953
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:permit ip any 172.17.83.80 0.0.0.3
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:ip access-list extended acl-in-53276-80953
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:permit ip 172.17.83.80 0.0.0.3 any
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class-map match-all user-out-53276-80953
.Apr 11 17:38:27: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:match access-group name acl-out-53276-80953
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class-map match-all user-in-53276-80953
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:match access-group name acl-in-53276-80953
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:policy-map ratelimit-out
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class user-out-53276-80953
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:38:28: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:38:28: %SYS-5-CONFIG_I: Configured from console by manad on vty1 (78.XXX.XXX.XXX)
.Apr 11 17:40:29: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:ip access-list extended acl-out-53276-80953
.Apr 11 17:40:29: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:permit ip any 172.17.83.80 0.0.0.3
.Apr 11 17:40:29: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:ip access-list extended acl-in-53276-80953
.Apr 11 17:40:29: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:permit ip 172.17.83.80 0.0.0.3 any
.Apr 11 17:40:29: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:40:29: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class-map match-all user-out-53276-80953
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:match access-group name acl-out-53276-80953
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class-map match-all user-in-53276-80953
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:match access-group name acl-in-53276-80953
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:policy-map ratelimit-out
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:class user-out-53276-80953
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit
.Apr 11 17:40:30: %PARSER-5-CFGLOG_LOGGEDCMD: User:manad  logged command:exit