accounting 09-30/15:11:16 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/15:11:16 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 call (checked 2) sessions for 0 ms.
accounting 09-30/15:11:16 DEBUG [accwrkr-1-p-13-t-1] InetAccountingPeriodList - Create:
                AccountingPeriod: 2: 01.09.2014 00:00:00.0 - 30.09.2014 23:59:59.999
accounting 09-30/15:11:16  INFO [accwrkr-1-p-13-t-1] InetApplication - inetServ[id=2] balance ok: 100.00 [0]
accounting 09-30/15:11:16  INFO [accwrkr-1-p-13-t-1] InetApplication - TariffOptionMap: {}
accounting 09-30/15:11:16  INFO [accwrkr-1-p-13-t-1] TimeTypeCache - Reloading time types..
accounting 09-30/15:11:16  INFO [accwrkr-1-p-13-t-1] InetApplication - OptionSet: [4]
accounting 09-30/15:11:16 DEBUG [accwrkr-1-p-13-t-1] connection - 375:403 Option set full: [4]
accounting 09-30/15:11:16  INFO [accwrkr-1-p-13-t-1] connection - 375:403 Sending events to modify serv & connection options to: 4
mq 09-30/15:11:16 DEBUG [accwrkr-1-p-13-t-1] EventProcessor - Publish: Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 9; inetServId: 2; connectionId: 0; options: 4; timestamp: 1412075476198
accounting 09-30/15:11:16 DEBUG [TimeTypeCacheInit] EventProcessor - Add bitel.billing.server.tariff.bean.TimeTypeCache$1$1@2f152c0e to BG.Event.ru.bitel.bgbilling.kernel.tariff.timetype.event.TimeTypesChangedEvent
mq 09-30/15:11:16 DEBUG [accwrkr-1-p-13-t-1] EventProcessor - Publish: Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 8; inetServId: 2; connectionId: 375; options: 4; timestamp: 1412075476226
accounting 09-30/15:11:16 DEBUG [accwrkr-1-p-13-t-1] SessionTrackingWorker - Tracked 1 sessions for 55 ms.
accounting 09-30/15:11:26 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/15:11:26 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 auto (checked 0) sessions for 0 ms.
accounting 09-30/15:11:26 DEBUG [worker-p-14-t-1] InetLogProccessor - Run InetLogProcessor...
accounting 09-30/15:11:26 DEBUG [worker-p-14-t-1] InetLogProccessor - Proccesed 0 days for 2 ms.
accounting 09-30/15:11:26 DEBUG [worker-p-14-t-1] InetLogProccessor - InetLogProcessor finished

connection 09-30/15:11:16  INFO [sa-p-12-t-2] ServiceActivatorSet - Connecting to device
connection 09-30/15:11:16  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Do task deviceId: 9; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 9; inetServId: 2; connectionId: 0; options: 4; timestamp: 1412075476198
connection 09-30/15:11:16  INFO [sa-p-12-t-3] ServiceActivatorSet - Connecting to device
connection 09-30/15:11:16  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Do task deviceId: 8; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 8; inetServId: 2; connectionId: 375; options: 4; timestamp: 1412075476226
connection 09-30/15:11:16  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 7; newOptionSet: 4
connection 09-30/15:11:16  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Processing deviceId:8; command ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 7; newOptionSet: 4
connection 09-30/15:11:16  INFO [sa-p-12-t-3] ServiceActivatorSet - Invoking connectionModify
connection 09-30/15:11:16  INFO [sa-p-12-t-3] SmartEdgeServiceActivator - Connection modify: oldState: 1; newState: 1; oldOptionSet: [7]; newOptionSet: [4]
connection 09-30/15:11:16  INFO [sa-p-12-t-3] SmartEdgeServiceActivator - Send CoA:
Packet type: CoA-Request
Identifier: 43
Authenticator: {7D 9C 2A 1B A2 09 6D 39 36 5E 1D F6 58 BF 4D 60}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4

connection 09-30/15:11:16  INFO [sa-p-12-t-3] RadiusClient - Sending to /10.0.76.50:3799
Packet type: CoA-Request
Identifier: 43
Authenticator: {7D 9C 2A 1B A2 09 6D 39 36 5E 1D F6 58 BF 4D 60}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4

connection 09-30/15:11:16  INFO [rds-clnt-/10.0.76.50-3799] RadiusClient - Recieved from /10.0.76.50:3799
Packet type: CoA-NAK
Identifier: 43
Authenticator: {0C F3 75 4F 9C 50 A9 87 C2 3E 79 60 C8 88 54 65}
Attributes:
  Error-Cause=402
  Event-Timestamp=1412075476

connection 09-30/15:11:16  INFO [sa-p-12-t-3] SmartEdgeServiceActivator - Send CoA:
Packet type: CoA-Request
Identifier: 44
Authenticator: {0C EA 6E 49 40 9E 26 C4 15 6F 10 4F 95 0C 26 84}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4
  Service-Name:1=RSE-SVC-EXT
  Service-Options:1=1
  Service-Parameter:1=Rate=10000 Burst=1250000 Excess-burst=1875000
  NAT-Policy-Name=NAT

connection 09-30/15:11:16  INFO [sa-p-12-t-3] RadiusClient - Sending to /10.0.76.50:3799
Packet type: CoA-Request
Identifier: 44
Authenticator: {0C EA 6E 49 40 9E 26 C4 15 6F 10 4F 95 0C 26 84}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4
  Service-Name:1=RSE-SVC-EXT
  Service-Options:1=1
  Service-Parameter:1=Rate=10000 Burst=1250000 Excess-burst=1875000
  NAT-Policy-Name=NAT

connection 09-30/15:11:16  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Process event type[2] result=true
connection 09-30/15:11:16  INFO [rds-clnt-/10.0.76.50-3799] RadiusClient - Recieved from /10.0.76.50:3799
Packet type: CoA-NAK
Identifier: 44
Authenticator: {ED F4 54 64 D5 0E 21 6D 31 5A 98 DA 37 DD AF C1}
Attributes:
  Error-Cause=552
  Event-Timestamp=1412075476
  Service-Name:0=RSE-SVC-EXT
  UNKNOWN[2352-193]={01 00 02 28}

sh subscribers active all
d8:fe:e3:c8:cd:f2
        Session state Up
        Agent Remote ID   "^A^Md3200-sw-tech"
        Circuit   2/1 vlan-id 88 clips 132149
        Internal Circuit   2/1:511:63:31/7/2/1077
        Interface bound  vlan88
        Current port-limit unlimited
        Protocol Stack IPV4
        dhcp max-addrs 1 (applied)
        context-name inet-aa (applied)
        ip interface vlan88 (applied)
        dhcp option client id 0x3d0701d8fee3c8cdf2 (applied)
        acct-interim-interval 900 (applied)
        nat policy-name NAT (applied)
        qos-policing-policy DEF-IPOE-IN (applied from sub_default)
        qos-metering-policy DEF-IPOE-OUT (applied from sub_default)
        service  (applied)
           [svc id: 0] RSE-SVC-EXT (acct enabled)
        service-parameter  (applied)
           [svc id: 0] Rate=15000 Burst=1875000 Excess-burst=2812500
        dynamic policy acl  [svc mask: 0x0001] (applied in: qos out: qos)
           [svc id: 0] ip out forward class EXTERNAL qos
           [svc id: 0] ip in forward class EXTERNAL qos
        qos-dynamic-param  [svc mask: 0x0001] (applied)
           [svc id: 0] meter-class-rate EXTERNAL rate-absolute 15000 (applied)
           [svc id: 0] meter-class-burst EXTERNAL 1875000 (applied)
           [svc id: 0] police-class-rate EXTERNAL rate-absolute 15000 (applied)
           [svc id: 0] police-class-burst EXTERNAL 1875000 (applied)
        service-acct (in)  [svc mask: 0x0001] (applied)
           [svc id: 0] qos class-mask 0x02
        service-acct (out)  [svc mask: 0x0001] (applied)
           [svc id: 0] qos class-mask 0x02
        service-interim-acct-interval  [svc mask: 0x0001] (applied)
           [svc id: 0] 900
          IP host entries installed by DHCP: (max_addr 1 cur_entries 1)
                10.88.0.107    d8:fe:e3:c8:cd:f2

#хост для отправки PoD и CoA запросов (по умолчанию - хост, заданный в параметрах устройства Хост/порт)
radius.host=10.XX.XX.XX
#порт для отправки PoD и CoA запросов (по умолчанию - порт, заданный в параметрах устройства Хост/порт)
radius.port=3799
#идентификатор - Nas-Identifier (по умолчанию - значение из поля Идентификатор параметров устройства)
#radius.identifier=<идентификатор устройства>
#используемый secret для общения по radius-протоколу (по умолчанию - значение из поля Community/secret параметров устройства)
#radius.secret=<community/sercret устройства>
 
# Атрибуты, которые передаются для всех сессий.
# IP-Interface-Name соответствует интерфейсу в конфиге RedBack, к которому "биндится" абонентская сессия (обязательный параметр).
# DHCP-Max-Leases - обязательный параметр, необходим для получения адреса по DHCP.
const.access.attributes=IP-Interface-Name=vlanB;DHCP-Max-Leases=1;Acct-Interim-Interval=900;Context-Name=inet

 
# Атрибуты для сессий абонентов, отключенных по балансу. Используются для http-редиректа.
redirect.attributes=HTTP-Redirect-Profile-Name=STOP;Forward-Policy=in:NOAUTH-IPOE;Acct-Interim-Interval=900;NAT-Policy-Name=NAT
# Наборы атрибутов для разных опций. В данном случае указываются разные скорости доступа


#1Mbit (2)   
option.2.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=1000 Burst=125000 Excess-burst=187500;NAT-Policy-Name=NAT
#5Mbit (6)
option.6.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=5000 Burst=1250000 Excess-burst=625000;NAT-Policy-Name=NAT
#10Mbit (4)
option.4.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=10000 Burst=1250000 Excess-burst=1875000;NAT-Policy-Name=NAT
#15Mbit (7)
option.7.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=15000 Burst=1875000 Excess-burst=2812500;NAT-Policy-Name=NAT
#20Mbit (8)
option.8.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=20000 Burst=2500000 Excess-burst=3750000;NAT-Policy-Name=NAT
#30Mbit (9)
option.9.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=30000 Burst=3750000 Excess-burst=5625000;NAT-Policy-Name=NAT
#40Mbit (10)
option.10.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=40000 Burst=5000000 Excess-burst=7500000;NAT-Policy-Name=NAT
#50Mbit (11)
option.11.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=50000 Burst=6250000 Excess-burst=9375000;NAT-Policy-Name=NAT
#60Mbit (12)
option.12.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=60000 Burst=7500000 Excess-burst=11250000;NAT-Policy-Name=NAT
#70Mbit (13)
option.13.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=70000 Burst=8750000 Excess-burst=13125000;NAT-Policy-Name=NAT
#80Mbit (14)
option.14.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=80000 Burst=10000000 Excess-burst=15000000;NAT-Policy-Name=NAT
#90Mbit (15)
option.15.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=90000 Burst=11250000 Excess-burst=16875000;NAT-Policy-Name=NAT
#100Mbit (16)
option.16.attributes=Service-Name:1=RSE-SVC-EXT;Service-Options:1=1;Service-Parameter:1=Rate=100000 Burst=12500000 Excess-burst=18750000;NAT-Policy-Name=NAT


#привязка кодов опций модуля к атрибутам
#данные атрибуты будут выдаваться в AccessAccept при удачной авторизации и при наличии активных опций в тарифе или сервисе


radius.inetOption.2.attributes={@const.access.attributes};{@option.2.attributes}
radius.inetOption.4.attributes={@const.access.attributes};{@option.4.attributes}
radius.inetOption.6.attributes={@const.access.attributes};{@option.6.attributes}
radius.inetOption.7.attributes={@const.access.attributes};{@option.7.attributes}
radius.inetOption.8.attributes={@const.access.attributes};{@option.8.attributes}
radius.inetOption.9.attributes={@const.access.attributes};{@option.9.attributes}
radius.inetOption.10.attributes={@const.access.attributes};{@option.10.attributes}
radius.inetOption.11.attributes={@const.access.attributes};{@option.11.attributes}
radius.inetOption.12.attributes={@const.access.attributes};{@option.12.attributes}
radius.inetOption.13.attributes={@const.access.attributes};{@option.13.attributes}
radius.inetOption.14.attributes={@const.access.attributes};{@option.14.attributes}
radius.inetOption.15.attributes={@const.access.attributes};{@option.15.attributes}
radius.inetOption.16.attributes={@const.access.attributes};{@option.16.attributes}


#атрибуты, выдаваемые при авторизации по реалму default (default - реалм по умолчанию)
radius.realm.default.attributes=
#категории ip адресов из ресурсов, из которых будут выдаваться адреса ("пул", указывается во вкладке "IP ресурсы")
radius.realm.default.ipCategories=1
 
#коды ошибок, при которых вместо reject выдавать accept с заданными атрибутами
#(пользователю выдается серый адрес и устанавливается HTTP-редирект)
radius.disable.accessCodes=1,2,3,4,10,11,12
#атрибуты, выдаваемые при rejectToAccept
radius.disable.attributes=IP-Interface-Name=vlanA;DHCP-Max-Leases=1;{@redirect.attributes}
#категории ip адресов из ресурсов, из которых будут выдаваться адреса для отключенных ("пул", указывается во вкладке "IP ресурсы")
radius.disable.ipCategories=4
#атрибуты, при наличии которых соединение должно считаться в состоянии DISABLE (т.е. с ограниченным доступом)
# Используемый для отключенных пул адресов и параметры http-редиректа.
radius.disable.pattern.attributes=HTTP-Redirect-Profile-Name=STOP;Context-Name=inet




#---------------------------------------------------------------------------------------------
# ОТЛАДКА
#---------------------------------------------------------------------------------------------

# Режим поиска сервиса: 0 (по умолчанию) - по логину, 1 - по интерфейсу на устройстве (в предобработке должны быть
# проставлены опции AGENT_REMOTE_ID и AGENT_CIRCUIT_ID или INTERFACE_ID), 2 - по VLAN на устройстве (в предобработке
# должны быть проставлены опции AGENT_REMOTE_ID и AGENT_CIRCUIT_ID или VLAN_ID), 4 - по VLAN на устройстве или
# дочернем устройстве (в предобработке должны быть проставлены опции AGENT_REMOTE_ID и AGENT_CIRCUIT_ID или VLAN_ID),
# 5 - по MAC-адресу на устройстве (в предобработке должна быть проставлена опция MAC_ADDRESS), 6 - по MAC-адресу на
# устройстве или дочернем устройстве (в предобработке должна быть проставлена опция MAC_ADDRESS).
radius.servSearchMode=1
# Нужно ли проверять пароль: 0 - нет, 1 (по умолчанию) - да.
radius.password.verification=0

# Шаблон, по которому создается ключ для привязки RADIUS-сессии и DHCP-пакетов.
# $deviceId - устройство (обычно NAS и relay-агент, с которого пришел DHCP-запрос на биллинг)
# $remoteId - агенское устройство (обычно коммутатор, к которому подключен абонент), определенное по agentRemoteId
# $circuitId - в зависимости от типа поиска сервиса либо interfaceId, либо VLAN
# $mac - MAC-адрес
radius.key.pattern=$deviceId:$circuitId:$mac


# Настройка алгоритма поиска УСТРОЙСТВА.

#Идентификация коммутатора, расположенного под релеем и сервиса на коммутаторе, может производится в нескольких режимах.
#Режимы определяется переменными конфигурации устройства-релея.

#0 (рекомендуется) - по giaddr или IP-адресу источника идет поиск устройства, далее у этого устройства вызывается
#предобработка preprocessDhcpRequest  (где можно при необходимости извлечь и установить AGENT_REMOTE_ID,
#а также INTERFACE_ID или VLAN_ID), далее по установленному AGENT_REMOTE_ID или, если AGENT_REMOTE_ID не установлен - по конфигурации dhcp.option82.agentRemoteId.x agentRemoteId извлекается из пакета и идет поиск агентского устройства, далее у агентского устройства, если таковое найдено вызывается preprocessDhcpRequest (где можно при необходимости извлечь и установить INTERFACE_ID или VLAN_ID). Здесь запоминаются оба устройства, как deviceId и agentDeviceId;

# 1 - по giaddr или IP-адресу источника идет поиск устройства, по его конфигурации идет извлечение agentRemoteId, далее по agentRemoteId идет поиск агентского устройства. Здесь запоминается последнее найденное устройство как deviceId, agentDeviceId для биллинга будет 0;

# 2 - по giaddr или IP-адресу источника идет поиск устройства, найденное устройство будет запомнено как deviceId, agentDeviceId для биллинга будет 0.

# поиск по giaddr, агентское устройство будет найдено позднее
dhcp.deviceSearchMode=2

# Настройка алгоритма поиска СЕРВИСА

#dhcp.servSearchMode=<servSearchMode>-<subServSearchMode>

# <deviceSearchMode>

# 1 - поиск по интерфейсу на (найденном) устройстве;
# 2 - поиск по VLAN'у на устройстве;
# 3 - поиск на устройстве по интерфейсу и MAC-адресу;
# 4 - поиск по VLAN'у на устройстве и его дочерних устройствах;
# 5 - поиск по MAC-адресу на устройстве;
# 6 - поиск по MAC-адресу на устройстве и дочерних устройствах.

# <subServSearchMode>

# 0 или отсутсвует - нет поиска дочернего сервиса
# 1 - поиск дочернего сервиса по MAC-адресу, если такого дочернего сервиса нет - ошибка авторизации;
# 2 - поиск дочернего сервиса по MAC-адресу, если такого дочернего сервиса нет - сессия будет привязана к родительскому сервису.

dhcp.servSearchMode=1-2

dhcp.key.pattern=$deviceId:$circuitId:$mac

dhcp.connection.closeOnNew=1




#---------------------------------------------------------------------------------------------
# ОТЛАДКА
#---------------------------------------------------------------------------------------------


#--------------
#параметры dhcp
#--------------


#настройки DHCP-сервера
dhcp.option.serverIdentifier=10.34.0.1
dhcp.option.leaseTime=900
 
dhcp.net.option.10.34.0.0:255.255.252.0.gate=10.34.0.1
dhcp.net.option.10.34.0.0:255.255.252.0.dns=10.34.0.1


dhcp.net.option.10.35.0.0:255.255.224.0.gate=10.35.0.1
dhcp.net.option.10.35.0.0:255.255.224.0.dns=10.35.0.1

 


#----------------------------
#параметры активации сервисов
#----------------------------
#длина паузы, если возникла ошибка
#sa.error.pause=60
#количество заданий за раз
#sa.batch.size=20
#время (сек) ожидания завершения всех заданий (при асинхронной работе)
#sa.batch.wait=5
#пауза (сек) после обработки заданий
#sa.batch.pause=0
#время (сек) ожидания новой задачи перед вызовом disconnect.
#sa.batch.waitNext=5
 
#----------------------------------------
#параметры обработчика активации сервисов
#----------------------------------------
#откуда при отправке CoA брать атрибуты опций (по умолчанию - те же атрибуты, что выдаются при удачной авторизации)
sa.radius.option.attributesPrefix=option.
sa.radius.connection.attributes=Acct-Session-Id
#атрибуты CoA запроса для прекращения доступа (используется при sa.radius.connection.withoutBreak=1)
sa.radius.disable.attributes={@redirect.attributes}
#фиксированные атрибуты, добавляемые в запрос перед отправкой CoA
#sa.radius.coa.attributes=
#добавлять ли при отправке CoA атрибуты реалма (для default - из radius.realm.default.attributes)
#sa.radius.realm.addAttributes=0
#атрибуты, посылаемые при закрытии сервисов
sa.radius.service.closeAttributes=Deactivate-Service-Name:1=RSE-SVC-EXT
#фиксированные атрибуты, добавляемые в запрос перед отправкой PoD
#sa.radius.pod.attributes=



#connection.start.fromAccept=1

connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorSet - Connecting to device
connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Do task deviceId: 9; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 9; inetServId: 2; connectionId: 0; options: 7; timestamp: 1412078417159
connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 4; newOptionSet: 7
connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Processing deviceId:9; command ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 4; newOptionSet: 7
connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorSet - Invoking serviceModify
connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Process event type[2] result=true
connection 09-30/16:00:17  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Changing InetServ:2 state and/or options
connection 09-30/16:00:17  INFO [sa-p-12-t-4] ServiceActivatorSet - Connecting to device
connection 09-30/16:00:17  INFO [sa-p-12-t-4] ServiceActivatorDeviceWorker - Do task deviceId: 8; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 8; inetServId: 2; connectionId: 375; options: 7; timestamp: 1412078417166
mq 09-30/16:00:17  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 9; inetServId: 2; state: -1000; optionSet: 7; timestamp: 1412078417172
connection 09-30/16:00:22  INFO [sa-p-12-t-4] ServiceActivatorSet - Disconnecting from device
connection 09-30/16:00:22  INFO [sa-p-12-t-2] ServiceActivatorSet - Disconnecting from device
radius 09-30/16:01:51  INFO [hrlydtlggr-p-6-t-1] HourlyDataLoggerTracker - Checking data log files to close [hours=0]...
dhcp 09-30/16:01:52  INFO [hrlydtlggr-p-8-t-1] HourlyDataLoggerTracker - Checking data log files to close [hours=1]...
dhcp 09-30/16:01:52  INFO [hrlydtlggr-p-8-t-1] HourlyDataLoggerTracker - Remove dataLog file from writers map [/usr/local/BGInetAccess/data/dhcp/source_8/2014/2014-09/2014-09-30/log_2014-09-30-15.004.bgdl]
dhcp 09-30/16:01:54  INFO [hrlydtlggr-p-8-t-1] HourlyDataLoggerTracker - Close dataLog file [/usr/local/BGInetAccess/data/dhcp/source_8/2014/2014-09/2014-09-30/log_2014-09-30-15.004.bgdl]
mq 09-30/16:04:21  INFO [event-proc-p-2-t-1] InetConnectionManager - Update connection: InetConnection [id=386-375, iface=8:553648216, sessId=0100FFFF78000435-542028D4-RSE-SVC-EXT-6C0E061D, start=22.09.2014 17:49:15, uname=SE-SVC-EXT, addr=[IpAddress:null]]
mq 09-30/16:04:21  INFO [event-proc-p-2-t-1] InetDhcpHelperProcessor - IP is null 8:3:
radius 09-30/16:06:51  INFO [hrlydtlggr-p-6-t-1] HourlyDataLoggerTracker - Checking data log files to close [hours=0]...
dhcp 09-30/16:06:52  INFO [hrlydtlggr-p-8-t-1] HourlyDataLoggerTracker - Checking data log files to close [hours=1]...
dhcp 09-30/16:07:13  INFO [dhcpLstnr-p-9-t-3] InetAbstractDhcpProcessor - REQUEST:
Message type: BOOT_REQUEST
Dhcp message type: DHCP Request{3}
htype: 1, hlen: 6, hops: 1
xid: -1808425449, secs: 0, flags: 0
Client IP: 10.88.0.107
Your IP: 0.0.0.0
Server IP: 0.0.0.0
Relay IP: 10.88.0.1
Client MAC: {D8FEE3C8CDF2}
  {61}={01D8FEE3C8CDF2}
  Parameter request list{55}={1, 3, 6, 12, 15, 17, 28, 42, 66, 33, 121, -7, 43}
  Agent information{82}=
    sub{1}={000400580003}
    sub{2}={010D64333230302D73772D74656368}

accounting 09-30/16:00:06 DEBUG [worker-p-14-t-1] InetLogProccessor - Run InetLogProcessor...
accounting 09-30/16:00:06 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/16:00:06 DEBUG [worker-p-14-t-1] InetLogProccessor - Proccesed 0 days for 3 ms.
accounting 09-30/16:00:06 DEBUG [worker-p-14-t-1] InetLogProccessor - InetLogProcessor finished
accounting 09-30/16:00:06 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 auto (checked 0) sessions for 0 ms.
accounting 09-30/16:00:16 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/16:00:16 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 call (checked 2) sessions for 0 ms.
accounting 09-30/16:00:17 DEBUG [accwrkr-1-p-13-t-1] InetAccountingPeriodList - Create:
                AccountingPeriod: 2: 01.09.2014 00:00:00.0 - 30.09.2014 23:59:59.999
accounting 09-30/16:00:17  INFO [accwrkr-1-p-13-t-1] InetApplication - inetServ[id=2] balance ok: 100.00 [0]
accounting 09-30/16:00:17  INFO [accwrkr-1-p-13-t-1] InetApplication - TariffOptionMap: {}
accounting 09-30/16:00:17  INFO [accwrkr-1-p-13-t-1] InetApplication - OptionSet: [7]
accounting 09-30/16:00:17 DEBUG [accwrkr-1-p-13-t-1] connection - 375:403 Option set full: [7]
accounting 09-30/16:00:17  INFO [accwrkr-1-p-13-t-1] connection - 375:403 Sending events to modify serv & connection options to: 7
mq 09-30/16:00:17 DEBUG [accwrkr-1-p-13-t-1] EventProcessor - Publish: Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 9; inetServId: 2; connectionId: 0; options: 7; timestamp: 1412078417159
mq 09-30/16:00:17 DEBUG [accwrkr-1-p-13-t-1] EventProcessor - Publish: Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 8; inetServId: 2; connectionId: 375; options: 7; timestamp: 1412078417166
accounting 09-30/16:00:17 DEBUG [accwrkr-1-p-13-t-1] SessionTrackingWorker - Tracked 1 sessions for 15 ms.
mq 09-30/16:00:17 DEBUG [event-proc-p-2-t-1] Consumer - Caught from BGInetAccess Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 9; inetServId: 2; state: -1000; optionSet: 7; timestamp: 1412078417172
mq 09-30/16:00:17  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 9; inetServId: 2; state: -1000; optionSet: 7; timestamp: 1412078417172
accounting 09-30/16:00:26 DEBUG [worker-p-14-t-1] InetLogProccessor - Run InetLogProcessor...
accounting 09-30/16:00:26 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/16:00:26 DEBUG [worker-p-14-t-1] InetLogProccessor - Proccesed 0 days for 4 ms.
accounting 09-30/16:00:26 DEBUG [worker-p-14-t-1] InetLogProccessor - InetLogProcessor finished
accounting 09-30/16:00:26 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 auto (checked 0) sessions for 0 ms.
accounting 09-30/16:00:36 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/16:00:36 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 call (checked 2) sessions for 0 ms.
accounting 09-30/16:00:37 DEBUG [accwrkr-1-p-13-t-1] InetAccountingPeriodList - Create:
                AccountingPeriod: 2: 01.09.2014 00:00:00.0 - 30.09.2014 23:59:59.999
accounting 09-30/16:00:37  INFO [accwrkr-1-p-13-t-1] InetApplication - inetServ[id=2] balance ok: 100.00 [0]
accounting 09-30/16:00:37  INFO [accwrkr-1-p-13-t-1] InetApplication - TariffOptionMap: {}
accounting 09-30/16:00:37  INFO [accwrkr-1-p-13-t-1] InetApplication - OptionSet: [7]
accounting 09-30/16:00:37 DEBUG [accwrkr-1-p-13-t-1] connection - 375:403 Option set full: [7]
accounting 09-30/16:00:37 DEBUG [accwrkr-1-p-13-t-1] SessionTrackingWorker - Tracked 1 sessions for 4 ms.
accounting 09-30/16:00:46 DEBUG [worker-p-14-t-1] InetLogProccessor - Run InetLogProcessor...
accounting 09-30/16:00:46 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/16:00:46 DEBUG [worker-p-14-t-1] InetLogProccessor - Proccesed 0 days for 3 ms.
accounting 09-30/16:00:46 DEBUG [worker-p-14-t-1] InetLogProccessor - InetLogProcessor finished
accounting 09-30/16:00:46 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 auto (checked 0) sessions for 0 ms.
radius 09-30/16:00:54  INFO [hrlydtlggr-p-5-t-1] HourlyDataLoggerTracker - Checking data log files to close [hours=1]...
collector 09-30/16:00:54  INFO [hrlydtlggr-p-7-t-1] HourlyDataLoggerTracker - Checking data log files to close [hours=0]...
accounting 09-30/16:00:56 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Run SessionFinishManager...
accounting 09-30/16:00:56 DEBUG [accwrkr-3-p-11-t-1] SessionFinishManager - Finished 0 call (checked 2) sessions for 0 ms.
accounting 09-30/16:00:57 DEBUG [accwrkr-1-p-13-t-1] InetAccountingPeriodList - Create:
                AccountingPeriod: 2: 01.09.2014 00:00:00.0 - 30.09.2014 23:59:59.999

                        <attribute name="Service-Name" tag="1" type="string" code="190"/>
                        <attribute name="Service-Options" tag="1" type="integer" code="191"/>
                        <attribute name="Service-Parameter" tag="1" type="string" code="192"/>
                        <attribute name="Dynamic-QoS-Param" type="string" code="196"/>

access 09-30/17:30:28 DEBUG [access-p-5-t-1] SessionCountManager - Run SessionCountManager...
access 09-30/17:30:37 DEBUG [access-p-5-t-1] OldConnectionsRemover - Run OldConnectionsRemover...
access 09-30/17:30:37 DEBUG [access-p-5-t-1] OldConnectionsRemover - Removed 0 old connections with status=0
mq 09-30/17:30:40 DEBUG [event-proc-p-2-t-1] Consumer - Caught from BGBillingServerN1 Event[ru.bitel.bgbilling.kernel.tariff.server.event.TariffTreeModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: 1; timestamp: 1412083840413
mq 09-30/17:30:40 DEBUG [event-proc-p-2-t-1] TariffTreeCache - Reload tree in cache, moduleId: 1; treeId: 1


connection 09-30/17:30:58  INFO [sa-p-12-t-2] ServiceActivatorSet - Connecting to device
connection 09-30/17:30:58 DEBUG [sa-p-12-t-2] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 8; inetServId: 2; connectionId: 375; options: 10; timestamp: 1412083858904
connection 09-30/17:30:58  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Do task deviceId: 8; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 8; inetServId: 2; connectionId: 375; options: 10; timestamp: 1412083858904
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorSet - Connecting to device
connection 09-30/17:30:58 DEBUG [sa-p-12-t-3] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 9; inetServId: 2; connectionId: 0; options: 10; timestamp: 1412083858892
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Do task deviceId: 9; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaOptionsModifyEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; deviceId: 9; inetServId: 2; connectionId: 0; options: 10; timestamp: 1412083858892
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 7; newOptionSet: 10
connection 09-30/17:30:58  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 7; newOptionSet: 10
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Processing deviceId:9; command ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 7; newOptionSet: 10
connection 09-30/17:30:58  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Processing deviceId:8; command ServiceActivatorEvent type=2; inetServId: 2; call: true; oldState: 1; newState: 1; oldOptionSet: 7; newOptionSet: 10
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorSet - Invoking serviceModify
connection 09-30/17:30:58  INFO [sa-p-12-t-2] ServiceActivatorSet - Invoking connectionModify
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Process event type[2] result=true
connection 09-30/17:30:58  INFO [sa-p-12-t-3] ServiceActivatorDeviceWorker - Changing InetServ:2 state and/or options
connection 09-30/17:30:58  INFO [sa-p-12-t-2] SmartEdgeServiceActivator - Connection modify: oldState: 1; newState: 1; oldOptionSet: [7]; newOptionSet: [10]
connection 09-30/17:30:58  INFO [sa-p-12-t-2] SmartEdgeServiceActivator - Send CoA:
Packet type: CoA-Request
Identifier: 1
Authenticator: {85 6F 59 0E B6 5F 74 91 3C F5 F4 AA BC 4E F2 04}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4
  Deactivate-Service-Name:1=RSE-SVC-EXT

connection 09-30/17:30:58  INFO [sa-p-12-t-2] RadiusClient - Sending to /10.0.76.50:3799
Packet type: CoA-Request
Identifier: 1
Authenticator: {85 6F 59 0E B6 5F 74 91 3C F5 F4 AA BC 4E F2 04}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4
  Deactivate-Service-Name:1=RSE-SVC-EXT

mq 09-30/17:30:58 DEBUG [sa-p-12-t-3] EventProcessor - Publish: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 9; inetServId: 2; state: -1000; optionSet: 10; timestamp: 1412083858972
connection 09-30/17:30:58  INFO [sa-p-12-t-2] DatagramChannelListener - ru.bitel.bgbilling.kernel.network.radius.RadiusClient$RadiusDatagramChannelListener socket init ok.
mq 09-30/17:30:58 DEBUG [event-proc-p-2-t-1] Consumer - Caught from BGInetAccess Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 9; inetServId: 2; state: -1000; optionSet: 10; timestamp: 1412083858972
mq 09-30/17:30:58  INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 9; inetServId: 2; state: -1000; optionSet: 10; timestamp: 1412083858972
connection 09-30/17:30:59  INFO [rds-clnt-/10.0.76.50-3799] RadiusClient - Recieved from /10.0.76.50:3799
Packet type: CoA-ACK
Identifier: 1
Authenticator: {0A 7E FA 3C 8B DE 24 20 5B F7 3D 47 5B F4 2B 91}
Attributes:
  Error-Cause=200
  Event-Timestamp=1412083858
  UNKNOWN[2352-193]={01 00 00 C8}
  Deactivate-Service-Name=RSE-SVC-EXT

connection 09-30/17:30:59  INFO [sa-p-12-t-2] SmartEdgeServiceActivator - Send CoA:
Packet type: CoA-Request
Identifier: 2
Authenticator: {DD B5 1F B4 EB 1B 56 F4 F3 73 4D F2 41 56 D8 AA}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4
  Service-Name:1=RSE-SVC-EXT
  Service-Options:1=1
  Service-Parameter:1=Rate=40000 Burst=5000000 Excess-burst=7500000
  NAT-Policy-Name=NAT

connection 09-30/17:30:59  INFO [sa-p-12-t-2] RadiusClient - Sending to /10.0.76.50:3799
Packet type: CoA-Request
Identifier: 2
Authenticator: {DD B5 1F B4 EB 1B 56 F4 F3 73 4D F2 41 56 D8 AA}
Attributes:
  Acct-Session-Id=0100FFFF78000435-542028D4
  Service-Name:1=RSE-SVC-EXT
  Service-Options:1=1
  Service-Parameter:1=Rate=40000 Burst=5000000 Excess-burst=7500000
  NAT-Policy-Name=NAT

connection 09-30/17:30:59  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Process event type[2] result=true
connection 09-30/17:30:59  INFO [rds-clnt-/10.0.76.50-3799] RadiusClient - Recieved from /10.0.76.50:3799
Packet type: CoA-ACK
Identifier: 2
Authenticator: {9F F7 E6 13 BA FB 3B 2D 02 4C 14 4A C8 3E 3D E4}
Attributes:
  Error-Cause=200
  Event-Timestamp=1412083859
  Service-Name:0=RSE-SVC-EXT
  UNKNOWN[2352-193]={01 00 00 C8}

mq 09-30/17:30:59 DEBUG [event-proc-p-2-t-1] Consumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.accounting.event.InetAccountingEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; type: 1; deviceId: 8; connectionId: 387; timestamp: 1412083859052
mq 09-30/17:30:59  INFO [event-proc-p-2-t-1] InetConnectionManager - Add connection: InetConnection [id=387-375, iface=8:553648216, sessId=0100FFFF78000435-542028D4-RSE-SVC-EXT-19656E09, start=30.09.2014 17:30:59, uname=SE-SVC-EXT, addr=[IpAddress:null]]
mq 09-30/17:30:59 DEBUG [event-proc-p-2-t-1] InetAbstractDhcpProcessor - Skip service connection
mq 09-30/17:30:59  INFO [event-proc-p-2-t-1] InetDhcpHelperProcessor - IP is null 8:3:
connection 09-30/17:30:59 DEBUG [sa-p-12-t-2] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; type: 1; deviceId: 8; connectionId: 387; timestamp: 1412083859052
connection 09-30/17:30:59  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Do task deviceId: 8; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; type: 1; deviceId: 8; connectionId: 387; timestamp: 1412083859052
connection 09-30/17:30:59  INFO [sa-p-12-t-2] InetApplication - TariffOptionMap: {}
connection 09-30/17:30:59  INFO [sa-p-12-t-2] TimeTypeCache - Reloading time types..
connection 09-30/17:30:59  INFO [sa-p-12-t-2] InetApplication - OptionSet: [10]
connection 09-30/17:30:59  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=4; inetServId: 2; call: true; oldState: 0; newState: 0; oldOptionSet: 10; newOptionSet: 10
connection 09-30/17:30:59  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Processing deviceId:8; command ServiceActivatorEvent type=4; inetServId: 2; call: true; oldState: 0; newState: 0; oldOptionSet: 10; newOptionSet: 10
connection 09-30/17:30:59  INFO [sa-p-12-t-2] ServiceActivatorSet - Invoking onAccountingStart
connection 09-30/17:30:59  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Process event type[4] result=true
connection 09-30/17:30:59 DEBUG [TimeTypeCacheInit] EventProcessor - Add bitel.billing.server.tariff.bean.TimeTypeCache$1$1@307a140f to BG.Event.ru.bitel.bgbilling.kernel.tariff.timetype.event.TimeTypesChangedEvent
access 09-30/17:31:00 DEBUG [access-p-5-t-1] SessionCountManager - Run SessionCountManager...
connection 09-30/17:31:00 DEBUG [sa-p-12-t-2] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; type: 2; deviceId: 8; connectionId: 386; timestamp: 1412083860921
connection 09-30/17:31:00  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Do task deviceId: 8; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; type: 2; deviceId: 8; connectionId: 386; timestamp: 1412083860921
connection 09-30/17:31:00  INFO [sa-p-12-t-2] InetApplication - TariffOptionMap: {}
connection 09-30/17:31:00  INFO [sa-p-12-t-2] InetApplication - OptionSet: [10]
connection 09-30/17:31:00  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=5; inetServId: 2; call: true; oldState: 0; newState: 0; oldOptionSet: 10; newOptionSet: 10
connection 09-30/17:31:00  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Processing deviceId:8; command ServiceActivatorEvent type=5; inetServId: 2; call: true; oldState: 0; newState: 0; oldOptionSet: 10; newOptionSet: 10
connection 09-30/17:31:00  INFO [sa-p-12-t-2] ServiceActivatorSet - Invoking onAccountingStop
connection 09-30/17:31:00  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Process event type[5] result=true
mq 09-30/17:31:00 DEBUG [event-proc-p-2-t-1] Consumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.accounting.event.InetAccountingEvent] moduleId: 1; pluginId: no; cid: 1; scid: -1; userId: 0; type: 2; deviceId: 8; connectionId: 386; timestamp: 1412083860921
mq 09-30/17:31:00  INFO [event-proc-p-2-t-1] InetConnectionManager - Remove connection: InetConnection [id=386-375, iface=8:553648216, sessId=0100FFFF78000435-542028D4-RSE-SVC-EXT-6C0E061D, start=22.09.2014 17:49:15, uname=SE-SVC-EXT, addr=[IpAddress:null]]
mq 09-30/17:31:00 DEBUG [event-proc-p-2-t-1] InetAbstractDhcpProcessor - Skip service connection
mq 09-30/17:31:00  INFO [event-proc-p-2-t-1] InetDhcpHelperProcessor - Remove connection remove 8:3:
connection 09-30/17:31:03  INFO [sa-p-12-t-3] ServiceActivatorSet - Disconnecting from device



connection 09-30/17:31:05 DEBUG [sa-p-12-t-2] EventWorker - Waiting 5000 millis for last future results will done...
connection 09-30/17:31:05  INFO [sa-p-12-t-2] EventWorker - Future is done
connection 09-30/17:31:05  INFO [sa-p-12-t-2] ServiceActivatorDeviceWorker - Changing InetConnection:375 state and/or options
mq 09-30/17:31:05 DEBUG [sa-p-12-t-2] EventProcessor - Publish: Event[ru.bitel.bgbilling.modules.inet.access.event.InetConnectionDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 8; connectionId: 375; optionSet: 10; timestamp: 1412083865932
connection 09-30/17:31:05  INFO [sa-p-12-t-2] ServiceActivatorSet - Disconnecting from device

mq 09-30/17:31:05 DEBUG [event-proc-p-2-t-1] Consumer - Caught from BGInetAccess Event[ru.bitel.bgbilling.modules.inet.access.event.InetConnectionDeviceStateAndOptionsModifiedEvent] moduleId: 1; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 8; connectionId: 375; optionSet: 10; timestamp: 1412083865932








radius 09-30/17:31:20 DEBUG [setup-sched-p-1-t-1] RadiusProcessor - After clean old rad sessions: 0
access 09-30/17:31:32 DEBUG [access-p-5-t-1] SessionCountManager - Run SessionCountManager...
access 09-30/17:32:04 DEBUG [access-p-5-t-1] SessionCountManager - Run SessionCountManager...