Traccar Server Stops

Ulli Lukas6 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 Tananaev6 years ago

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

Ulli Lukas6 years ago

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

Anton Tananaev6 years ago

Sure, email address is on support page.

Ulli Lukas6 years ago

thank you
email is on the way

Anton Tananaev6 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 Lukas6 years ago

thank you it works