Traccar Device Freezing issue

C5roki2 months ago

Log has a lot of cache logs like this one:

2024-08-07 09:34:08  INFO: [T0f833593] connected
2024-08-07 09:34:34  INFO: [T0f833593: h02 < 31.161.158.224] *HQ,7028538743,V1,073431,V,5141.0133,N,00517.4965,E,000.00,000,070824,FFFFFBFF,204,08,34201,5355809,84#
2024-08-07 09:34:34  INFO: Cache add device 31 references 0 - Exception (CacheManager:160 < ConnectionManager:157 < BaseProtocolDecoder:135 < H02ProtocolDecoder:322 < ...)
2024-08-07 09:34:34  INFO: Cache add device 31 references 1 - Exception (CacheManager:160 < NotificationManager:175 < ConnectionManager:250 < BaseProtocolDecoder:182 < ...)
2024-08-07 09:34:34  INFO: Event id: 7028538743, time: 2024-08-07 09:34:34, type: deviceOnline, notifications: 0
2024-08-07 09:34:34  INFO: Cache remove device 31 references 1 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < BaseProtocolDecoder:182 < ...)
2024-08-07 09:34:34  INFO: Position filtered by Invalid filters from device: 7028538743
2024-08-07 09:34:34  INFO: [T0f833593: h02 > 31.161.158.224] *HQ,7028538743,V4,V1,20240807073434#
2024-08-07 09:34:54  INFO: [T0f833593] disconnected

I guess that the 'Exception' part in the Cache logs is to force the logging?

Anton Tananaev2 months ago

Don't see any issue here. What about OsmAnd?

C5roki2 months ago

I see a lot of these 'Cache add device' 'Cache remove device' lines, but I don't see any device freeze yet.
All the devices (but one) are also pretty stationary at the moment, so that could also explain why everything is still working :-)
Tomorrow morning I'll make another trip and hopefully we get useful information out of that.

C5roki2 months ago

Sooner than expected: at 18:20 a device using the h02 protocol froze with error - Cannot invoke "org.traccar.model.Device.getUniqueId()" because "device" is null in the log.

2024-08-07 18:19:30  INFO: [T2c3867a9] connected
2024-08-07 18:19:36  INFO: [Ta3679fae] connected
2024-08-07 18:19:56  INFO: [T2c3867a9: h02 < 77.240.240.235] *HQ,9172416819,V1,161952,A,5141.1576,N,00517.8691,E,000.00,054,070824,FFFFFBFF,204,04,21805,26546985#
2024-08-07 18:19:56  INFO: Cache add device 21 references 2 - Exception (CacheManager:160 < NotificationManager:175 < ConnectionManager:250 < BaseProtocolDecoder:182 < ...)
2024-08-07 18:20:02  INFO: [Ta3679fae: h02 < 31.161.158.247] *HQ,7028538743,V1,161958,A,5141.1227,N,00517.3158,E,000.00,008,070824,FFFFFBFF,204,08,34201,19095328,100#
2024-08-07 18:20:02  INFO: Cache add device 31 references 2 - Exception (CacheManager:160 < NotificationManager:175 < ConnectionManager:250 < BaseProtocolDecoder:182 < ...)
2024-08-07 18:20:24  INFO: Event id: 9172416819, time: 2024-08-07 18:19:13, type: deviceOffline, notifications: 0
2024-08-07 18:20:24  INFO: Cache remove device 21 references 2 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < *:198 < MainEventHandler:72 < ...)
2024-08-07 18:20:24  INFO: Event id: 9172416819, time: 2024-08-07 18:19:56, type: deviceOnline, notifications: 0
2024-08-07 18:20:24  INFO: Cache remove device 21 references 1 - Exception (CacheManager:180 < ConnectionManager:201 < MainEventHandler:72 < ... < WrapperInboundHandler:50 < ...)
2024-08-07 18:20:24  INFO: Cache remove device 21 references 0 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < BaseProtocolDecoder:182 < ...)
2024-08-07 18:20:24  INFO: Event id: 7028538743, time: 2024-08-07 18:20:02, type: deviceOnline, notifications: 0
2024-08-07 18:20:24  INFO: Cache remove device 31 references 2 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < BaseProtocolDecoder:182 < ...)
2024-08-07 18:20:24  INFO: Event id: 7028538743, time: 2024-08-07 18:19:19, type: deviceOffline, notifications: 0
2024-08-07 18:20:24  INFO: Cache remove device 31 references 1 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < *:198 < MainEventHandler:72 < ...)
2024-08-07 18:20:24  INFO: [T2c3867a9] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...)
2024-08-07 18:20:24  INFO: Cache remove device 31 references 0 - Exception (CacheManager:180 < ConnectionManager:201 < MainEventHandler:72 < ... < WrapperInboundHandler:50 < ...)
2024-08-07 18:20:24  INFO: [T2c3867a9] disconnected
2024-08-07 18:20:24  INFO: Cache add device 21 references 0 - Exception (CacheManager:160 < NotificationManager:175 < ConnectionManager:250 < *:198 < MainEventHandler:72 < ...)
2024-08-07 18:20:24  INFO: Cache add device 31 references 0 - Exception (CacheManager:160 < NotificationManager:175 < ProcessingHandler:174 < GeofenceEventHandler:75 < ...)
2024-08-07 18:20:24  INFO: Event id: 9172416819, time: 2024-08-07 18:20:24, type: deviceOffline, notifications: 0
2024-08-07 18:20:24  INFO: Cache remove device 21 references 0 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < *:198 < MainEventHandler:72 < ...)
2024-08-07 18:20:24  INFO: Event id: 7028538743, time: 2024-08-07 18:19:58, type: geofenceEnter, notifications: 1
2024-08-07 18:20:24  INFO: Cache remove device 21 references -1 - Exception (CacheManager:180 < ConnectionManager:201 < MainEventHandler:72 < ... < WrapperInboundHandler:50 < ...)
2024-08-07 18:20:24  INFO: Cache remove device 31 references 0 - Exception (CacheManager:180 < NotificationManager:180 < ProcessingHandler:174 < GeofenceEventHandler:75 < ...)
2024-08-07 18:20:24  INFO: [Ta3679fae] error - Cannot invoke "org.traccar.model.Device.getUniqueId()" because "device" is null - NullPointerException (PositionLogger:50 < ProcessingHandler:181 < PostProcessHandler:64 < ProcessingHandler:180 < ...)
2024-08-07 18:20:24  INFO: [Ta3679fae] disconnected
2024-08-07 18:20:24  INFO: Cache add device 31 references 0 - Exception (CacheManager:160 < NotificationManager:175 < ConnectionManager:250 < *:198 < MainEventHandler:72 < ...)
2024-08-07 18:20:25  INFO: Event id: 7028538743, time: 2024-08-07 18:20:24, type: deviceOffline, notifications: 0
2024-08-07 18:20:25  INFO: Cache remove device 31 references 0 - Exception (CacheManager:180 < NotificationManager:180 < ConnectionManager:250 < *:198 < MainEventHandler:72 < ...)
2024-08-07 18:20:25  INFO: Cache remove device 31 references -1 - Exception (CacheManager:180 < ConnectionManager:201 < MainEventHandler:72 < ... < WrapperInboundHandler:50 < ...)
2024-08-07 18:20:41  INFO: [T9982b8bc] connected
C5roki2 months ago

The device using the osmand protocol froze as well, but after a traccar server restart, it uploaded the missing data.

Anton Tananaev2 months ago

This is very helpful. Looks like we have some race condition. I will investigate more.

C5roki2 months ago

Thanks in advance. Let me know if you need more logging or want to test more.
(Next week will be a bit difficult, I'll have limited access to my Traccar server.)

Anton Tananaev2 months ago

Please try this updated version:

https://www.traccar.org/preview/

C5roki2 months ago

Preliminary verdict: this version works better, no errors in the log yet.

Gathered as many devices I could find and made a trip. All devices are still alive and nicely report their position.
I'll keep this version running the rest of the day, see what happens.

AvlMexico2 months ago

We had the same problem, we run the API to receive only readings and if the back server is working in a timely manner, strangely it does not update in the web interface 6.3 they freeze after a certain time, the browser history is deleted and it works again, for a few minutes, in the “log” file it does not show any connection or new data, when it freezes but in the BACKEND SERVER the data arrives without problem, whatever the report time, in version 6.2 in some models it does the same... not in all!!

C5roki2 months ago

Still no device froze, but there is still a minor issue:
In this case (only seen once) it seems that the device is removed from the cache just before the getCalendarId is invoked.
But as I said, it is not fatal and doesn't freeze devices ... yet

2024-08-08 09:19:33  INFO: [Tb3e6e92a] timed out
2024-08-08 09:19:33  INFO: [Tb3e6e92a] disconnected
2024-08-08 09:19:33  INFO: Cache add device 31 references 2 key java.lang.Object@5bd3c5a3
2024-08-08 09:19:50  INFO: [T8a04250c] connected
2024-08-08 09:20:15  INFO: [T8a04250c: h02 < 62.133.98.150] *HQ,7028538743,V1,072012,A,5125.1265,N,00526.0237,E,000.00,061,080824,FFFFFBFF,204,08,33902,10880308,97#
2024-08-08 09:20:15  INFO: Cache add device 31 references 3 key java.lang.Object@2df66f9
2024-08-08 09:20:30  INFO: Event id: 7028538670, time: 2024-08-08 09:19:16, type: deviceOnline, notifications: 0
2024-08-08 09:20:30  INFO: Event id: 7028538743, time: 2024-08-08 09:19:33, type: deviceOffline, notifications: 0
2024-08-08 09:20:30  INFO: Cache remove device 30 references 1 key java.lang.Object@353b61b2
2024-08-08 09:20:30  INFO: Cache remove device 31 references 2 key java.lang.Object@5bd3c5a3
2024-08-08 09:20:30  INFO: Event id: 7028538743, time: 2024-08-08 09:20:15, type: deviceOnline, notifications: 0
2024-08-08 09:20:30  INFO: Cache remove device 31 references 1 key java.lang.Object@2df66f9
2024-08-08 09:20:30  INFO: Cache remove device 31 references 0 key ConnectionKey[localAddress=/192.168.2.223:55002, remoteAddress=/62.133.98.150:3676]
2024-08-08 09:20:30  INFO: [T8a04250c] error - Cannot invoke "org.traccar.model.Device.getCalendarId()" because "device" is null - NullPointerException (FilterHandler:262 < *:279 < ProcessingHandler:161 < DistanceHandler:74 < ProcessingHandler:161 < ...)
2024-08-08 09:20:30  INFO: [T8a04250c] disconnected
2024-08-08 09:20:30  INFO: Cache add device 31 references 1 key java.lang.Object@70ef7726
2024-08-08 09:20:30  INFO: Event id: 7028538743, time: 2024-08-08 09:20:30, type: deviceOffline, notifications: 0
2024-08-08 09:20:30  INFO: Cache remove device 31 references 0 key java.lang.Object@70ef7726
2024-08-08 09:20:30  INFO: Cache remove device 31 references 0 key ConnectionKey[localAddress=/192.168.2.223:55002, remoteAddress=/62.133.98.150:3677]
tecseguridad2 months ago

Hello have installed the preview, but I am still having the same problem. The difference is that in my case, the devices suddenly went offline and do not reconnect even after restarting the server. This happened when I updated to version 6.3. However, if I point the device to the Traccar demo, it connects immediately. I am sharing a portion of the log where the error 'cannot invoke' is still observed."

2024-08-08 08:58:04  INFO: Operating system name: Linux version: 5.15.0-117-generic architecture: amd64
2024-08-08 08:58:04  INFO: Java runtime name: OpenJDK 64-Bit Server VM vendor: Eclipse Adoptium version: 21.0.4+7-LTS
2024-08-08 08:58:04  INFO: Memory limit heap: 3998mb non-heap: 0mb
2024-08-08 08:58:04  INFO: Character encoding: UTF-8
2024-08-08 08:58:04  INFO: Version: 6.3
2024-08-08 08:58:04  INFO: Starting server...
2024-08-08 08:58:04  INFO: HikariPool-1 - Starting...
2024-08-08 08:58:04  INFO: HikariPool-1 - Added connection com.mysql.cj.jdbc.ConnectionImpl@69ce2f62
2024-08-08 08:58:04  INFO: HikariPool-1 - Start completed.
2024-08-08 08:58:05  INFO: Clearing database change log checksums
2024-08-08 08:58:05  INFO: Successfully acquired change log lock
2024-08-08 08:58:05  INFO: Successfully released change log lock
2024-08-08 08:58:07  INFO: Reading from geosdb.DATABASECHANGELOG
2024-08-08 08:58:07  INFO: Reading from geosdb.DATABASECHANGELOG
2024-08-08 08:58:07  INFO: Reading from geosdb.DATABASECHANGELOG
2024-08-08 08:58:08  INFO: UPDATE SUMMARY
2024-08-08 08:58:08  INFO: Run:                          0
2024-08-08 08:58:08  INFO: Previously run:              49
2024-08-08 08:58:08  INFO: Filtered out:                 0
2024-08-08 08:58:08  INFO: -------------------------------
2024-08-08 08:58:08  INFO: Total change sets:           49

2024-08-08 08:58:08  INFO: Update summary generated
2024-08-08 08:58:08  INFO: Successfully released change log lock
2024-08-08 08:58:08  INFO: Command execution complete
2024-08-08 08:58:08  INFO: Health check enabled with period 480000
2024-08-08 08:58:09  INFO: jetty-11.0.21; built: 2024-05-14T03:19:28.958Z; git: 996cd61addad9cb033e0e3eba6fa3f0fa3dc270d; jvm 21.0.4+7-LTS
2024-08-08 08:58:09  INFO: Started o.t.w.@6f115fc5{/,null,AVAILABLE}
2024-08-08 08:58:09  INFO: Session workerName=node0
2024-08-08 08:58:09  INFO: [U9757b748: gps103 < 186.11.61.126] imei:864895033822287,tracker,240808125808,,F,125808.00,A,3324.44465,S,07031.30755,W,0.012,0;
2024-08-08 08:58:09  INFO: [U9757b748] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:09  WARN: JAXBContext implementation could not be found. WADL feature is disabled.
2024-08-08 08:58:09  INFO: [T069887a1] connected
2024-08-08 08:58:09  INFO: [Tea9016af] connected
2024-08-08 08:58:09  INFO: [U9757b748: gps103 < 186.11.30.94] imei:864895033847946,tracker,240808125808,,F,125808.00,A,3332.79236,S,07047.63946,W,18.018,50.70;
2024-08-08 08:58:09  WARN: Error loading timezone aliases: inStream parameter is null
2024-08-08 08:58:09  WARN: Error loading timezone aliases: inStream parameter is null
2024-08-08 08:58:10  INFO: [U9757b748] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:10  INFO: Started o.e.j.s.ServletContextHandler@16a28e7b{/,null,AVAILABLE}
2024-08-08 08:58:10  INFO: Started ServerConnector@7eef12e2{HTTP/1.1, (http/1.1)}{0.0.0.0:8082}
2024-08-08 08:58:10  INFO: Started Server@799e507{STARTING}[11.0.21,sto=0] @6577ms
2024-08-08 08:58:10  INFO: [Tfd9b8087] connected
2024-08-08 08:58:10  INFO: [U9757b748: gps103 < 186.11.61.133] imei:864895033821594,tracker,240808125808,,F,125808.00,A,3327.64129,S,07045.06145,W,0.036,0;
2024-08-08 08:58:10  INFO: [U9757b748] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:10  INFO: [T6bf409da] connected
2024-08-08 08:58:10  INFO: [T3c1482b2] connected
2024-08-08 08:58:10  INFO: [T3c1482b2: gps103 < 191.126.12.92] ##,imei:864035050561480,A;
2024-08-08 08:58:10  INFO: [T3c1482b2] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:10  INFO: [T3c1482b2] disconnected
2024-08-08 08:58:10  INFO: Cache remove device 716 references 0 key ConnectionKey[localAddress=/85.239.248.229:5001, remoteAddress=/191.126.12.92:14720]
2024-08-08 08:58:10  INFO: [T168301ec] connected
2024-08-08 08:58:10  INFO: [T1e8f9656] connected
2024-08-08 08:58:10  INFO: [T2da84c02] connected
2024-08-08 08:58:10  INFO: [T93b758fb] connected
2024-08-08 08:58:10  INFO: [T93b758fb: gps103 < 191.126.55.197] ##,imei:864035050560144,A;
2024-08-08 08:58:10  INFO: [T15e15d4f] connected
2024-08-08 08:58:10  INFO: [T93b758fb] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:10  INFO: [T93b758fb] disconnected
2024-08-08 08:58:10  INFO: Cache remove device 722 references 0 key ConnectionKey[localAddress=/85.239.248.229:5001, remoteAddress=/191.126.55.197:17856]
2024-08-08 08:58:10  INFO: [T44013702] connected
2024-08-08 08:58:10  INFO: [T6a71d3e6] connected
2024-08-08 08:58:11  INFO: [T1090bfdc] connected
2024-08-08 08:58:11  INFO: [U9757b748: gps103 < 186.11.27.43] imei:864895033850304,tracker,240808125807,,F,125807.00,A,3327.20486,S,07033.60706,W,13.991,350.13;
2024-08-08 08:58:11  INFO: [U9757b748] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:11  INFO: [T6a71d3e6: gps103 < 186.11.56.157] ##,imei:864035050069708,A;
2024-08-08 08:58:11  INFO: [T6a71d3e6] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:11  INFO: [T6a71d3e6] disconnected
2024-08-08 08:58:11  INFO: Cache remove device 564 references 0 key ConnectionKey[localAddress=/85.239.248.229:5001, remoteAddress=/186.11.56.157:33985]
2024-08-08 08:58:11  INFO: [U9757b748: gps103 < 186.11.26.85] 864895033821685;
2024-08-08 08:58:11  INFO: [U9757b748: gps103 > 186.11.26.85] ON
2024-08-08 08:58:11  INFO: [T6cc349f2] connected
2024-08-08 08:58:11  INFO: [U9757b748: gps103 < 186.11.56.179] 864180039268649;
2024-08-08 08:58:11  INFO: [U9757b748: gps103 > 186.11.56.179] ON
2024-08-08 08:58:11  INFO: [T1fc68b68] connected
2024-08-08 08:58:11  INFO: [T133a6b9d] connected
2024-08-08 08:58:11  INFO: [T133a6b9d: gps103 < 191.126.99.141] ##,imei:864035050564286,A;
2024-08-08 08:58:11  INFO: [T133a6b9d] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:11  INFO: [T133a6b9d] disconnected
2024-08-08 08:58:11  INFO: Cache remove device 733 references 0 key ConnectionKey[localAddress=/85.239.248.229:5001, remoteAddress=/191.126.99.141:28976]
2024-08-08 08:58:11  INFO: [Tb5681502] connected
2024-08-08 08:58:12  INFO: [Tea9016af: gt06 < 191.126.99.130] 78780d010868022034184403001251650d0a
2024-08-08 08:58:12  INFO: [U9757b748: gps103 < 186.11.58.173] imei:864893037212241,tracker,240808125810,,F,125810.00,A,3322.11600,S,07030.70494,W,0.024,0;
2024-08-08 08:58:12  INFO: Cache add device 942 references 1 key ConnectionKey[localAddress=/85.239.248.229:5023, remoteAddress=/191.126.99.130:39505]
2024-08-08 08:58:12  INFO: [U9757b748] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:12  INFO: Cache add device 942 references 2 key java.lang.Object@79b9d57b
2024-08-08 08:58:12  INFO: [T45c16138] connected
2024-08-08 08:58:12  INFO: Event id: 868022034184403, time: 2024-08-08 08:58:12, type: deviceOnline, notifications: 0
2024-08-08 08:58:12  INFO: Cache remove device 942 references 1 key java.lang.Object@79b9d57b
2024-08-08 08:58:12  INFO: [Tea9016af: gt06 > 191.126.99.130] 787805010012fbc60d0a
2024-08-08 08:58:12  INFO: [Tf30435f8] connected
2024-08-08 08:58:12  INFO: [Tf30435f8: gps103 < 181.203.124.44] ##,imei:864035051539451,A;
2024-08-08 08:58:12  INFO: Cache add device 843 references 1 key ConnectionKey[localAddress=/85.239.248.229:5001, remoteAddress=/181.203.124.44:41856]
2024-08-08 08:58:12  INFO: Cache add device 843 references 2 key java.lang.Object@42f1c206
2024-08-08 08:58:12  INFO: Event id: 864035051539451, time: 2024-08-08 08:58:12, type: deviceOnline, notifications: 0
2024-08-08 08:58:12  INFO: Cache remove device 843 references 1 key java.lang.Object@42f1c206
2024-08-08 08:58:12  INFO: [Tf30435f8: gps103 > 181.203.124.44] LOAD
2024-08-08 08:58:12  INFO: [T3e30e936] connected
2024-08-08 08:58:12  INFO: [U9757b748: gps103 < 186.11.26.85] imei:864895033821685,tracker,,,L,,,9ca9,,8d26,,,;
2024-08-08 08:58:12  INFO: [U9757b748] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:12  INFO: [T069887a1: gt06 < 191.126.99.141] 78780d010869731058366444009581ef0d0a
2024-08-08 08:58:12  INFO: Cache add device 854 references 1 key ConnectionKey[localAddress=/85.239.248.229:5023, remoteAddress=/191.126.99.141:54910]
2024-08-08 08:58:12  INFO: Cache add device 854 references 2 key java.lang.Object@54810058
2024-08-08 08:58:12  INFO: Event id: 869731058366444, time: 2024-08-08 08:58:12, type: deviceOnline, notifications: 0
2024-08-08 08:58:12  INFO: Cache remove device 854 references 1 key java.lang.Object@54810058
2024-08-08 08:58:12  INFO: [T069887a1: gt06 > 191.126.99.141] 7878050100950b710d0a
2024-08-08 08:58:13  INFO: [T2da84c02: gt06 < 191.126.99.130] 78780d010869731058218918006e556a0d0a
2024-08-08 08:58:13  INFO: Cache add device 848 references 1 key ConnectionKey[localAddress=/85.239.248.229:5023, remoteAddress=/191.126.99.130:5535]
2024-08-08 08:58:13  INFO: Cache add device 848 references 2 key java.lang.Object@2c88cdab
2024-08-08 08:58:13  INFO: Event id: 869731058218918, time: 2024-08-08 08:58:13, type: deviceOnline, notifications: 0
2024-08-08 08:58:13  INFO: Cache remove device 848 references 1 key java.lang.Object@2c88cdab
2024-08-08 08:58:13  INFO: [T2da84c02: gt06 > 191.126.99.130] 78780501006e422d0d0a
2024-08-08 08:58:13  INFO: [Tfaf13642] connected
2024-08-08 08:58:13  INFO: [Tfaf13642: gps103 < 191.126.137.0] ##,imei:868307060017720,A;
2024-08-08 08:58:13  INFO: Cache add device 791 references 1 key ConnectionKey[localAddress=/85.239.248.229:5001, remoteAddress=/191.126.137.0:55040]
2024-08-08 08:58:13  INFO: Cache add device 791 references 2 key java.lang.Object@cd35cc1
2024-08-08 08:58:13  INFO: Event id: 868307060017720, time: 2024-08-08 08:58:13, type: deviceOnline, notifications: 0
2024-08-08 08:58:13  INFO: Cache remove device 791 references 1 key java.lang.Object@cd35cc1
2024-08-08 08:58:13  INFO: [Tfaf13642: gps103 > 191.126.137.0] LOAD
2024-08-08 08:58:13  INFO: [T919674ba] connected
2024-08-08 08:58:13  INFO: [T6bf409da: gt06 < 191.126.99.151] 78780d0108680220346470450015a9a80d0a
2024-08-08 08:58:13  INFO: Cache add device 967 references 1 key ConnectionKey[localAddress=/85.239.248.229:5023, remoteAddress=/191.126.99.151:13387]
2024-08-08 08:58:13  INFO: Cache add device 967 references 2 key java.lang.Object@45864ff2
2024-08-08 08:58:13  INFO: Event id: 868022034647045, time: 2024-08-08 08:58:13, type: deviceOnline, notifications: 0
2024-08-08 08:58:13  INFO: Cache remove device 967 references 1 key java.lang.Object@45864ff2
2024-08-08 08:58:13  INFO: [T6bf409da: gt06 > 191.126.99.151] 7878050100158f790d0a
2024-08-08 08:58:13  INFO: [T23938d25] connected
2024-08-08 08:58:13  INFO: [T168301ec: gt06 < 191.126.99.153] 78780d010869731058218066007b7be60d0a
2024-08-08 08:58:13  INFO: Cache add device 859 references 1 key ConnectionKey[localAddress=/85.239.248.229:5023, remoteAddress=/191.126.99.153:25614]
2024-08-08 08:58:13  INFO: Cache add device 859 references 2 key java.lang.Object@22609fc9
2024-08-08 08:58:13  INFO: Event id: 869731058218066, time: 2024-08-08 08:58:13, type: deviceOnline, notifications: 0
2024-08-08 08:58:13  INFO: Cache remove device 859 references 1 key java.lang.Object@22609fc9
2024-08-08 08:58:13  INFO: [T168301ec: gt06 > 191.126.99.153] 78780501007b05010d0a
2024-08-08 08:58:13  INFO: [Tfaf13642] error - Connection reset - SocketException (...)
2024-08-08 08:58:13  INFO: [Tfaf13642] disconnected
2024-08-08 08:58:13  INFO: Cache add device 791 references 2 key java.lang.Object@c31e056
2024-08-08 08:58:13  INFO: Event id: 868307060017720, time: 2024-08-08 08:58:13, type: deviceOffline, notifications: 1
2024-08-08 08:58:13  INFO: [Tfd9b8087: gt06 < 191.126.99.152] 78780d010868022034466891009a69590d0a
2024-08-08 08:58:13  INFO: Cache add device 943 references 1 key ConnectionKey[localAddress=/85.239.248.229:5023, remoteAddress=/191.126.99.152:56936]
2024-08-08 08:58:13  INFO: Cache add device 943 references 2 key java.lang.Object@23c438ee
2024-08-08 08:58:13  INFO: Event id: 868022034466891, time: 2024-08-08 08:58:13, type: deviceOnline, notifications: 0
2024-08-08 08:58:13  INFO: Cache remove device 943 references 1 key java.lang.Object@23c438ee
2024-08-08 08:58:13  INFO: [Tfd9b8087: gt06 > 191.126.99.152] 78780501009af3860d0a
2024-08-08 08:58:13  INFO: [T2ec98895] connected
2024-08-08 08:58:13  INFO: [T2ec98895: gps103 < 191.126.171.162] ##,imei:864035050564732,A;
2024-08-08 08:58:13  INFO: [T2ec98895] error - Cannot invoke "Object.getClass()" because "object" is null - NullPointerException (CacheKey:22 < CacheGraph:95 < CacheManager:291 < *:317 < *:164 < ConnectionManager:157 < ...)
2024-08-08 08:58:13  INFO: [T2ec98895] disconnected
Anton Tananaev2 months ago

C5roki thanks for checking. Here's another iteration that should hopefully fix all remaining issues: https://www.traccar.org/preview/.

AvlMexico, this doesn't seem like the same problem as we're discussing here, so I recommend starting a new thread if you want to discuss you issue. Obviously you need to provide all the relevant details, like logs.

tecseguridad, your issue is completely different. It indicates that you have a corrupted database with link to objects that don't exist. You have to fix this in the database.

C5roki2 months ago

Tested the latest preview, but just after the start it gave WARN: Failed to add device cache - ConcurrentModificationException that actually caused one of the devices using the h02 protocol to freeze. I had to restart the server to get a location update on the frozen device.

2024-08-08 17:09:54  INFO: [T7903a987] connected
2024-08-08 17:09:55  INFO: [T23428929] connected
2024-08-08 17:09:56  INFO: [T7903a987: h02 < 31.161.138.229] *HQ,7028538670,V1,150942,A,5125.1238,N,00526.0246,E,000.00,203,080824,FBFFFBFF,204,08,33902,10880288,100#
2024-08-08 17:09:57  INFO: [T23428929: h02 < 188.206.96.15] *HQ,7028538743,V1,150945,A,5125.1214,N,00526.0244,E,000.00,166,080824,FBFFFBFF,204,08,33902,10880288,93#
2024-08-08 17:09:57  INFO: Cache add device 30 references 1 key ConnectionKey[localAddress=/192.168.2.223:55002, remoteAddress=/31.161.138.229:5381]
2024-08-08 17:09:57  INFO: Cache add device 31 references 1 key ConnectionKey[localAddress=/192.168.2.223:55002, remoteAddress=/188.206.96.15:12051]
2024-08-08 17:09:57  INFO: Cache add device 30 references 2 key java.lang.Object@628b98ea
2024-08-08 17:09:57  INFO: Cache add device 31 references 2 key java.lang.Object@1ed7e628
2024-08-08 17:09:57  INFO: Event id: 7028538743, time: 2024-08-08 17:09:57, type: deviceOnline, notifications: 0
2024-08-08 17:09:57  INFO: Event id: 7028538670, time: 2024-08-08 17:09:57, type: deviceOnline, notifications: 0
2024-08-08 17:09:57  INFO: Cache remove device 31 references 1 key java.lang.Object@1ed7e628
2024-08-08 17:09:57  INFO: Cache remove device 30 references 1 key java.lang.Object@628b98ea
2024-08-08 17:09:57  INFO: Cache add device 30 references 2 key 30
2024-08-08 17:09:57  INFO: Cache add device 31 references 2 key 31
2024-08-08 17:09:57  WARN: Failed to add device cache - ConcurrentModificationException (... < CacheNode:32 < CacheGraph:72 < *:77 < ... < CacheManager:106 < MaintenanceEventHandler:41 < ...)
2024-08-08 17:09:57  INFO: [T7903a987] id: 7028538670, time: 2024-08-08 17:09:42, lat: 51.41873, lon: 5.43374, course: 203.0
2024-08-08 17:09:57  INFO: [T7903a987: h02 > 31.161.138.229] *HQ,7028538670,V4,V1,20240808150957#
2024-08-08 17:09:57  INFO: Cache remove device 30 references 1 key 30
2024-08-08 17:10:59  INFO: [T7903a987: h02 < 31.161.138.229] *HQ,7028538670,V1,151055,A,5125.1242,N,00526.0264,E,000.00,203,080824,FFFFFBFF,204,08,33902,10880288,100#
2024-08-08 17:10:59  INFO: Cache add device 30 references 2 key 30
2024-08-08 17:10:59  INFO: [T7903a987] id: 7028538670, time: 2024-08-08 17:10:55, lat: 51.41874, lon: 5.43377, course: 203.0
2024-08-08 17:10:59  INFO: [T7903a987: h02 > 31.161.138.229] *HQ,7028538670,V4,V1,20240808151059#
2024-08-08 17:10:59  INFO: Cache remove device 30 references 1 key 30
2024-08-08 17:10:59  INFO: [T23428929: h02 < 188.206.96.15] *HQ,7028538743,V1,151058,A,5125.1228,N,00526.0240,E,000.00,166,080824,FFFFFBFF,204,08,33902,10880278,93#
2024-08-08 17:12:12  INFO: [T7903a987: h02 < 31.161.138.229] *HQ,7028538670,V1,151208,A,5125.1228,N,00526.0282,E,000.00,203,080824,FFFFFBFF,204,08,33902,10880288,100#
2024-08-08 17:12:12  INFO: Cache add device 30 references 2 key 30
2024-08-08 17:12:12  INFO: [T7903a987] id: 7028538670, time: 2024-08-08 17:12:08, lat: 51.41871, lon: 5.43380, course: 203.0
2024-08-08 17:12:12  INFO: [T7903a987: h02 > 31.161.138.229] *HQ,7028538670,V4,V1,20240808151212#
2024-08-08 17:12:12  INFO: Cache remove device 30 references 1 key 30
2024-08-08 17:12:12  INFO: [T23428929: h02 < 188.206.96.15] *HQ,7028538743,V1,151210,A,5125.1248,N,00526.0252,E,000.00,166,080824,FFFFFBFF,204,08,33902,10880278,93#
2024-08-08 17:13:25  INFO: [T7903a987: h02 < 31.161.138.229] *HQ,7028538670,V1,151321,A,5125.1226,N,00526.0267,E,000.00,203,080824,FFFFFBFF,000,00,0,0,100#
2024-08-08 17:13:25  INFO: Cache add device 30 references 2 key 30
2024-08-08 17:13:25  INFO: [T7903a987] id: 7028538670, time: 2024-08-08 17:13:21, lat: 51.41871, lon: 5.43378, course: 203.0
2024-08-08 17:13:25  INFO: [T7903a987: h02 > 31.161.138.229] *HQ,7028538670,V4,V1,20240808151325#
2024-08-08 17:13:25  INFO: Cache remove device 30 references 1 key 30
2024-08-08 17:13:25  INFO: [T23428929: h02 < 188.206.96.15] *HQ,7028538743,V1,151323,A,5125.1228,N,00526.0195,E,000.48,166,080824,FFFFFBFF,204,08,33902,10880278,93#
2024-08-08 17:14:37  INFO: [T7903a987: h02 < 31.161.138.229] *HQ,7028538670,V1,151433,A,5125.1233,N,00526.0256,E,000.00,203,080824,FFFFFBFF,000,00,0,0,100#
2024-08-08 17:14:37  INFO: Cache add device 30 references 2 key 30
2024-08-08 17:14:37  INFO: [T7903a987] id: 7028538670, time: 2024-08-08 17:14:33, lat: 51.41872, lon: 5.43376, course: 203.0
Anton Tananaev2 months ago

This seems like another unrelated issue, but I also fixed it now.