В тестовом режиме работает пару 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