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/ |