forum.bitel.ru
http://forum.bitel.ru/

Reply-Message=44 на активированном договоре (CRM)
http://forum.bitel.ru/viewtopic.php?f=44&t=9162
Страница 1 из 1

Автор:  aiwbend [ 17 апр 2014, 10:34 ]
Заголовок сообщения:  Reply-Message=44 на активированном договоре

С периодичностью сталкиваемся с такой проблемой.
После пополнения счета абонентом и автоматической переактивацией сервиса с новыми атрибутами прилетает 44 ошибка (Доступ приостановлен). Бывает очень редко но стабильно. Если вызвать serviceModify руками пересохранив сервис то ошибки уже нет и все отрабатывает по сценарию.

Логи инетакцеса в этот момент:
Цитата:
mq 04-16/16:34:30 INFO [event-proc-p-2-t-1] ContractRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusChangedTopicEvent] moduleId: 0; pluginId: no; cid: 16816; scid: -1; userId: 0; timestamp: 1397640856451
mq 04-16/16:34:30 INFO [event-proc-p-2-t-1] ContractRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusModifiedEvent] moduleId: 0; pluginId: no; cid: 16816; scid: -1; userId: 0; timestamp: 1397640856402
mq 04-16/16:34:30 INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusModifiedEvent] moduleId: 0; pluginId: no; cid: 16816; scid: -1; userId: 0; timestamp: 1397640856402
mq 04-16/16:34:30 INFO [event-proc-p-2-t-1] ContractRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusModifiedEvent] moduleId: 0; pluginId: no; cid: 16816; scid: -1; userId: 0; timestamp: 1397640856402
mq 04-16/16:34:30 INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.kernel.event.events.ContractStatusModifiedEvent] moduleId: 0; pluginId: no; cid: 16816; scid: -1; userId: 0; timestamp: 1397640856402
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorSet - Connecting to device
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorDeviceWorker - Do task deviceId: 53; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEvent] moduleId: 15; pluginId: no; cid: 16816; scid: -1; userId: 0; deviceId: 53; inetServId: 2013; connectionId: 0; state: 1; accessCode: 0; timestamp: 1397640863113
connection 04-16/16:34:37 INFO [sa-p-13-t-16] InetApplication - TariffOptionMap: {}
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 2013; call: true; oldState: 0; newState: 1; oldOptionSet: 7; newOptionSet: 7
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorDeviceWorker - Processing deviceId:53; command ServiceActivatorEvent type=2; inetServId: 2013; call: true; oldState: 0; newState: 1; oldOptionSet: 7; newOptionSet: 7
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorSet - Invoking serviceModify
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorAdapter - MY SCRIIIIPT! serviceModify 2013 с сервисом договора
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorDeviceWorker - Process event type[2] result=true
connection 04-16/16:34:37 INFO [sa-p-13-t-16] ServiceActivatorDeviceWorker - Changing InetServ:2013 state and/or options
radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 176
Authenticator: {5C CA C3 D5 E0 34 1E 12 9C 9F C2 85 5D C8 87 02}
Attributes:
User-Name=2013
NAS-Port-Id=lg id 25 clips 2013
NAS-Identifier=Redback-IPOE
User-Password=Redback
NAS-IP-Address=10.1.1.1
NAS-Port=-1761605905
Service-Type=5
NAS-Port-Type=5
Platform-Type=4
Medium-Type=11
OS-Version=11.1.2.6p4
NAS-Real-Port=34603008

radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] InetNas - Search by username=2013
radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] InetRadiusProcessor - [username=2013] Authenticated as inetServId:2013
radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] InetApplication - inetServ[id=2013] deviceState not active (accessCode=44).
radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] InetRadiusProcessor - Return code=0
mq 04-16/16:34:38 INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent]
moduleId: 15; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 53; inetServId: 2013; state: 1; optionSet: ; timestamp: 1397640877381
connection 04-16/16:34:38 INFO [sa-p-13-t-16] ServiceActivatorDeviceWorker - Do task deviceId: 53; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEv
ent] moduleId: 15; pluginId: no; cid: 16816; scid: -1; userId: 0; deviceId: 53; inetServId: 2013; connectionId: 0; state: 1; accessCode: 0; timestamp: 1397640863563
radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] HourlyDataLogEntry - Create dataLog file: /usr/local/BGInetAccess/data/radius/source_2/2014/2014-04/2014-04-16/log_2014-04-16-
16.002.bgdl
radius 04-16/16:34:38 INFO [rdsLstnr-p-9-t-9] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 176
Authenticator: {53 90 E2 B7 BB 23 5B 6F 4C 18 E1 C3 8C 68 40 C1}
Attributes:
Reply-Message=44
Framed-IP-Address=10.50.145.66
Forward-Policy=in:HTTP-REDIRECT
HTTP-Redirect-Profile-Name=noauth
IP-Interface-Name=2core

Process time auth: 7

connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorSet - Connecting to device
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEven
t] moduleId: 15; pluginId: no; cid: 16816; scid: -1; userId: 0; type: 2; deviceId: 2; connectionId: 417771; timestamp: 1397640878098
connection 04-16/16:34:38 INFO [sa-p-13-t-45] InetApplication - TariffOptionMap: {}
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=5; inetServId: 2013; call: true; oldState: 1;
newState: 1; oldOptionSet: 7; newOptionSet: 7
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=5; inetServId: 2013; call: true; oldS
tate: 1; newState: 1; oldOptionSet: 7; newOptionSet: 7
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorSet - Invoking onAccountingStop
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorAdapter - MY SCRIIIIPT! onAccountingStop
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Process event type[5] result=true
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEven
t] moduleId: 15; pluginId: no; cid: 16816; scid: -1; userId: 0; type: 1; deviceId: 2; connectionId: 417869; timestamp: 1397640878254
connection 04-16/16:34:38 INFO [sa-p-13-t-45] InetApplication - TariffOptionMap: {}
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=4; inetServId: 2013; call: true; oldState: 1;
newState: 1; oldOptionSet: 7; newOptionSet: 7
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=4; inetServId: 2013; call: true; oldS
tate: 1; newState: 1; oldOptionSet: 7; newOptionSet: 7
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorSet - Invoking onAccountingStart
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorAdapter - MY SCRIIIIPT! onAccountingStart
connection 04-16/16:34:38 INFO [sa-p-13-t-45] ServiceActivatorDeviceWorker - Process event type[4] result=true
mq 04-16/16:34:38 INFO [event-proc-p-2-t-1] InetConnectionManager - Remove connection: InetConnection [id=417771-0, iface=2:-1761605905, sessId=FF160019480006EF-534D6812, s
tart=16.04.2014 00:10:42, uname=2013, addr=10.50.145.66]
mq 04-16/16:34:38 INFO [event-proc-p-2-t-1] InetConnectionManager - Add connection: InetConnection [id=417869-0, iface=2:-1761605905, sessId=FF160019480006EF-534E4EA6, star
t=16.04.2014 16:34:38, uname=2013, addr=10.50.145.66]


Версия:
Цитата:
Сервер: вер. 5.2 сборка 1359 от 02.12.2012 16:15:29
os: Linux; java: Java HotSpot(TM) 64-Bit Server VM, v.1.6.0_38
inet вер. 5.2 сборка 1027 от 29.11.2012 19:15:37


Версия старая мб бага с асинхронностью какаята? Прошу помощи.

Автор:  Amir [ 17 апр 2014, 16:46 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

А у сервиса в клиенте какое состояние при этом отображается?

Автор:  aiwbend [ 18 апр 2014, 12:56 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

отключен [Доступ приостановлен] забыл упомянуть.

Автор:  stark [ 18 апр 2014, 14:32 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

А абонент как счет пополняет ? Напрямую в биллинг платеж заносят или через платежную систему какую-нибудь?

Автор:  aiwbend [ 18 апр 2014, 23:23 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

Последний случай был с приходом напрямую из клиента биллинга.

А может быть причина с небольшим рассинхроном времени с радиусом(не больше 1.5 секунд)? Бывает что за сутки часы убегают немного, и раз в сутки корректируются.

Автор:  aiwbend [ 01 май 2014, 06:34 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

Мы недавно сделали на странице редиректа для помесячных тарифов кнопку "Включить интернет на 3 дня"(приравнивает лимит с балансом в следствии смена статуса и включение интернета).
После списания средств и отключения должников сегодня ночью началось массовое нажимание по этой кнопке, проверив всех кто нажимал увидел такую картину.

Изображение
те у всех(около 100 договоров) все по сценарию кроме 5 подряд идущих по времени договоров у которых состояние сервиса "отключен [Доступ приостановлен]" .
В * * 10мин в планировщике выполняется проверка шлюзов IPN.
Если есть идеи куда копать прошу поделиться.
Наблюдаю дальше.

Автор:  skyb [ 02 май 2014, 10:57 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

Поддерживаю ситуацию, только у меня состояние на сервисе активен, все включено, а дернешь статус договора, все начинает работать

Автор:  aiwbend [ 29 май 2014, 11:31 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре

Похоже, так происходит потому, что статус сервиса в бд меняется на новый после того как NAS запрашивает атрибуты, из за этого и 44 еррор.
Цитата:
mq 05-29/11:06:51 INFO [event-proc-p-2-t-1] InetServRuntimeMap - Taked event: Event[ru.bitel.bgbilling.modules.inet.access.event.InetServDeviceStateAndOptionsModifiedEvent] moduleId: 15; pluginId: no; cid: global; scid: -1; userId: -1; deviceId: 901; inetServId: 9993; state: 1; optionSet: ; timestamp: 1401336411536

Получается mq порой не успевает отправить изменения?

NAS запрашивает новые атрибуты в момент serviceModify. Можно ли в serviceModify коим образом отследить событие InetServDeviceStateAndOptionsModifiedEvent и отправлять атрибуты поле того как статус сменится?
Или мб другие идеи есть?

...
ps. выше про статус сервиса я похоже ошибся, с задержкой он принимает состояние Включен.
pss. в activmq Number Of Pending Messages все по нулям.

Автор:  Amir [ 29 май 2014, 15:30 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре (CRM)

Попробуйте указать в конфигурации модуля authorization.mode=1. После этого понадобится перезапуск Access и Accounting.

Автор:  aiwbend [ 02 июн 2014, 07:10 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре (CRM)

В сервере вер. 5.2 сборка 1359 от 02.12.2012 16:15:29 как понимаю не попало это обновление?
Если 5.2 обновлю там оно будет?

Автор:  stark [ 02 июн 2014, 13:20 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре (CRM)

aiwbend писал(а):
В сервере вер. 5.2 сборка 1359 от 02.12.2012 16:15:29 как понимаю не попало это обновление?
Если 5.2 обновлю там оно будет?

Да. Это в 2013 году добавлено.

Автор:  aiwbend [ 01 мар 2016, 11:03 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре (CRM)

Цитата:
Попробуйте указать в конфигурации модуля authorization.mode=1. После этого понадобится перезапуск Access и Accounting.


Проблема актуальна только с другим Reply-Message. Обработчик активации сервисов запускается до того как договор примет положительный баланс и многих реджектит с Reply-Message=12. Потом если руками вызвать то все четко. Сегодня чет очень много таких.


сб в скрипте можно событие отследить, уже думал в обработчике sleep попробовать.
Цитата:
Сервер: вер. 6.2.1018 / 15.02.2016 02:41:36
inet: вер. 6.2.630 / 15.02.2016 02:38:54

Автор:  Amir [ 02 мар 2016, 20:42 ]
Заголовок сообщения:  Re: Reply-Message=44 на активированном договоре (CRM)

Здесь возможно проблема в схеме - CoA на переавторизацию сессии отправлялся в serviceModify, а рекомендуется работать с соединениями в connectionModify.

Страница 1 из 1 Часовой пояс: UTC + 5 часов [ Летнее время ]
Powered by phpBB® Forum Software © phpBB Group
http://www.phpbb.com/