Traccar 4.1 Errors

After downloading traccar-4.1-linux-64-4.1.zip and installing, this appears in my system logs:

Oct 23 21:42:20 pinnacle traccar[17537]: WARNING: All illegal access operations will be denied in a future release
Oct 23 21:42:20 pinnacle traccar[17537]: WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
Oct 23 21:42:20 pinnacle traccar[17537]: WARNING: Please consider reporting this to the maintainers of com.google.inject.internal.cglib.core.$ReflectUtils$1
Oct 23 21:42:20 pinnacle traccar[17537]: WARNING: Illegal reflective access by com.google.inject.internal.cglib.core.$ReflectUtils$1 (file:/opt/traccar/lib/guice-4.2.1.jar) to method java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
Oct 23 21:42:20 pinnacle traccar[17537]: WARNING: An illegal reflective access operation has occurred
Oct 23 21:42:15 pinnacle systemd[1]: Started traccar.

There is no tracker-server.log file created. So I restarted traccar and the tracker-server.log file was created but ended like this:

2018-10-23 21:54:58  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:8aa3e56afe6ba86cd004fa801507bc28' WHERE ID = 'changelog-3.17' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.17'
2018-10-23 21:54:58  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:90aedfa378aa717f8d8ae541f97b87b2' WHERE ID = 'changelog-4.0-renaming' AND AUTHOR = 'author' AND FILENAME = 'changelog-4.0'
2018-10-23 21:54:58  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:ac63c4153f5b2ee5c7a07056da269571' WHERE ID = 'changelog-4.0' AND AUTHOR = 'author' AND FILENAME = 'changelog-4.0'
2018-10-23 21:54:58  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOG
2018-10-23 21:54:58  INFO: Reading from traccar.DATABASECHANGELOG
2018-10-23 21:54:58  INFO: SELECT * FROM traccar.DATABASECHANGELOG ORDER BY DATEEXECUTED ASC, ORDEREXECUTED ASC
2018-10-23 21:54:58  INFO: Successfully released change log lock
2018-10-23 21:54:58 ERROR: Main method error - 1 - ArrayIndexOutOfBoundsException (... < DataManager:306 < *:91 < Context:382 < Main:110 < ...)

And I get this in my system logs:

Oct 23 21:54:58 pinnacle systemd[1]: traccar.service: Failed with result 'exit-code'.
Oct 23 21:54:58 pinnacle systemd[1]: traccar.service: Main process exited, code=exited, status=1/FAILURE
Oct 23 21:54:58 pinnacle traccar[18372]:         ... 1 more
Oct 23 21:54:58 pinnacle traccar[18372]:         at org.traccar.Main.run(Main.java:110)
Oct 23 21:54:58 pinnacle traccar[18372]:         at org.traccar.Context.init(Context.java:382)
Oct 23 21:54:58 pinnacle traccar[18372]:         at org.traccar.database.DataManager.<init>(DataManager.java:91)
Oct 23 21:54:58 pinnacle traccar[18372]:         at org.traccar.database.DataManager.initDatabaseSchema(DataManager.java:306)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.Liquibase.update(Liquibase.java:175)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.Liquibase.update(Liquibase.java:179)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.Liquibase.update(Liquibase.java:198)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.changelog.DatabaseChangeLog.validate(DatabaseChangeLog.java:269)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.changelog.ChangeLogIterator.run(ChangeLogIterator.java:78)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.changelog.visitor.ValidatingVisitor.visit(ValidatingVisitor.java:109)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.change.AbstractChange.warn(AbstractChange.java:358)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.change.AbstractChange.generateStatementsVolatile(AbstractChange.java:287)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.change.core.CreateTableChange.generateStatements(CreateTableChange.java:70)
Oct 23 21:54:58 pinnacle traccar[18372]:         at liquibase.datatype.DataTypeFactory.fromDescription(DataTypeFactory.java:251)
Oct 23 21:54:58 pinnacle traccar[18372]: Caused by: java.lang.ArrayIndexOutOfBoundsException: 1
Oct 23 21:54:58 pinnacle traccar[18372]:         at org.traccar.Main.main(Main.java:104)
Oct 23 21:54:58 pinnacle traccar[18372]:         at org.traccar.Main.run(Main.java:152)
Oct 23 21:54:58 pinnacle traccar[18372]: Exception in thread "main" java.lang.RuntimeException: java.lang.ArrayIndexOutOfBoundsException: 1
Oct 23 21:54:54 pinnacle systemd[1]: Started traccar.

Please help!

Neil Romig6 years ago

I posted this in a panic before carefully looking to see it already mentioned. Sorry.

Neil Romig6 years ago

Applied the fixed changelog-4.0-clean.xml & also changelog-4.0.xml file from github and have started successfully, though some odd lines in tracker-server.log about a precondition failing:

2018-10-23 22:37:24  INFO: HikariPool-1 - Starting...
2018-10-23 22:37:24  INFO: HikariPool-1 - Start completed.
2018-10-23 22:37:25  INFO: Clearing database change log checksums
2018-10-23 22:37:25  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOGLOCK
2018-10-23 22:37:25  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOGLOCK
2018-10-23 22:37:25  INFO: SELECT `LOCKED` FROM traccar.DATABASECHANGELOGLOCK WHERE ID=1
2018-10-23 22:37:25  INFO: Successfully acquired change log lock
2018-10-23 22:37:25  INFO: SELECT MD5SUM FROM traccar.DATABASECHANGELOG WHERE MD5SUM IS NOT NULL LIMIT 1
2018-10-23 22:37:25  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = NULL
2018-10-23 22:37:25  INFO: Successfully released change log lock
2018-10-23 22:37:25  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOGLOCK
2018-10-23 22:37:25  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOGLOCK
2018-10-23 22:37:25  INFO: SELECT `LOCKED` FROM traccar.DATABASECHANGELOGLOCK WHERE ID=1
2018-10-23 22:37:25  INFO: Successfully acquired change log lock
2018-10-23 22:37:27  INFO: SELECT MD5SUM FROM traccar.DATABASECHANGELOG WHERE MD5SUM IS NOT NULL LIMIT 1
2018-10-23 22:37:27  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOG
2018-10-23 22:37:27  INFO: Reading from traccar.DATABASECHANGELOG
2018-10-23 22:37:27  INFO: SELECT * FROM traccar.DATABASECHANGELOG ORDER BY DATEEXECUTED ASC, ORDEREXECUTED ASC
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:ea6f202534dc7845fd1a49e1d8384d9c' WHERE ID = 'changelog-3.3' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.3'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:194e3ae7ec6d201917136e04a26e237b' WHERE ID = 'changelog-3.5' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.5'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:1ec7941b03749f24e365bc6126b07e1a' WHERE ID = 'changelog-3.6' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.6'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:2e444e379f31d0b8e09273ec7ffe87a0' WHERE ID = 'changelog-3.7' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.7'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:974c33d2fb399ef6477c3897450fb078' WHERE ID = 'changelog-3.7-notmssql' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.7'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:74d68027951f8d2ae6bb2e24df8365bc' WHERE ID = 'changelog-3.8' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.8'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:34822842d65deb843a7d16f857d52ecc' WHERE ID = 'changelog-3.9' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.9'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:e1ddbe83e1ecf856a912755fc118f82e' WHERE ID = 'changelog-3.10' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.10'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:191c21d8f0f921845cf93bbc9d0639b9' WHERE ID = 'changelog-3.10-notmssql' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.10'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:ad1f63566e8d08812fbf0b93a118ef6e' WHERE ID = 'changelog-3.10-mssql' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.10'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:127b36b9d32a9d236df51d19b18c3766' WHERE ID = 'changelog-3.7-mssql' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.10'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:7800f890b9706a480bd5a79b591b6ca7' WHERE ID = 'changelog-3.11' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.11'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:5ce520811d626ad325a014b9fcbb1a13' WHERE ID = 'changelog-3.12' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.12'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:a3bf7fabcde29e106fe2f89829a76a84' WHERE ID = 'changelog-3.12-notmssql' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.12'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:cfc881bd2dadb561aa9c1a467bc8cc1c' WHERE ID = 'changelog-3.12-pgsql' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.12'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:1be7e6c0520f8be53ef1b099d96afba5' WHERE ID = 'changelog-3.14' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.14'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:ac8ccec176027e98bbf45dc6c15d9b05' WHERE ID = 'changelog-3.15' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.15'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:b59407d70bfe9bf57bc9968adfbbdf1c' WHERE ID = 'changelog-3.16' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.16'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:3f14c3b08068eb7628d0d3e2941eb2d3' WHERE ID = 'changelog-3.3-admin' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.17'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:8aa3e56afe6ba86cd004fa801507bc28' WHERE ID = 'changelog-3.17' AND AUTHOR = 'author' AND FILENAME = 'changelog-3.17'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:90aedfa378aa717f8d8ae541f97b87b2' WHERE ID = 'changelog-4.0-renaming' AND AUTHOR = 'author' AND FILENAME = 'changelog-4.0'
2018-10-23 22:37:27  INFO: UPDATE traccar.DATABASECHANGELOG SET MD5SUM = '8:ac63c4153f5b2ee5c7a07056da269571' WHERE ID = 'changelog-4.0' AND AUTHOR = 'author' AND FILENAME = 'changelog-4.0'
2018-10-23 22:37:27  INFO: SELECT COUNT(*) FROM traccar.DATABASECHANGELOG
2018-10-23 22:37:27  INFO: Reading from traccar.DATABASECHANGELOG
2018-10-23 22:37:27  INFO: SELECT * FROM traccar.DATABASECHANGELOG ORDER BY DATEEXECUTED ASC, ORDEREXECUTED ASC
2018-10-23 22:37:27  INFO: Marking ChangeSet: changelog-4.0-clean::changelog-4.0-clean::author ran despite precondition failure due to onFail='MARK_RAN': 
          ./schema/changelog-master.xml : Not precondition failed

2018-10-23 22:37:27  INFO: SELECT MAX(ORDEREXECUTED) FROM traccar.DATABASECHANGELOG
2018-10-23 22:37:27  INFO: INSERT INTO traccar.DATABASECHANGELOG (ID, AUTHOR, FILENAME, DATEEXECUTED, ORDEREXECUTED, MD5SUM, `DESCRIPTION`, COMMENTS, EXECTYPE, CONTEXTS, LABELS, LIQUIBASE, DEPLOYMENT_ID) VALUES ('changelog-4.0-clean', 'author', 'changelog-4.0-clean', NOW(), 26, '8:d59fa93049c031a8bcfbc02958c1942c', 'createTable tableName=tc_attributes; createTable tableName=tc_calendars; createTable tableName=tc_commands; createTable tableName=tc_device_attribute; createTable tableName=tc_device_command; createTable tableName=tc_device_driver; createTable tab...', '', 'MARK_RAN', NULL, NULL, '3.6.2', '0330647438')
2018-10-23 22:37:27  INFO: Marking ChangeSet: changelog-4.0-clean::changelog-4.0-clean-common::author ran despite precondition failure due to onFail='MARK_RAN': 
          ./schema/changelog-master.xml : Not precondition failed

2018-10-23 22:37:27  INFO: INSERT INTO traccar.DATABASECHANGELOG (ID, AUTHOR, FILENAME, DATEEXECUTED, ORDEREXECUTED, MD5SUM, `DESCRIPTION`, COMMENTS, EXECTYPE, CONTEXTS, LABELS, LIQUIBASE, DEPLOYMENT_ID) VALUES ('changelog-4.0-clean-common', 'author', 'changelog-4.0-clean', NOW(), 27, '8:1adabae3238e02ccc97cc422359a58a0', 'addForeignKeyConstraint baseTableName=tc_groups, constraintName=fk_groups_groupid, referencedTableName=tc_groups; addForeignKeyConstraint baseTableName=tc_user_user, constraintName=fk_user_user_manageduserid, referencedTableName=tc_users', '', 'MARK_RAN', NULL, NULL, '3.6.2', '0330647438')
2018-10-23 22:37:27  INFO: Marking ChangeSet: changelog-4.0-clean::changelog-4.0-clean-mssql::author ran despite precondition failure due to onFail='MARK_RAN': 
          ./schema/changelog-master.xml : Not precondition failed

2018-10-23 22:37:27  INFO: INSERT INTO traccar.DATABASECHANGELOG (ID, AUTHOR, FILENAME, DATEEXECUTED, ORDEREXECUTED, MD5SUM, `DESCRIPTION`, COMMENTS, EXECTYPE, CONTEXTS, LABELS, LIQUIBASE, DEPLOYMENT_ID) VALUES ('changelog-4.0-clean-mssql', 'author', 'changelog-4.0-clean', NOW(), 28, '8:b148f52efe9c6a3e74a56e33e257a3e2', 'sql; sql; sql; sql', '', 'MARK_RAN', NULL, NULL, '3.6.2', '0330647438')
2018-10-23 22:37:27  INFO: Marking ChangeSet: changelog-4.0::changelog-4.0-pre::author ran despite precondition failure due to onFail='MARK_RAN': 
          ./schema/changelog-master.xml : Not precondition failed

2018-10-23 22:37:27  INFO: INSERT INTO traccar.DATABASECHANGELOG (ID, AUTHOR, FILENAME, DATEEXECUTED, ORDEREXECUTED, MD5SUM, `DESCRIPTION`, COMMENTS, EXECTYPE, CONTEXTS, LABELS, LIQUIBASE, DEPLOYMENT_ID) VALUES ('changelog-4.0-pre', 'author', 'changelog-4.0', NOW(), 29, '8:3974bfe5a2e962c0cd663433c832c16a', 'addColumn tableName=notifications', '', 'MARK_RAN', NULL, NULL, '3.6.2', '0330647438')
2018-10-23 22:37:27  INFO: Marking ChangeSet: changelog-4.0::changelog-4.0-common::author ran despite precondition failure due to onFail='MARK_RAN': 
          ./schema/changelog-master.xml : Not precondition failed

2018-10-23 22:37:27  INFO: INSERT INTO traccar.DATABASECHANGELOG (ID, AUTHOR, FILENAME, DATEEXECUTED, ORDEREXECUTED, MD5SUM, `DESCRIPTION`, COMMENTS, EXECTYPE, CONTEXTS, LABELS, LIQUIBASE, DEPLOYMENT_ID) VALUES ('changelog-4.0-common', 'author', 'changelog-4.0', NOW(), 30, '8:65fb49c5be37693183708351c507dd50', 'update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications', '', 'MARK_RAN', NULL, NULL, '3.6.2', '0330647438')
2018-10-23 22:37:27  INFO: Marking ChangeSet: changelog-4.0::changelog-4.0-pg::author ran despite precondition failure due to onFail='MARK_RAN': 
          ./schema/changelog-master.xml : Not precondition failed

2018-10-23 22:37:27  INFO: INSERT INTO traccar.DATABASECHANGELOG (ID, AUTHOR, FILENAME, DATEEXECUTED, ORDEREXECUTED, MD5SUM, `DESCRIPTION`, COMMENTS, EXECTYPE, CONTEXTS, LABELS, LIQUIBASE, DEPLOYMENT_ID) VALUES ('changelog-4.0-pg', 'author', 'changelog-4.0', NOW(), 31, '8:9831511507d8ae1d6759c8ccf506a27a', 'update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications; update tableName=notifications', '', 'MARK_RAN', NULL, NULL, '3.6.2', '0330647438')
2018-10-23 22:37:27  INFO: Successfully released change log lock
2018-10-23 22:37:27  INFO: Logging initialized @4248ms to org.eclipse.jetty.util.log.Slf4jLog
2018-10-23 22:37:28  INFO: Operating system name: Linux version: 4.18.14-arch1-1-ARCH architecture: amd64
2018-10-23 22:37:28  INFO: Java runtime name: OpenJDK 64-Bit Server VM vendor: Oracle Corporation version: 10.0.2-ojdkbuild+13
2018-10-23 22:37:28  INFO: Memory limit heap: 4004mb non-heap: 0mb
2018-10-23 22:37:28  INFO: Character encoding: UTF-8 charset: UTF-8
2018-10-23 22:37:28  INFO: Version: 4.1-SNAPSHOT
2018-10-23 22:37:28  INFO: Starting server...
2018-10-23 22:37:28  INFO: jetty-9.4.12.v20180830; built: 2018-08-30T13:59:14.071Z; git: 27208684755d94a92186989f695db2d7b21ebc51; jvm 10.0.2-ojdkbuild+13
2018-10-23 22:37:29  INFO: Started o.t.w.@1068176{/,null,AVAILABLE}
2018-10-23 22:37:29  INFO: DefaultSessionIdManager workerName=node0
2018-10-23 22:37:29  INFO: No SessionScavenger set, using defaults
2018-10-23 22:37:29  INFO: node0 Scavenging every 660000ms
2018-10-23 22:37:30  INFO: Started o.e.j.s.ServletContextHandler@6d7865d6{/,null,AVAILABLE}
2018-10-23 22:37:30  INFO: Started ServerConnector@72f24a84{HTTP/1.1,[http/1.1]}{0.0.0.0:8082}
2018-10-23 22:37:30  INFO: Started @6537ms

I'm hoping all is good now.