forum.bitel.ru http://forum.bitel.ru/ |
|
[5.2] InetAccounting начал умирать.... http://forum.bitel.ru/viewtopic.php?f=44&t=6527 |
Страница 1 из 1 |
Автор: | nik247 [ 05 мар 2012, 17:38 ] |
Заголовок сообщения: | [5.2] InetAccounting начал умирать.... |
В тестовом режиме работает пару PPtP клиетов. Последнее время начал умирать InetAccounting... (логики проявления ошибки пока не выявил...) После получения "Packet type: Accounting-Request" Вот логи c all.log: Код: accounting 03-05/11:03:09 INFO [accwrkr-1-p-12-t-1] InetApplication - TariffOptionMap: {} radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] update - REQUEST: Packet type: Accounting-Request Identifier: 69 Authenticator: {D3 CA 0C 5A 97 AF FB 6B 2A 30 25 CF F0 9B 3F AD} Attributes: User-Name=bg@localhost NAS-Identifier=router NAS-IP-Address=192.168.1.250 NAS-Port=368161 Service-Type=2 Framed-Protocol=1 Framed-IP-Address=192.168.66.32 Acct-Input-Octets=74 Acct-Output-Octets=70 Acct-Status-Type=3 Acct-Delay-Time=0 Acct-Session-Time=48305 Acct-Input-Packets=8 Acct-Session-Id=81f07374 Acct-Authentic=1 Acct-Output-Packets=7 Event-Timestamp=1330938191 Acct-Output-Gigawords=0 Acct-Input-Gigawords=0 NAS-Port-Type=5 Calling-Station-Id=192.168.1.174 Called-Station-Id=192.168.1.250 Mikrotik-Realm=localhost MS-CHAP-Domain=localhost radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] HourlyDataLogEntry - Create dataLog file: /usr/local/BGInetAccounting/data/radius/source_5/2012/2012-03/2012-0 3-05/log_2012-03-05-11.000.bgdl radius 03-05/11:03:11 WARN [rdsLstnr-p-6-t-1] InetRadiusProcessor - Creating NasConnection from accounting packet [statusType=3] radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] InetRadiusProcessor - Connection exist in db radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] InetRadiusProcessor - Session exist in db radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] connection - 4162:4403 Register ip: 192.168.66.32 radius 03-05/11:03:11 ERROR [rdsLstnr-p-6-t-1] InetNas - InetNasConnection is null radius 03-05/11:03:11 ERROR [rdsLstnr-p-6-t-1] RadiusListenerWorker - java.lang.NullPointerException at ru.bitel.bgbilling.modules.inet.radius.InetNas.setConnection(InetNas.java:239) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.connectionCallStart(InetRadiusProcessor.java:1265) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.getNasConnection(InetRadiusProcessor.java:1108) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.getNasConnection(InetRadiusProcessor.java:832) at ru.bitel.bgbilling.modules.inet.radius.InetNas.updateConnection(InetNas.java:296) at ru.bitel.bgbilling.modules.inet.radius.InetNas.updateConnection(InetNas.java:1) at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accounting(RadiusProcessor.java:508) at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accountingRequest(RadiusProcessor.java:445) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accountingRequest(InetRadiusListenerWorker.java:136) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accountingRequest(InetRadiusListenerWorker.java:1) at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.processPacket(RadiusListenerWorker.java:202) at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.runImpl(RadiusListenerWorker.java:88) at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86) 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) radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] update - RESPONSE: Packet type: Accounting-Response Identifier: 69 Authenticator: {55 02 68 E3 55 E3 CF 99 5B FC C0 EC B6 98 79 50} Attributes: Process time update: 18 accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] SessionTarifficationManager - Calculate for 05.03.2012 11:00:00 accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] SessionTarifficationManager - TariffOptionMap: {} accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] SessionTarifficationManager - InetOptionSet: [1] accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] SessionTarifficationManager - Calculate for 05.03.2012 11:00:00 accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] SessionTarifficationManager - TariffOptionMap: {} accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] SessionTarifficationManager - InetOptionSet: [1] accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] InetApplication - TariffOptionMap: {} accounting 03-05/11:03:14 INFO [accwrkr-1-p-12-t-1] InetApplication - TariffOptionMap: {} accounting 03-05/11:03:44 WARN [accwrkr-3-p-10-t-1] CheckReentrantLock - Object is locked by java.lang.RuntimeException at java.lang.Thread.getStackTrace(Thread.java:1479) at ru.bitel.bgbilling.modules.inet.runtime.CheckReentrantLock.lock(CheckReentrantLock.java:50) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.updateConnectionIpAddress(InetRadiusProcessor.java:757) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.connectionCallStart(InetRadiusProcessor.java:1262) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.getNasConnection(InetRadiusProcessor.java:1108) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.getNasConnection(InetRadiusProcessor.java:832) at ru.bitel.bgbilling.modules.inet.radius.InetNas.updateConnection(InetNas.java:296) at ru.bitel.bgbilling.modules.inet.radius.InetNas.updateConnection(InetNas.java:1) at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accounting(RadiusProcessor.java:508) at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accountingRequest(RadiusProcessor.java:445) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accountingRequest(InetRadiusListenerWorker.java:136) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accountingRequest(InetRadiusListenerWorker.java:1) at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.processPacket(RadiusListenerWorker.java:202) at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.runImpl(RadiusListenerWorker.java:88) at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86) 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) accounting 03-05/11:03:44 WARN [accwrkr-3-p-10-t-1] CheckReentrantLock - Lock wait timeout (waiting next 15s) ... И через час... тухнет... Код: accounting 03-05/12:06:49 ERROR [Thread-11] Accounting - Lock wait timeout for ContractId: 1; servId: 11 ppp:bg Options [1:01.03.2012-01.01.1970; ] TariffModuleTreeSet [8:01.09.2011-…; ] Device state: 1; optionSet:1 java.lang.RuntimeException: Lock wait timeout for ContractId: 1; servId: 11 ppp:bg Options [1:01.03.2012-01.01.1970; ] TariffModuleTreeSet [8:01.09.2011-…; ] Device state: 1; optionSet:1 at ru.bitel.bgbilling.modules.inet.runtime.CheckReentrantLock.lock(CheckReentrantLock.java:46) at ru.bitel.bgbilling.modules.inet.accounting.Accounting.saveState(Accounting.java:721) at ru.bitel.bgbilling.modules.inet.accounting.Accounting.stop(Accounting.java:309) at ru.bitel.bgbilling.kernel.application.server.Application$3.run(Application.java:187) at ru.bitel.common.bootstrap.Shutdown$2.run(Shutdown.java:25) Caused by: java.lang.RuntimeException: OwnerThread: Thread[rdsLstnr-p-6-t-1,5,main] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) at java.lang.Thread.run(Thread.java:662) at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:40) Caused by: java.lang.RuntimeException: Trace: Thread[rdsLstnr-p-6-t-1,5,main] at java.lang.Thread.getStackTrace(Thread.java:1479) at ru.bitel.bgbilling.modules.inet.runtime.CheckReentrantLock.lock(CheckReentrantLock.java:50) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.updateConnectionIpAddress(InetRadiusProcessor.java:757) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.connectionCallStart(InetRadiusProcessor.java:1262) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.getNasConnection(InetRadiusProcessor.java:1108) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.getNasConnection(InetRadiusProcessor.java:832) at ru.bitel.bgbilling.modules.inet.radius.InetNas.updateConnection(InetNas.java:296) at ru.bitel.bgbilling.modules.inet.radius.InetNas.updateConnection(InetNas.java:1) at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accounting(RadiusProcessor.java:508) at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accountingRequest(RadiusProcessor.java:445) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accountingRequest(InetRadiusListenerWorker.java:136) at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accountingRequest(InetRadiusListenerWorker.java:1) at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.processPacket(RadiusListenerWorker.java:202) at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.runImpl(RadiusListenerWorker.java:88) at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) ... 2 more 03-05/12:06:49 INFO [Thread-11] EventProcessor - Shutdown EventProcessor... Могу больше логов привести, если надо. Код: Информация о версии: Клиент: вер. 5.2 сборка 926 от 05.03.2012 15:06:19 os: Windows XP; java: Java HotSpot(TM) Client VM, v.1.6.0_29 ВНИМАНИЕ: Необходимо обновить таймзоны Сервер: вер. 5.2 сборка 1082 от 05.03.2012 15:06:04 os: Linux; java: Java HotSpot(TM) Client VM, v.1.6.0_29 ВНИМАНИЕ: Необходимо обновить таймзоны bill вер. 5.2 сборка 240 от 05.03.2012 15:07:37 card вер. 5.2 сборка 163 от 03.03.2012 13:09:25 dialup вер. 5.2 сборка 310 от 05.03.2012 15:06:46 email вер. 5.2 сборка 159 от 03.03.2012 13:09:33 inet вер. 5.2 сборка 750 от 05.03.2012 15:06:39 mps вер. 5.2 сборка 143 от 03.03.2012 13:09:48 npay вер. 5.2 сборка 164 от 03.03.2012 13:09:52 rentsoft вер. 5.2 сборка 34 от 03.03.2012 13:10:15 reports вер. 5.2 сборка 167 от 03.03.2012 13:10:15 rscm вер. 5.2 сборка 154 от 03.03.2012 13:10:15 ru.bitel.bgbilling.plugins.crm вер. 5.2 сборка 173 от 03.03.2012 13:10:55 ru.bitel.bgbilling.plugins.documents вер. 5.2 сборка 137 от 03.03.2012 13:10:56 ru.bitel.bgbilling.plugins.helpdesk вер. 5.2 сборка 158 от 03.03.2012 13:10:56 ru.bitel.bgbilling.plugins.organizer вер. 5.2 сборка 55 от 03.03.2012 13:10:56 И после такой перезагрузки всегда при старте появляется вот такая ошибка: Код: accounting 03-05/10:58:29 ERROR [main] Accounting - ParseError at [row,col]:[1,2036]
Message: XML document structures must start and end within the same entity. javax.xml.stream.XMLStreamException: ParseError at [row,col]:[1,2036] Message: XML document structures must start and end within the same entity. at com.sun.org.apache.xerces.internal.impl.XMLStreamReaderImpl.next(XMLStreamReaderImpl.java:594) at ru.bitel.bgbilling.modules.inet.accounting.Accounting.loadState(Accounting.java:798) at ru.bitel.bgbilling.modules.inet.accounting.Accounting.startImpl(Accounting.java:284) at ru.bitel.bgbilling.modules.inet.accounting.Accounting.start(Accounting.java:210) at ru.bitel.bgbilling.kernel.application.server.Application.application(Application.java:239) at ru.bitel.bgbilling.kernel.application.server.Application.<init>(Application.java:154) at ru.bitel.bgbilling.kernel.application.server.Application.main(Application.java:742) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at ru.bitel.common.bootstrap.Boot.boot(Boot.java:129) at ru.bitel.common.bootstrap.Boot.main(Boot.java:178) accounting 03-05/10:58:29 INFO [main] Application - Started Accounting@32498805 |
Автор: | Amir [ 05 мар 2012, 19:53 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
После обновления биллинга update.sh в InetAccounting запускали? Вроде бы исправляли и эту ошибку, и чтобы последствий не было с lock. NasIdentifier в поле Идентификатор на всех устройствах-NAS'ах прописан? Какой-нибудь NAS не был удален? |
Автор: | nik247 [ 05 мар 2012, 20:02 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
Amir писал(а): После обновления биллинга update.sh в InetAccounting запускали? Вроде бы исправляли и эту ошибку, и чтобы последствий не было с lock. NasIdentifier в поле Идентификатор на всех устройствах-NAS'ах прописан? Какой-нибудь NAS не был удален? Я сейчас в стадии запуска и обновляюсь по несколько раз в день.... А сообщения с lock очень часто бывают и помогает только перезапуск InetAccounting (при это остановка идет несколько минут) с последующим "завершением" сессии с монитора. NasIdentifier прописан - последнюю неделю структуру их вообще не менял. Менял уже только сегодня (после того как InetAccounting просто лег). Я при lock обычно долго не жду - перезапускаю. Проблема с lock всегда начинется вот так: Код: radius 03-05/11:03:11 WARN [rdsLstnr-p-6-t-1] InetRadiusProcessor - Creating NasConnection from accounting packet [statusType=3]
radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] InetRadiusProcessor - Connection exist in db radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] InetRadiusProcessor - Session exist in db radius 03-05/11:03:11 INFO [rdsLstnr-p-6-t-1] connection - 4162:4403 Register ip: 192.168.66.32 radius 03-05/11:03:11 ERROR [rdsLstnr-p-6-t-1] InetNas - InetNasConnection is null radius 03-05/11:03:11 ERROR [rdsLstnr-p-6-t-1] RadiusListenerWorker - |
Автор: | Amir [ 05 мар 2012, 20:52 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
Отправьте все логи на amir@bitel.ru, пока не понятно из-за чего может быть. |
Автор: | nik247 [ 05 мар 2012, 21:13 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
Amir писал(а): Отправьте все логи на amir@bitel.ru, пока не понятно из-за чего может быть. Отправил. |
Автор: | Amir [ 06 мар 2012, 02:51 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
Выложили обновление. |
Автор: | nik247 [ 06 мар 2012, 03:18 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
Amir писал(а): Выложили обновление. Спасибо. Сейчас обновлюсь. |
Автор: | nik247 [ 06 мар 2012, 13:06 ] |
Заголовок сообщения: | Re: [5.2] InetAccounting начал умирать.... |
Amir писал(а): Выложили обновление. Пока lock не наблюдается.... тестирую дальше. Спасибо. |
Страница 1 из 1 | Часовой пояс: UTC + 5 часов [ Летнее время ] |
Powered by phpBB® Forum Software © phpBB Group http://www.phpbb.com/ |