Traccar Server Stops

Ulli Lukas 8 years ago

Hi,

our traccar server stop sometimes working. Now we have the problem that the server say he is startet but nothing works.

Here is the wrapper.log

INFO|wrapper|Service traccar|18-02-22 08:45:58|exec:java -classpath /opt/traccar/wrapperApp.jar:/opt/traccar/tracker-server.jar:/opt/traccar/lib/log4j-1.2.17.jar:/opt/traccar/lib/joda-time-2.9.9.jar:$
INFO|wrapper|Service traccar|18-02-22 08:45:58|posix_spawn pid 3275
INFO|wrapper|Service traccar|18-02-22 08:45:58|started process 3275
INFO|wrapper|Service traccar|18-02-22 08:45:58|started process with pid 3275
INFO|3275/0|Service traccar|18-02-22 08:45:59|[INFO] StandardFileSystemManager - Using "/tmp/vfs_cache" as temporary files store.
INFO|3275/0|Service traccar|18-02-22 08:46:00|[main] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
INFO|3275/0|Service traccar|18-02-22 08:46:00|[main] INFO com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
INFO|3275/0|Service traccar|18-02-22 08:46:01|INFO 2/22/18 8:46 AM: liquibase: Clearing database change log checksums
INFO|3275/0|Service traccar|18-02-22 08:46:01|INFO 2/22/18 8:46 AM: liquibase: Successfully acquired change log lock
INFO|3275/0|Service traccar|18-02-22 08:46:01|INFO 2/22/18 8:46 AM: liquibase: Successfully released change log lock
INFO|3275/0|Service traccar|18-02-22 08:46:01|INFO 2/22/18 8:46 AM: liquibase: Successfully acquired change log lock
INFO|3275/0|Service traccar|18-02-22 08:46:02|INFO 2/22/18 8:46 AM: liquibase: Reading from traccar.DATABASECHANGELOG
INFO|3275/0|Service traccar|18-02-22 08:46:03|INFO 2/22/18 8:46 AM: liquibase: Reading from traccar.DATABASECHANGELOG
INFO|3275/0|Service traccar|18-02-22 08:46:03|INFO 2/22/18 8:46 AM: liquibase: ./schema/changelog-master.xml: changelog-3.8::changelog-3.8-dropuniquetoken::author: Change set changelog-3.8::changelog$
INFO|3275/0|Service traccar|18-02-22 08:46:03|INFO 2/22/18 8:46 AM: liquibase: Successfully released change log lock
INFO|3275/0|Service traccar|18-02-22 08:46:03|[main] INFO org.eclipse.jetty.util.log - Logging initialized @4531ms
INFO|3275/0|Service traccar|18-02-22 08:46:03|java.lang.reflect.InvocationTargetException
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at java.lang.reflect.Method.invoke(Method.java:606)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at org.rzo.yajsw.app.WrapperJVMMain.executeMain(WrapperJVMMain.java:60)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at org.rzo.yajsw.app.WrapperJVMMain.main(WrapperJVMMain.java:43)
INFO|3275/0|Service traccar|18-02-22 08:46:03|Caused by: java.lang.StackOverflowError
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at java.util.HashMap.newKeyIterator(HashMap.java:969)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at java.util.HashMap$KeySet.iterator(HashMap.java:1003)
INFO|3275/0|Service traccar|18-02-22 08:46:03|  at java.util.HashSet.iterator(HashSet.java:171)

Here is the tracker-server.log

2018-02-22 08:45:59  INFO: Operating system name: Linux version: 3.2.41-042stab127.2 architecture: i386
2018-02-22 08:45:59  INFO: Java runtime name: OpenJDK Server VM vendor: Oracle Corporation version: 24.151-b01
2018-02-22 08:45:59  INFO: Memory limit heap: 910mb non-heap: 176mb
2018-02-22 08:45:59  INFO: Character encoding: UTF-8 charset: UTF-8
2018-02-22 08:45:59  INFO: Version: 3.15-SNAPSHOT

Has anybody a idea with what we can solve this issue.

Thanks Ulli

Anton Tananaev 8 years ago

Are you sure that's all you see in the wrapper log?

Ulli Lukas 8 years ago

can i send you the complete wrapper log from today via email?
for this here is the text to large

Anton Tananaev 8 years ago

Sure, email address is on support page.

Ulli Lukas 8 years ago

thank you
email is on the way

Anton Tananaev 8 years ago

This is your problem:

...
FINEST|3713/0|Service traccar|18-02-22 08:56:51|	at org.traccar.database.GroupTree.getNodes(GroupTree.java:146)
FINEST|3713/0|Service traccar|18-02-22 08:56:51|	at org.traccar.database.GroupTree.getNodes(GroupTree.java:146)
FINEST|3713/0|Service traccar|18-02-22 08:56:51|	at org.traccar.database.GroupTree.getNodes(GroupTree.java:146)
FINEST|3713/0|Service traccar|18-02-22 08:56:51|	at org.traccar.database.GroupTree.getNodes(GroupTree.java:146)
FINEST|3713/0|Service traccar|18-02-22 08:56:51|	at org.traccar.database.GroupTree.getNodes(GroupTree.java:146)
...

It means that you have made a loop with a group. Usually it happens when you set group to be its own parent. You need to fix it directly in the database.

You can use SQL like this:

update groups set groupid = null where id = groupid;
Ulli Lukas 8 years ago

thank you it works