Traccar Device Freezing issue
Traccar 6.3 in docker has similar issues:
- Trackers with h02 protocol work fine, but now trackers with osmand protocol freeze.
- In docker I got the same
ERROR: LEAK: ByteBuf.release() was not called before it's garbage-collected. See https://netty.io/wiki/reference-counted-objects.html for more information..
Reverting to 6.2 in docker, the trackers with osmand protocol updated the track points since the freeze.
Could it be that the communication with the trackers is interrupted and not reset?
Have you checked logs?
Nothing weird in the logs, in my opinion:
2024-08-05 12:22:19 INFO: Operating system name: Linux version: 5.15.0-1059-raspi architecture: aarch64
2024-08-05 12:22:19 INFO: Java runtime name: OpenJDK 64-Bit Server VM vendor: Ubuntu version: 17.0.12+7-Ubuntu-1ubuntu224.04
2024-08-05 12:22:19 INFO: Memory limit heap: 1024mb non-heap: 0mb
2024-08-05 12:22:19 INFO: Character encoding: US-ASCII
2024-08-05 12:22:19 INFO: Version: 6.3
2024-08-05 12:22:19 INFO: Starting server...
2024-08-05 12:22:20 INFO: HikariPool-1 - Starting...
2024-08-05 12:23:41 INFO: Operating system name: Linux version: 5.15.0-1059-raspi architecture: aarch64
2024-08-05 12:23:42 INFO: Java runtime name: OpenJDK 64-Bit Server VM vendor: Ubuntu version: 17.0.12+7-Ubuntu-1ubuntu224.04
2024-08-05 12:23:42 INFO: Memory limit heap: 1024mb non-heap: 0mb
2024-08-05 12:23:42 INFO: Character encoding: US-ASCII
2024-08-05 12:23:42 INFO: Version: 6.3
2024-08-05 12:23:42 INFO: Starting server...
2024-08-05 12:23:42 INFO: HikariPool-1 - Starting...
2024-08-05 12:23:44 INFO: HikariPool-1 - Added connection com.mysql.cj.jdbc.ConnectionImpl@1bc53649
2024-08-05 12:23:44 INFO: HikariPool-1 - Start completed.
2024-08-05 12:23:45 INFO: Clearing database change log checksums
2024-08-05 12:23:45 INFO: Successfully acquired change log lock
2024-08-05 12:23:46 INFO: Successfully released change log lock
2024-08-05 12:23:58 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-05 12:24:01 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-05 12:24:01 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-05 12:24:01 INFO: UPDATE SUMMARY
2024-08-05 12:24:01 INFO: Run: 0
2024-08-05 12:24:01 INFO: Previously run: 49
2024-08-05 12:24:01 INFO: Filtered out: 0
2024-08-05 12:24:01 INFO: -------------------------------
2024-08-05 12:24:01 INFO: Total change sets: 49
2024-08-05 12:24:01 INFO: Update summary generated
2024-08-05 12:24:01 INFO: Successfully released change log lock
2024-08-05 12:24:01 INFO: Command execution complete
2024-08-05 12:24:06 INFO: jetty-11.0.21; built: 2024-05-14T03:19:28.958Z; git: 996cd61addad9cb033e0e3eba6fa3f0fa3dc270d; jvm 17.0.12+7-Ubuntu-1ubuntu224.04
2024-08-05 12:24:06 INFO: Started o.t.w.@a4d1d2c{/,null,AVAILABLE}
2024-08-05 12:24:06 INFO: Session workerName=node0
2024-08-05 12:24:07 WARN: JAXBContext implementation could not be found. WADL feature is disabled.
2024-08-05 12:24:09 INFO: Started o.e.j.s.ServletContextHandler@7a06cd36{/,null,AVAILABLE}
2024-08-05 12:24:09 INFO: Started ServerConnector@490a51e7{HTTP/1.1, (http/1.1)}{0.0.0.0:8082}
2024-08-05 12:24:09 INFO: Started Server@13209c79{STARTING}[11.0.21,sto=0] @30669ms
2024-08-05 12:24:42 INFO: user: 2, action: login, from: xxx.xxx.xx.xx
2024-08-05 12:25:48 INFO: [T1b2eb178] connected
2024-08-05 12:25:54 INFO: [Td394b01c] connected
2024-08-05 12:25:54 INFO: [Td394b01c: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722860754&lat=51.4186151&lon=5.4337382&speed=0.0&bearing=0.0&altitude=67.69999694824219&accuracy=100.0&batt=84.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 12:25:55 INFO: Event id: tc5235140020666a55, time: 2024-08-05 12:25:55, type: deviceOnline, notifications: 0
2024-08-05 12:25:55 INFO: Event id: tc5235140020666a55, time: 2024-08-05 12:25:54, type: deviceStopped, notifications: 0
2024-08-05 12:25:55 INFO: [Td394b01c] id: tc5235140020666a55, time: 2024-08-05 12:25:54, lat: 51.41862, lon: 5.43374, course: 0.0, accuracy: 100.0
2024-08-05 12:25:55 INFO: [Td394b01c: osmand > 172.17.0.1] HTTP/1.1 200 OK\r\ncontent-length: 0\r\n\r\n
2024-08-05 12:26:18 INFO: [T1b2eb178] timed out
2024-08-05 12:26:18 INFO: [T1b2eb178] disconnected
...
2024-08-05 17:19:44 INFO: [Tbdc0d49f] connected
2024-08-05 17:19:44 INFO: [Tbdc0d49f: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 17:19:57 INFO: [T80489bf9: h02 < 172.17.0.1] *HQ,7028538743,V1,171954,A,5143.6344,N,00518.5879,E,000.00,185,050824,FFFFFBFF,204,08,34202,17057569,68#
2024-08-05 17:20:27 INFO: Event id: 7028538743, time: 2024-08-05 17:19:57, type: deviceOnline, notifications: 0
2024-08-05 17:20:27 INFO: [T675afc09] disconnected
2024-08-05 17:20:27 INFO: Event id: 7028538743, time: 2024-08-05 17:19:14, type: deviceOffline, notifications: 0
2024-08-05 17:20:27 INFO: [Tbdc0d49f] disconnected
2024-08-05 17:20:27 INFO: [T80489bf9] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ...)
2024-08-05 17:20:27 INFO: [T80489bf9] disconnected
2024-08-05 17:20:27 INFO: Event id: 7028538743, time: 2024-08-05 17:20:27, type: deviceOffline, notifications: 0
2024-08-05 17:20:29 INFO: [Tc4c2c0f1] connected
2024-08-05 17:20:29 INFO: [Tc4c2c0f1: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 17:20:44 INFO: [T9aa74a67] connected
2024-08-05 17:20:44 INFO: [Tc4c2c0f1] disconnected
2024-08-05 17:21:00 INFO: [T9aa74a67: h02 < 172.17.0.1] *HQ,7028538743,V1,172056,A,5143.6672,N,00518.5866,E,002.96,358,050824,FFFFFBFF,204,08,34202,17057569,68#
2024-08-05 17:21:00 INFO: Event id: 7028538743, time: 2024-08-05 17:21:00, type: deviceOnline, notifications: 0
2024-08-05 17:21:06 ERROR: LEAK: ByteBuf.release() was not called before it's garbage-collected. See https://netty.io/wiki/reference-counted-objects.html for more information.
Recent access records:
Created at:
io.netty.buffer.PooledByteBufAllocator.newDirectBuffer(PooledByteBufAllocator.java:410)
io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:188)
io.netty.buffer.AbstractByteBufAllocator.directBuffer(AbstractByteBufAllocator.java:179)
io.netty.buffer.AbstractByteBufAllocator.buffer(AbstractByteBufAllocator.java:116)
io.netty.handler.codec.http.HttpObjectEncoder.encodeFullHttpMessage(HttpObjectEncoder.java:328)
io.netty.handler.codec.http.HttpObjectEncoder.encode(HttpObjectEncoder.java:165)
io.netty.handler.codec.http.HttpObjectEncoder.write(HttpObjectEncoder.java:100)
org.traccar.WrapperOutboundHandler.write(WrapperOutboundHandler.java:71)
io.netty.channel.AbstractChannelHandlerContext.invokeWrite0(AbstractChannelHandlerContext.java:895)
io.netty.channel.AbstractChannelHandlerContext.invokeWriteAndFlush(AbstractChannelHandlerContext.java:956)
io.netty.channel.AbstractChannelHandlerContext.write(AbstractChannelHandlerContext.java:982)
io.netty.channel.AbstractChannelHandlerContext.writeAndFlush(AbstractChannelHandlerContext.java:950)
io.netty.channel.AbstractChannelHandlerContext.writeAndFlush(AbstractChannelHandlerContext.java:1000)
io.netty.channel.DefaultChannelPipeline.writeAndFlush(DefaultChannelPipeline.java:1024)
io.netty.channel.AbstractChannel.writeAndFlush(AbstractChannel.java:305)
org.traccar.BaseHttpProtocolDecoder.sendResponse(BaseHttpProtocolDecoder.java:44)
org.traccar.BaseHttpProtocolDecoder.sendResponse(BaseHttpProtocolDecoder.java:34)
org.traccar.protocol.OsmAndProtocolDecoder.decode(OsmAndProtocolDecoder.java:187)
org.traccar.ExtendedObjectDecoder.channelRead(ExtendedObjectDecoder.java:73)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412)
org.traccar.WrapperContext.fireChannelRead(WrapperContext.java:102)
io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103)
org.traccar.WrapperInboundHandler.channelRead(WrapperInboundHandler.java:56)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412)
org.traccar.WrapperContext.fireChannelRead(WrapperContext.java:102)
io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346)
io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318)
org.traccar.WrapperInboundHandler.channelRead(WrapperInboundHandler.java:56)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412)
io.netty.channel.ChannelInboundHandlerAdapter.channelRead(ChannelInboundHandlerAdapter.java:93)
org.traccar.handler.network.StandardLoggingHandler.channelRead(StandardLoggingHandler.java:62)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412)
org.traccar.handler.network.NetworkMessageHandler.channelRead(NetworkMessageHandler.java:36)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412)
io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:289)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412)
io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1407)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440)
io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420)
io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:918)
io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166)
io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788)
io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724)
io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650)
io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562)
io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:994)
io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74)
io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
java.base/java.lang.Thread.run(Thread.java:840)
2024-08-05 17:21:06 INFO: [T9aa74a67: h02 < 172.17.0.1] *HQ,7028538743,V1,172103,A,5143.6740,N,00518.5846,E,003.35,001,050824,FFFFFBFF,204,08,34202,17057569,68#
2024-08-05 17:21:10 INFO: [T9aa74a67: h02 < 172.17.0.1] *HQ,7028538743,V1,172107,A,5143.6774,N,00518.5859,E,002.86,014,050824,FFFFFBFF,204,08,34202,17057569,68#
2024-08-05 17:21:14 INFO: [T05d4d029] connected
2024-08-05 17:21:14 INFO: [T05d4d029: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 17:21:28 INFO: [T9aa74a67: h02 < 172.17.0.1] *HQ,7028538743,V1,172125,A,5143.6833,N,00518.5748,E,000.00,303,050824,FFFFFBFF,204,08,34202,17057569,68#
2024-08-05 17:21:29 INFO: [T05d4d029] disconnected
2024-08-05 17:21:58 INFO: [T9aa74a67] timed out
2024-08-05 17:21:58 INFO: [T9aa74a67] disconnected
2024-08-05 17:21:58 INFO: Event id: 7028538743, time: 2024-08-05 17:21:58, type: deviceOffline, notifications: 0
2024-08-05 17:21:59 INFO: [T62ad669b] connected
2024-08-05 17:22:00 INFO: [T62ad669b: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 17:22:14 INFO: [Te0633715] connected
2024-08-05 17:22:15 INFO: [T62ad669b] disconnected
...
2024-08-05 17:28:02 INFO: Stopping server...
2024-08-05 17:28:15 INFO: Operating system name: Linux version: 5.15.0-1059-raspi architecture: aarch64
2024-08-05 17:28:15 INFO: Java runtime name: OpenJDK 64-Bit Server VM vendor: AdoptOpenJDK version: 11.0.11+9
2024-08-05 17:28:15 INFO: Memory limit heap: 1024mb non-heap: 0mb
2024-08-05 17:28:15 INFO: Character encoding: UTF-8
2024-08-05 17:28:15 INFO: Version: 6.2
2024-08-05 17:28:15 INFO: Starting server...
2024-08-05 17:28:16 INFO: HikariPool-1 - Starting...
2024-08-05 17:28:17 INFO: HikariPool-1 - Added connection com.mysql.cj.jdbc.ConnectionImpl@5467eea4
2024-08-05 17:28:17 INFO: HikariPool-1 - Start completed.
2024-08-05 17:28:18 INFO: Clearing database change log checksums
2024-08-05 17:28:19 INFO: Successfully acquired change log lock
2024-08-05 17:28:19 INFO: Successfully released change log lock
2024-08-05 17:28:30 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-05 17:28:33 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-05 17:28:33 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-05 17:28:33 INFO: UPDATE SUMMARY
2024-08-05 17:28:33 INFO: Run: 0
2024-08-05 17:28:33 INFO: Previously run: 47
2024-08-05 17:28:33 INFO: Filtered out: 0
2024-08-05 17:28:33 INFO: -------------------------------
2024-08-05 17:28:33 INFO: Total change sets: 47
2024-08-05 17:28:33 INFO: Update summary generated
2024-08-05 17:28:33 INFO: Successfully released change log lock
2024-08-05 17:28:33 INFO: Command execution complete
2024-08-05 17:28:37 INFO: jetty-11.0.21; built: 2024-05-14T03:19:28.958Z; git: 996cd61addad9cb033e0e3eba6fa3f0fa3dc270d; jvm 11.0.11+9
2024-08-05 17:28:38 INFO: Started o.t.w.@13bf983d{/,null,AVAILABLE}
2024-08-05 17:28:38 INFO: Session workerName=node0
2024-08-05 17:28:39 WARN: JAXBContext implementation could not be found. WADL feature is disabled.
2024-08-05 17:28:41 INFO: Started o.e.j.s.ServletContextHandler@47384b7a{/,null,AVAILABLE}
2024-08-05 17:28:41 INFO: Started ServerConnector@6ad4b021{HTTP/1.1, (http/1.1)}{0.0.0.0:8082}
2024-08-05 17:28:41 INFO: Started Server@5f2b01af{STARTING}[11.0.21,sto=0] @30371ms
2024-08-05 17:28:52 INFO: [Tc6dacf75] connected
2024-08-05 17:28:53 INFO: [Tc6dacf75: h02 < 172.17.0.1] 2a48512c373032383533383734332c56312c3137323832342c412c353134332e363139352c4e2c30303531382e353631342c452c3030312e34332c3237332c3035303832342c46424646464246462c3230342c30382c33343230322c31373035373536392c363823
2024-08-05 17:28:54 INFO: Event id: 7028538743, time: 2024-08-05 17:28:54, type: deviceOnline, notifications: 0
2024-08-05 17:28:54 INFO: [Tb2cd70bd] connected
2024-08-05 17:28:54 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373531266c61743d35312e34383638333531266c6f6e3d352e343232333136322673706565643d35332e3537323436313935323238353736342662656172696e673d36352e303030303330353137353738313226616c7469747564653d36322e36393939393639343832343231392661636375726163793d312e373526626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:55 INFO: Event id: tc5235140020666a55, time: 2024-08-05 17:28:55, type: deviceOnline, notifications: 0
2024-08-05 17:28:55 INFO: Position filtered by Duplicate filters from device: tc5235140020666a55
2024-08-05 17:28:55 INFO: [Tb2cd70bd: osmand > 172.17.0.1] 485454502f312e3120323030204f4b0d0a636f6e74656e742d6c656e6774683a20300d0a0d0a
2024-08-05 17:28:55 INFO: user: 2, action: login, from: xxxx:xxxx:xxxx:xxxx:xxxx:xxxx:xxxxx:xxxx
2024-08-05 17:28:55 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373632266c61743d35312e34383738353937266c6f6e3d352e343236333539372673706565643d35332e3830353331373032383932333033352662656172696e673d36382e393939393331333335343439323226616c7469747564653d36362e302661636375726163793d322e3236393030303035333430353736313726626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:55 INFO: [Tc6dacf75: h02 < 172.17.0.1] 2a48512c373032383533383734332c56312c3137323835322c412c353134332e363138322c4e2c30303531382e353730352c452c3030302e30302c3136362c3035303832342c46464646464246462c3230342c30382c33343230322c31373035373536392c363823
2024-08-05 17:28:57 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:19:22, lat: 51.48786, lon: 5.42636, speed: 53.8, course: 69.0, accuracy: 2.3
2024-08-05 17:28:57 INFO: Event id: 7028538743, time: 2024-08-05 17:28:24, type: geofenceExit, notifications: 1
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand > 172.17.0.1] 485454502f312e3120323030204f4b0d0a636f6e74656e742d6c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373734266c61743d35312e34383936383136266c6f6e3d352e343239373637322673706565643d35302e31383631393535393530363232352662656172696e673d31392e303036373237323138363237393326616c7469747564653d37322e302661636375726163793d312e3333323939393934343638363838393626626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tc6dacf75] id: 7028538743, time: 2024-08-05 17:28:24, lat: 51.72699, lon: 5.30936, speed: 1.4, course: 273.0
2024-08-05 17:28:57 INFO: [Tc6dacf75] id: 7028538743, time: 2024-08-05 17:28:52, lat: 51.72697, lon: 5.30951, course: 166.0
2024-08-05 17:28:57 INFO: [Tc6dacf75: h02 > 172.17.0.1] 2a48512c373032383533383734332c56342c56312c323032343038303531373238353423
2024-08-05 17:28:57 INFO: [Tc6dacf75: h02 > 172.17.0.1] 2a48512c373032383533383734332c56342c56312c323032343038303531373238353523
2024-08-05 17:28:57 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:19:34, lat: 51.48968, lon: 5.42977, speed: 50.2, course: 19.0, accuracy: 1.3
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand > 172.17.0.1] 485454502f312e3120323030204f4b0d0a636f6e74656e742d6c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373835266c61743d35312e34393233393031266c6f6e3d352e343239373838352673706565643d35382e3231343839363236363132383533342662656172696e673d3334372e393939353131373138373526616c7469747564653d36392e302661636375726163793d312e31303030303030323338343138353826626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:19:45, lat: 51.49239, lon: 5.42979, speed: 58.2, course: 348.0, accuracy: 1.1
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand > 172.17.0.1] 485454502f312e3120323030204f4b0d0a636f6e74656e742d6c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373936266c61743d35312e34393439373434266c6f6e3d352e343238303336392673706565643d35342e37303338323530373530353739382662656172696e673d3332342e3030353439333136343036323526616c7469747564653d36332e302661636375726163793d312e3026626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:58 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:19:56, lat: 51.49497, lon: 5.42804, speed: 54.7, course: 324.0, accuracy: 1.0
2024-08-05 17:28:58 INFO: [Tb2cd70bd: osmand > 172.17.0.1] 485454502f312e3120323030204f4b0d0a636f6e74656e742d6c656e6774683a20300d0a0d0a
...It looks like everything is working, unless I'm missing something, but clearly you see something different.
The Traccar log looks fine (except for the error message), but during the freeze nothing logged in the MySQL database for the tracker with the osmand protocol.
The MySQL log contains lines like this:
2024-08-05T17:09:02.003831Z 4590 Query INSERT INTO tc_positions(address, protocol, valid, network, latitude, longitude, accuracy, deviceTime, fixTime, speed, serverTime, altitude, course, geofenceIds, deviceId, attributes) VALUES ('Buurschappenlaan 116, ''s-Hertogenbosch, NLD', 'h02', 1, 'null', 51.727085, 5.310005, 0.0, '2024-08-05 17:08:58', '2024-08-05 17:08:58', 1.86, '2024-08-05 17:09:01.965', 0.0, 175.0, '[11,1]', 31, '{"ignition":false,"status":4294966271,"io1":"204","io2":"08","io3":"34202","io4":"17057569","io5":"68","distance":15.433890691534431,"totalDistance":1.730446036254974E7,"motion":true}')If I count the lines with h02 and osmand during the freeze with 6.2 (one hour) and after the restart with 6.3, the osmand lines are not inserted in the database during the freeze:
# number of MySQL log lines with 'osmand' or 'h02' during the freeze with 6.3
% grep -e '^2024-08-05T15:.*Z' /var/log/mysql/mysql.log | grep -c 'osmand'
2
% grep -e '^2024-08-05T15:.*Z' /var/log/mysql/mysql.log | grep -c 'h02'
473
# number of MySQL log lines with 'osmand' or 'h02' after restart with 6.2
% grep -e '^2024-08-05T17:.*Z' /var/log/mysql/mysql.log | grep -c 'osmand'
114
% grep -e '^2024-08-05T17:.*Z' /var/log/mysql/mysql.log | grep -c 'h02'
161When you see lines like this in the log, it means it was successfully written to the database:
id: tc5235140020666a55, time: 2024-08-05 16:19:56, lat: 51.49497, lon: 5.42804, speed: 54.7, course: 324.0, accuracy: 1.0Ok, that's clear.
Looking at the Traccar log for lines with incoming osmand information and the lines right below it, I see that around 16:19:00 the tracker data comes in, but there is no 'id: line' to confirm that the data is written to the database.
After the switch back to 6.2, around 17:28:00, the 'id: line' to confirm that the data is written to the database are back again (though with slightly different tracker data)
Why is the GPS data, that looks fine and (according to the log) is not filtered, not inserted in the database?
2024-08-05 16:18:39 INFO: [Tdd684c4a: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874719&lat=51.48363&lon=5.4102012&speed=53.76688416968536&bearing=72.00003814697266&altitude=68.0&accuracy=1.5&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:18:39 INFO: [Tdd684c4a] id: tc5235140020666a55, time: 2024-08-05 16:18:39, lat: 51.48363, lon: 5.41020, speed: 53.8, course: 72.0, accuracy: 1.5
--
2024-08-05 16:18:50 INFO: [Tdd684c4a: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874729&lat=51.4844831&lon=5.4143893&speed=54.11709553548431&bearing=69.00069427490234&altitude=68.0&accuracy=1.7630000114440918&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:18:50 INFO: [Tdd684c4a] id: tc5235140020666a55, time: 2024-08-05 16:18:49, lat: 51.48448, lon: 5.41439, speed: 54.1, course: 69.0, accuracy: 1.8
--
2024-08-05 16:19:01 INFO: [Tdd684c4a: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874740&lat=51.4855972&lon=5.4184152&speed=53.747819751182554&bearing=61.00123596191406&altitude=62.69999694824219&accuracy=1.6440000534057617&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:19:01 INFO: [Tdd684c4a] id: tc5235140020666a55, time: 2024-08-05 16:19:00, lat: 51.48560, lon: 5.41842, speed: 53.7, course: 61.0, accuracy: 1.6
--
2024-08-05 16:19:12 INFO: [Tdd684c4a: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:19:16 INFO: [T451e079e: h02 < 172.17.0.1] *HQ,7028538743,V1,161912,A,5129.2153,N,00525.3575,E,053.62,065,050824,FFFFFBFF,204,08,33902,20272927,66#
--
2024-08-05 16:20:25 INFO: [T82e926f9: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:20:25 INFO: [T82e926f9] disconnected
--
2024-08-05 16:20:42 INFO: [T196a8170: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:20:57 INFO: [T196a8170] disconnected
--
2024-08-05 16:21:27 INFO: [Ta8aff33c: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 16:21:37 INFO: [Tee3662ff: globalsat < 172.17.0.1] GSr,30-MJ-SX,354033090800874,0000,5,a280,3,050824,162137,E00524.1807,N5130.8641,0,99,328,9,0.8,14460mV,4282mV,0,2631011,92,90,18,204,04,526D,198040D*1a!\r\n
--
--
2024-08-05 17:27:39 INFO: [T5046fa71: osmand < 172.17.0.1] POST /?id=tc5235140020666a55×tamp=1722874751&lat=51.4868351&lon=5.4223162&speed=53.572461952285764&bearing=65.00003051757812&altitude=62.69999694824219&accuracy=1.75&batt=76.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-05 17:27:54 INFO: [T5046fa71] disconnected
--
2024-08-05 17:28:54 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373531266c61743d35312e34383638333531266c6f6e3d352e343232333136322673706565643d35332e3537323436313935323238353736342662656172696e673d36352e303030303330353137353738313226616c7469747564653d36322e36393939393639343832343231392661636375726163793d312e373526626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:55 INFO: Event id: tc5235140020666a55, time: 2024-08-05 17:28:55, type: deviceOnline, notifications: 0
--
2024-08-05 17:28:55 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373632266c61743d35312e34383738353937266c6f6e3d352e343236333539372673706565643d35332e3830353331373032383932333033352662656172696e673d36382e393939393331333335343439323226616c7469747564653d36362e302661636375726163793d322e3236393030303035333430353736313726626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:55 INFO: [Tc6dacf75: h02 < 172.17.0.1] 2a48512c373032383533383734332c56312c3137323835322c412c353134332e363138322c4e2c30303531382e353730352c452c3030302e30302c3136362c3035303832342c46464646464246462c3230342c30382c33343230322c31373035373536392c363823
--
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373734266c61743d35312e34383936383136266c6f6e3d352e343239373637322673706565643d35302e31383631393535393530363232352662656172696e673d31392e303036373237323138363237393326616c7469747564653d37322e302661636375726163793d312e3333323939393934343638363838393626626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tc6dacf75] id: 7028538743, time: 2024-08-05 17:28:24, lat: 51.72699, lon: 5.30936, speed: 1.4, course: 273.0
--
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373835266c61743d35312e34393233393031266c6f6e3d352e343239373838352673706565643d35382e3231343839363236363132383533342662656172696e673d3334372e393939353131373138373526616c7469747564653d36392e302661636375726163793d312e31303030303030323338343138353826626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:57 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:19:45, lat: 51.49239, lon: 5.42979, speed: 58.2, course: 348.0, accuracy: 1.1
--
2024-08-05 17:28:57 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734373936266c61743d35312e34393439373434266c6f6e3d352e343238303336392673706565643d35342e37303338323530373530353739382662656172696e673d3332342e3030353439333136343036323526616c7469747564653d36332e302661636375726163793d312e3026626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:58 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:19:56, lat: 51.49497, lon: 5.42804, speed: 54.7, course: 324.0, accuracy: 1.0
--
2024-08-05 17:28:58 INFO: [Tb2cd70bd: osmand < 172.17.0.1] 504f5354202f3f69643d7463353233353134303032303636366135352674696d657374616d703d31373232383734383036266c61743d35312e34393730313431266c6f6e3d352e343235313537312673706565643d35332e363131323830343030363935382662656172696e673d3331372e3939393335393133303835393426616c7469747564653d36302e302661636375726163793d312e3026626174743d37362e3020485454502f312e310d0a436f6e74656e742d547970653a206170706c69636174696f6e2f782d7777772d666f726d2d75726c656e636f6465640d0a557365722d4167656e743a2044616c76696b2f322e312e3020284c696e75783b20553b20416e64726f69642031343b20534d2d4135353642204275696c642f555031412e3233313030352e303037290d0a486f73743a207665726d65696a6c656e2e6e6c3a35353030310d0a436f6e6e656374696f6e3a204b6565702d416c6976650d0a4163636570742d456e636f64696e673a20677a69700d0a436f6e74656e742d4c656e6774683a20300d0a0d0a
2024-08-05 17:28:58 INFO: [Tb2cd70bd] id: tc5235140020666a55, time: 2024-08-05 16:20:06, lat: 51.49701, lon: 5.42516, speed: 53.6, course: 318.0, accuracy: 1.0
--Can you try this version and see if the issue still happens?
Installed and running.
BTW how can you tell from the log that this is a preview version?
you told that you identified an issue with version 6.3
So he is asking you to try the preview build which is available under the link he provided. As I know that will be the next traccar release.
I know, and as Anton asked I unpacked and installed the preview version from the link and running it right now.
But as far as I can see, in the log there is no line that says this a (6.3) preview version. I don't mind, but I have to be careful not to mix up the official and preview release logs in the posts.
2024-08-06 10:56:28 INFO: Operating system name: Linux version: 5.15.0-1059-raspi architecture: aarch64
2024-08-06 10:56:28 INFO: Java runtime name: OpenJDK 64-Bit Server VM vendor: Eclipse Adoptium version: 21.0.4+7-LTS
2024-08-06 10:56:28 INFO: Memory limit heap: 1024mb non-heap: 0mb
2024-08-06 10:56:28 INFO: Character encoding: UTF-8
2024-08-06 10:56:28 INFO: Version: 6.3
2024-08-06 10:56:28 INFO: Starting server...
2024-08-06 10:56:28 INFO: HikariPool-1 - Starting...
2024-08-06 10:56:30 INFO: HikariPool-1 - Added connection com.mysql.cj.jdbc.ConnectionImpl@732bb66d
2024-08-06 10:56:30 INFO: HikariPool-1 - Start completed.
2024-08-06 10:56:31 INFO: Clearing database change log checksums
2024-08-06 10:56:31 INFO: Successfully acquired change log lock
2024-08-06 10:56:32 INFO: Successfully released change log lock
2024-08-06 10:56:42 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-06 10:56:45 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-06 10:56:45 INFO: Reading from traccar.DATABASECHANGELOG
2024-08-06 10:56:45 INFO: UPDATE SUMMARY
2024-08-06 10:56:45 INFO: Run: 0
2024-08-06 10:56:45 INFO: Previously run: 49
2024-08-06 10:56:45 INFO: Filtered out: 0
2024-08-06 10:56:45 INFO: -------------------------------
2024-08-06 10:56:45 INFO: Total change sets: 49
2024-08-06 10:56:45 INFO: Update summary generated
2024-08-06 10:56:45 INFO: Successfully released change log lock
2024-08-06 10:56:45 INFO: Command execution complete
2024-08-06 10:56:49 INFO: jetty-11.0.21; built: 2024-05-14T03:19:28.958Z; git: 996cd61addad9cb033e0e3eba6fa3f0fa3dc270d; jvm 21.0.4+7-LTS
2024-08-06 10:56:49 INFO: Started o.t.w.@58827b5d{/,null,AVAILABLE}
2024-08-06 10:56:50 INFO: Session workerName=node0
2024-08-06 10:56:51 WARN: JAXBContext implementation could not be found. WADL feature is disabled.
2024-08-06 10:56:53 INFO: Started o.e.j.s.ServletContextHandler@5a4a7283{/,null,AVAILABLE}
2024-08-06 10:56:53 INFO: Started ServerConnector@1b77509e{HTTP/1.1, (http/1.1)}{0.0.0.0:8082}
2024-08-06 10:56:53 INFO: Started Server@90b89a5{STARTING}[11.0.21,sto=0] @28007msWe identified the issue we had with gt06 protocol. We thought the issue is with the firmware but we noticed error - Connection reset - SocketException (.. ..) error in the log. So, we tried using different sim provider.
We used 10 devices with the same firmware and 5 of them Used or old ISP and the other 5 used new ISP. The devices with new sim card worked perfectly and the old guys frozen. We keep testing. will see.
About the preview version: same problem. Made a trip, Traccar ran nicely for about 2 hours, but then a tracker with the h02 protocol froze. Switched to 6.2 docker, continued the trip and everything still works after about 4 hours.
I did notice something in the (preview) 6.3 logs that I didn't see in the 6.2 logs:
After
2024-08-06 12:20:40 INFO: [Tfc96dd42] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...)the device froze and no data was logged in the database anymore (no 'id :' lines in the log for this device).
I didn't encounter error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...) in 6.2 logs (the ones I still have).
***
2024-08-06 12:19:43 INFO: [Tfc96dd42] connected
***
2024-08-06 12:19:49 INFO: [T51395dd2] connected
2024-08-06 12:19:49 INFO: [T51395dd2: osmand < 109.37.154.111] POST /?id=tc5235140020666a55×tamp=1722939543&lat=51.6977902&lon=5.1539356&speed=53.689306594276424&bearing=253.0000762939453&altitude=46.0&accuracy=2.0&batt=90.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-06 12:19:55 INFO: [T4f7aa713: h02 < 188.206.76.180] *HQ,7028538670,V1,101952,A,5143.6504,N,00518.6103,E,000.00,062,060824,FFFFFBFF,204,08,34202,17057569,1#
2024-08-06 12:20:01 INFO: [Te34823df] connected
2024-08-06 12:20:01 INFO: [Te34823df: osmand < 77.63.40.172] POST /?id=gl5235140240466s10×tamp=1722939554&lat=51.4750623&lon=5.4174487&speed=0.0&bearing=0.0&altitude=62.89999771118164&accuracy=1000.0&batt=85.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 12; SM-G973F Build/SP1A.210812.016)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
***
2024-08-06 12:20:07 INFO: [Tfc96dd42: h02 < 31.161.157.163] *HQ,7028538743,V1,102006,A,5141.6361,N,00507.8215,E,053.77,256,060824,FFFFFBFF,204,08,34103,1815329,65#
***
2024-08-06 12:20:40 INFO: [Tbf970a9a] connected
2024-08-06 12:20:40 INFO: [Tbf970a9a: osmand < 109.37.154.111] POST /?id=tc5235140020666a55×tamp=1722939543&lat=51.6977902&lon=5.1539356&speed=53.689306594276424&bearing=253.0000762939453&altitude=46.0&accuracy=2.0&batt=90.0 HTTP/1.1\r\nContent-Type: application/x-www-form-urlencoded\r\nUser-Agent: Dalvik/2.1.0 (Linux; U; Android 14; SM-A556B Build/UP1A.231005.007)\r\nHost: xxxxxx.xx:55001\r\nConnection: Keep-Alive\r\nAccept-Encoding: gzip\r\nContent-Length: 0\r\n\r\n
2024-08-06 12:20:40 INFO: Position filtered by Accuracy filters from device: gl5235140240466s10
2024-08-06 12:20:40 INFO: Event id: 7028538743, time: 2024-08-06 12:20:07, type: deviceOnline, notifications: 0
2024-08-06 12:20:40 INFO: [Te34823df: osmand > 77.63.40.172] HTTP/1.1 200 OK\r\ncontent-length: 0\r\n\r\n
2024-08-06 12:20:40 INFO: Event id: 7028538670, time: 2024-08-06 12:19:12, type: deviceOffline, notifications: 0
2024-08-06 12:20:40 INFO: [Te34823df] disconnected
2024-08-06 12:20:40 INFO: Event id: 7028538670, time: 2024-08-06 12:19:55, type: deviceOnline, notifications: 0
2024-08-06 12:20:40 INFO: Event id: 7028538743, time: 2024-08-06 12:19:24, type: deviceOffline, notifications: 0
2024-08-06 12:20:40 INFO: [T51395dd2] disconnected
2024-08-06 12:20:40 INFO: [Tbb6c91cb] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...)
2024-08-06 12:20:40 INFO: [T3fd1f96b] disconnected
2024-08-06 12:20:40 INFO: [Tbb6c91cb] disconnected
***
2024-08-06 12:20:40 INFO: [Tfc96dd42] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...)
***
2024-08-06 12:20:40 INFO: [Td61b423c] timed out
2024-08-06 12:20:40 INFO: [Td61b423c] disconnected
2024-08-06 12:20:40 INFO: [T4f7aa713] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...)
2024-08-06 12:20:40 INFO: [T4f7aa713] disconnected
***
2024-08-06 12:20:40 INFO: [Tfc96dd42] disconnected
***Another version to try:
https://www.traccar.org/preview/
It will have new logging for cache. Search for "Cache" in the logs.
I suspect cache is the cause of the problem.
It's the first time I saw it. As I said, it is probably not related.
At the moment I'm experimenting with Traccar 6.3 in docker. I'll keep you posted on the progress / issues.