connection 02-05/20:48:28  INFO [sa-p-7-t-1] ServiceActivatorSet - Connecting to device
connection 02-05/20:48:28  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEvent] moduleId: 1; pluginId: 0; cid: -1; scid: -1; userId: 0; deviceId: 2; inetServId: 4; connectionId: 20; state: 0; accessCode: 63; timestamp: 1328453308160
connection 02-05/20:48:28  INFO [sa-p-7-t-1] InetApplication - TariffOptionMap: {}
connection 02-05/20:48:28  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Command result event: ServiceActivatorEvent type=2; inetServId: 4; call: true; oldState: 1; newState: 0; oldOptionSet: 1; newOptionSet: 1
connection 02-05/20:48:28  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Processing deviceId:2; command ServiceActivatorEvent type=2; inetServId: 4; call: true; oldState: 1; newState: 0; oldOptionSet: 1; newOptionSet: 1
connection 02-05/20:48:28  INFO [sa-p-7-t-1] ServiceActivatorSet - Invoking connectionModify
connection 02-05/20:48:28  INFO [sa-p-7-t-1] CoAServiceActivator - Connection modify: oldState: 1; newState: 0; oldOptionSet: [1]; newOptionSet: [1]
connection 02-05/20:48:28  INFO [sa-p-7-t-1] CoAServiceActivator - Connection close
connection 02-05/20:48:28  INFO [sa-p-7-t-1] CoAServiceActivator - Send PoD:
Packet type: Disconnect-Request
Identifier: 1
Authenticator: {E8 53 48 BB 99 C8 9A E8 4D 86 BC 77 AD EB E5 93}
Attributes:
  Acct-Session-Id=8445515-vlan103-7

connection 02-05/20:48:28  INFO [sa-p-7-t-1] RadiusClient - Sending to /172.18.3.1:1700
Packet type: Disconnect-Request
Identifier: 1
Authenticator: {E8 53 48 BB 99 C8 9A E8 4D 86 BC 77 AD EB E5 93}
Attributes:
  Acct-Session-Id=8445515-vlan103-7

connection 02-05/20:48:28  INFO [sa-p-7-t-1] DatagramChannelListener - ru.bitel.bgbilling.kernel.network.radius.RadiusClient$RadiusDatagramChannelListener socket init ok.
connection 02-05/20:48:28  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Process event type[2] result=true
connection 02-05/20:48:30  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEvent] moduleId: 1; pluginId: 0; cid: -1; scid: -1; userId: 1; deviceId: 2; inetServId: 1; connectionId: 23; state: 0; accessCode: 3; timestamp: 1328453310177
connection 02-05/20:48:30  INFO [sa-p-7-t-1] InetApplication - TariffOptionMap: {}
connection 02-05/20:48:30  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Command result event: ServiceActivatorEvent type=2; inetServId: 1; call: true; oldState: 1; newState: 0; oldOptionSet: 1; newOptionSet: 1
connection 02-05/20:48:30  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Processing deviceId:2; command ServiceActivatorEvent type=2; inetServId: 1; call: true; oldState: 1; newState: 0; oldOptionSet: 1; newOptionSet: 1
connection 02-05/20:48:30  INFO [sa-p-7-t-1] ServiceActivatorSet - Invoking connectionClose
connection 02-05/20:48:30  INFO [sa-p-7-t-1] CoAServiceActivator - Connection close
connection 02-05/20:48:30  INFO [sa-p-7-t-1] CoAServiceActivator - Send PoD:
Packet type: Disconnect-Request
Identifier: 2
Authenticator: {E9 89 BB C2 41 A3 4B 8E 8A 02 53 F7 0B 7B 67 61}
Attributes:
  Acct-Session-Id=8446239-vlan103-9

connection 02-05/20:48:30  INFO [sa-p-7-t-1] RadiusClient - Sending to /172.18.3.1:1700
Packet type: Disconnect-Request
Identifier: 2
Authenticator: {E9 89 BB C2 41 A3 4B 8E 8A 02 53 F7 0B 7B 67 61}
Attributes:
  Acct-Session-Id=8446239-vlan103-9

connection 02-05/20:48:30  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Process event type[2] result=true
connection 02-05/20:48:40  INFO [sa-p-7-t-1] EventWorker - Timeout waiting futures
connection 02-05/20:48:40 ERROR [sa-p-7-t-1] EventWorker - java.util.concurrent.TimeoutException
ru.bitel.bgbilling.common.BGException: java.util.concurrent.TimeoutException
        at ru.bitel.bgbilling.kernel.event.AsyncEventWorker.doTasks(AsyncEventWorker.java:92)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorWorkerDevice.runWorker(ServiceActivatorWorkerDevice.java:160)
        at ru.bitel.bgbilling.kernel.event.EventWorker.internalRunWorker(EventWorker.java:101)
        at ru.bitel.bgbilling.modules.inet.access.sa.ServiceActivatorWorkerDevice.runImpl(ServiceActivatorWorkerDevice.java:104)
        at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
        at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
        at java.lang.Thread.run(Thread.java:662)
        at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:40)
Caused by: java.util.concurrent.TimeoutException
        ... 15 more
connection 02-05/20:48:40  INFO [sa-p-7-t-1] ServiceActivatorSet - Disconnecting from device
connection 02-05/20:48:40  INFO [sa-p-7-t-1] ServiceActivatorWorkerDevice - Execution error - waiting 60000ms for next try.