GT02A device tracker - receiving HEX data from tracker in serverlog --> not decoded

tw6 years ago

Hi,

I'm having trouble setting up my device tracker and traccar server.
I'm receiving data in HEX format so at least I know my server can be reached. However, the HEX does not seem to be decoded properly?

I've tried the following ports:
5001
5002
5006
5013
5022
5023
2036
5093

None of them without any luck.
If enter the HEX code in the HEX decoder I come to the following:

(037046641676BP01GT02D-TK006-HS-SLW-JLX_V2.5_190718CCID:8931089619059980796F)

and

(037046641676BP05355227046641676190921A5132.7351N44505.8992E000.44423009000.0000000001L00000000)

which makes me believe it is protocol GT02D which seems to be 5023.

However, also then it doesn't seem to be working --> as in, device is not visible in the webgui.

Any help would be appreciated.

Anton Tananaev6 years ago

Correct port is 5002. Not sure how you tried it. Device id is 037046641676.

tw6 years ago

Can you tell me how you determined that it needs to be 5002? Just out of curiosity ;)

With regards to how I've tested:

Situation 1 - local server on qnap

  1. Traccar is running on the server
  2. forwarding all ports as stated above to the NAS
  3. send command to the device to set port to 5002 (server,password,1,dns,5002,0#)
  4. device replies with OK.
  5. Added device ID's in Traccar (037046641676 and 37046641676). Also added to complete IMEI just in case
  6. Check logging and see the HEX codes coming in but without any result

Situation 2 - traccar demo server

  1. send command to the device to set port to 5002 (server,password,1,demo5.traccar.org,5002,0#)
  2. device replies with OK.
  3. Added device ID's in Traccar (037046641676 and 37046641676). Also added to complete IMEI just in case
  4. no result

Both methods I also tried with my phone (android, traccar client) and both work fine.

Any ideas what I'm doing wrong?

Anton Tananaev6 years ago

Where are the logs?

tw6 years ago

Hi Anton,

Thanks for the quick reply. Wasn't able to get logs earlier but here are a few lines from today when I switched on the tracker again.
First entry for the tracker can be found at 20:00:43

ps. I added the device : 27046641676 and 027046641676 but no luck.
If I use my phone, it is shown on traccar. If you want, I can also place logging from that if you want.

2019-09-22 20:00:35 DEBUG: recycle HttpChannelState@18e057c4{s=UPGRADED a=NOT_ASYNC i=true r=IDLE w=false}
2019-09-22 20:00:35 DEBUG: reset HttpParser{s=END,0 of -1}
2019-09-22 20:00:35 DEBUG: END --> START
2019-09-22 20:00:35 DEBUG: HttpChannelOverHttp@1cf7a5d9{r=1,c=false,c=false/false,a=UPGRADED,uri=null,age=0} handle exit, result COMPLETE
2019-09-22 20:00:35 DEBUG: HttpConnection@1d34078::SocketChannelEndPoint@34790249{/192.168.2.227:61175<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=5/600000}{io=1/1,kio=1,kro=1}->WebSocketServerConnection@864a22e[s=ConnectionState@8d9174d[OPENED],f=org.eclipse.jetty.websocket.common.io.AbstractWebSocketConnection$Flusher@6fe0aae2[IDLE][queueSize=0,aggregateSize=-1,terminated=null],g=Generator[SERVER,validating,+rsv1],p=Parser@62d65e5a[ExtensionStack,s=START,c=0,len=0,f=null]] onFillable exit HttpChannelState@18e057c4{s=UPGRADED a=NOT_ASYNC i=true r=IDLE w=false} null
2019-09-22 20:00:35 DEBUG: ReservedThreadExecutor@58f39564{s=4/4,p=0}@227eefda waiting
2019-09-22 20:00:42  INFO: [13d924ad] connected
2019-09-22 20:00:43  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364250303533353532323730343636343136373631393039323241353133322e373432384e30303530352e39313035453030302e313138303033393030302e303030303030303030314c303030303030303029
2019-09-22 20:00:46  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364250303147543032442d544b3030362d48532d534c572d4a4c585f56322e355f313930373138434349443a383933313038393631393035393938303739364629
2019-09-22 20:00:48 DEBUG: HikariPool-1 - Pool stats (total=10, active=0, idle=10, waiting=0)
2019-09-22 20:00:50  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034643553303547363429
2019-09-22 20:00:52  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364252303031393039323241353133322e373430374e30303530352e39313037453030302e313138303034343030302e303030303030303030314c303030303030303029
2019-09-22 20:00:53  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364252303131393039323141353133322e373332394e30303530352e39323235453030302e313136313732313030302e303030303030303030314c303030303030303029
2019-09-22 20:00:54 DEBUG: SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=28964/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=START,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 28964 ms, remaining: 1036 ms
2019-09-22 20:00:55 DEBUG: SocketChannelEndPoint@76b75131{/192.168.2.227:61160<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=21466/30000}{io=1/1,kio=1,kro=1}->HttpConnection@7e1adc91[p=HttpParser{s=START,0 of -1},g=HttpGenerator@326834ba{s=START}]=>HttpChannelOverHttp@2c4dac15{r=7,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 21466 ms, remaining: 8534 ms
2019-09-22 20:00:55 DEBUG: SocketChannelEndPoint@29d8b002{/192.168.2.227:61161<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=20389/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3573ae66[p=HttpParser{s=START,0 of -1},g=HttpGenerator@59c7506a{s=START}]=>HttpChannelOverHttp@2de731e1{r=15,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 20389 ms, remaining: 9611 ms
2019-09-22 20:00:55 DEBUG: SocketChannelEndPoint@54b851b4{/192.168.2.227:61162<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=23443/30000}{io=1/1,kio=1,kro=1}->HttpConnection@32796e58[p=HttpParser{s=START,0 of -1},g=HttpGenerator@24daac52{s=START}]=>HttpChannelOverHttp@5087dffb{r=4,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 23443 ms, remaining: 6557 ms
2019-09-22 20:00:55 DEBUG: SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=START,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 30000 ms, remaining: 0 ms
2019-09-22 20:00:55 DEBUG: SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=START,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout expired
2019-09-22 20:00:55 DEBUG: onFail FillInterest@32e56d80{AC.ReadCB@400fcbe6{HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=START,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}}} - Idle timeout expired: 30000/30000 ms - TimeoutException (...)
2019-09-22 20:00:55 DEBUG: close HttpParser{s=START,0 of -1}
2019-09-22 20:00:55 DEBUG: START --> CLOSE
2019-09-22 20:00:55 DEBUG: HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OPEN,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed {}
2019-09-22 20:00:55 DEBUG: shutdownOutput SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OPEN,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: fillInterested HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: interested FillInterest@32e56d80{AC.ReadCB@400fcbe6{HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 woken up from select, 1/1/4 selected
2019-09-22 20:00:55 DEBUG: changeInterests p=false 1->1 for SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 processing 1 keys, 0 updates
2019-09-22 20:00:55 DEBUG: Queued change org.eclipse.jetty.io.ChannelEndPoint$1@2aab0269 on ManagedSelector@a0bf272{STARTED} id=0 keys=4 selected=1 updates=0
2019-09-22 20:00:55 DEBUG: ignored: WriteFlusher@306e14f4{IDLE}->null - Idle timeout expired: 30000/30000 ms - TimeoutException (...)
2019-09-22 20:00:55 DEBUG: selected 1 sun.nio.ch.SelectionKeyImpl@286325f6 SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} 
2019-09-22 20:00:55 DEBUG: Ignored idle endpoint SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: onSelected 1->0 r=true w=false for SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: task CEP:SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/0,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2019-09-22 20:00:55 DEBUG: ReservedThreadExecutor@58f39564{s=4/4,p=0} tryExecute EatWhatYouKill@124d26ba/SelectorProducer@415d88de/PRODUCING/p=false/QueuedThreadPool[qtp628487793]@2575f671{STARTED,8<=14<=200,i=5,r=4,q=0}[ReservedThreadExecutor@58f39564{s=4/4,p=0}][pc=0,pic=0,pec=5,epc=53]@2019-09-22T20:00:55.719+02:00
2019-09-22 20:00:55 DEBUG: ReservedThreadExecutor@58f39564{s=3/4,p=0}@227eefda offer EatWhatYouKill@124d26ba/SelectorProducer@415d88de/PRODUCING/p=false/QueuedThreadPool[qtp628487793]@2575f671{STARTED,8<=14<=200,i=5,r=4,q=0}[ReservedThreadExecutor@58f39564{s=3/4,p=0}][pc=0,pic=0,pec=5,epc=53]@2019-09-22T20:00:55.72+02:00
2019-09-22 20:00:55 DEBUG: EatWhatYouKill@124d26ba/SelectorProducer@415d88de/IDLE/p=true/QueuedThreadPool[qtp628487793]@2575f671{STARTED,8<=14<=200,i=5,r=4,q=0}[ReservedThreadExecutor@58f39564{s=3/4,p=0}][pc=0,pic=0,pec=5,epc=53]@2019-09-22T20:00:55.721+02:00 m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
2019-09-22 20:00:55 DEBUG: ReservedThreadExecutor@58f39564{s=3/4,p=0}@227eefda task=EatWhatYouKill@124d26ba/SelectorProducer@415d88de/IDLE/p=true/QueuedThreadPool[qtp628487793]@2575f671{STARTED,8<=14<=200,i=5,r=4,q=0}[ReservedThreadExecutor@58f39564{s=3/4,p=0}][pc=0,pic=0,pec=5,epc=53]@2019-09-22T20:00:55.721+02:00
2019-09-22 20:00:55 DEBUG: fillable FillInterest@32e56d80{AC.ReadCB@400fcbe6{HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}}}
2019-09-22 20:00:55 DEBUG: EatWhatYouKill@124d26ba/SelectorProducer@415d88de/IDLE/p=true/QueuedThreadPool[qtp628487793]@2575f671{STARTED,8<=14<=200,i=5,r=4,q=0}[ReservedThreadExecutor@58f39564{s=3/4,p=0}][pc=0,pic=0,pec=5,epc=54]@2019-09-22T20:00:55.721+02:00 tryProduce true
2019-09-22 20:00:55 DEBUG: HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=-,flush=-,to=3/30000}{io=1/0,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@4faaff9a{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2019-09-22 20:00:55 DEBUG: updateable 1
2019-09-22 20:00:55 DEBUG: update org.eclipse.jetty.io.ChannelEndPoint$1@2aab0269
2019-09-22 20:00:55 DEBUG: shutdownInput SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=-,flush=-,to=3/30000}{io=1/0,kio=1,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: Key interests updated 1 -> 0 on SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,OSHUT,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: updates 0
2019-09-22 20:00:55 DEBUG: Key interests updated 0 -> 0 on SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 waiting with 4 keys
2019-09-22 20:00:55 DEBUG: doClose SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: ignored: WriteFlusher@306e14f4{IDLE}->null - ClosedChannelException (...)
2019-09-22 20:00:55 DEBUG: onClose FillInterest@32e56d80{null}
2019-09-22 20:00:55 DEBUG: Wakeup ManagedSelector@a0bf272{STARTED} id=0 keys=4 selected=0 updates=0
2019-09-22 20:00:55 DEBUG: queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@1a59a4f4
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 woken with none selected
2019-09-22 20:00:55 DEBUG: run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@1a59a4f4
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 woken up from select, 0/0/3 selected
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 processing 0 keys, 0 updates
2019-09-22 20:00:55 DEBUG: Destroyed SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=5/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: updateable 0
2019-09-22 20:00:55 DEBUG: updates 0
2019-09-22 20:00:55 DEBUG: onClose HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=6/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: filled -1 HeapByteBuffer@197fbcbb[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 101 Swit...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2019-09-22 20:00:55 DEBUG: Selector sun.nio.ch.EPollSelectorImpl@42f70126 waiting with 3 keys
2019-09-22 20:00:55 DEBUG: ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@1a59a4f4
2019-09-22 20:00:55 DEBUG: atEOF HttpParser{s=CLOSE,0 of -1}
2019-09-22 20:00:55 DEBUG: HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=6/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} filled -1 HeapByteBuffer@197fbcbb[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 101 Swit...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2019-09-22 20:00:55 DEBUG: close SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=7/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: close(null) SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=7/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=8/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@197fbcbb[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 101 Swit...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2019-09-22 20:00:55 DEBUG: parseNext s=CLOSE HeapByteBuffer@197fbcbb[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 101 Swit...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2019-09-22 20:00:55 DEBUG: CLOSE --> CLOSED
2019-09-22 20:00:55 DEBUG: HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=8/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=CLOSED,0 of -1}
2019-09-22 20:00:55 DEBUG: releaseRequestBuffer HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=9/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: shutdownOutput SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=9/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0}
2019-09-22 20:00:55 DEBUG: HttpConnection@400fcbe6::SocketChannelEndPoint@45b2b272{/192.168.2.227:61158<->/192.168.2.100:18082,CLOSED,fill=-,flush=-,to=9/30000}{io=0/0,kio=-1,kro=-1}->HttpConnection@400fcbe6[p=HttpParser{s=CLOSED,0 of -1},g=HttpGenerator@107ec0b7{s=START}]=>HttpChannelOverHttp@450ce6ae{r=6,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@4faaff9a{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2019-09-22 20:00:55 DEBUG: ReservedThreadExecutor@58f39564{s=4/4,p=0}@3c652a02 waiting
2019-09-22 20:00:56  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364252303131393039323141353133322e373335374e30303530352e39313135453030302e303136313832303030302e303030303030303030314c303030303030303029
2019-09-22 20:00:59  INFO: [13d924ad: osmand < 188.216.107.255] HEX: 283032373034363634313637364252303131393039323141353133322e373532324e30303530352e38393837453030302e303136323134313030302e303030303030303030314c303030303030303029
2019-09-22 20:01:01 DEBUG: SocketChannelEndPoint@54b851b4{/192.168.2.227:61162<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@32796e58[p=HttpParser{s=START,0 of -1},g=HttpGenerator@24daac52{s=START}]=>HttpChannelOverHttp@5087dffb{r=4,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 30000 ms, remaining: 0 ms
2019-09-22 20:01:01 DEBUG: SocketChannelEndPoint@54b851b4{/192.168.2.227:61162<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@32796e58[p=HttpParser{s=START,0 of -1},g=HttpGenerator@24daac52{s=START}]=>HttpChannelOverHttp@5087dffb{r=4,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout expired
2019-09-22 20:01:01 DEBUG: onFail FillInterest@4accc735{AC.ReadCB@32796e58{HttpConnection@32796e58::SocketChannelEndPoint@54b851b4{/192.168.2.227:61162<->/192.168.2.100:18082,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@32796e58[p=HttpParser{s=START,0 of -1},g=HttpGenerator@24daac52{s=START}]=>HttpChannelOverHttp@5087dffb{r=4,c=false,c=false/false,a=IDLE,uri=null,age=0}}} - Idle timeout expired: 30000/30000 ms - TimeoutException (...)
2019-09-22 20:01:01 DEBUG: close HttpParser{s=START,0 of -1}
Anton Tananaev6 years ago

Please use default logging level.

tw6 years ago

Hmm, I never changed anything to the logging level. This is default as it came (it's running on a QNAP).
That being said, I will have a look how I can change it.
(I guess you don't want the debug messages then?)

tw6 years ago

This is what I read on traccar website regarding the configuration file:

"logger.level config

Minimum severity of the events to put into the log file. By default the values is 'all' which enables logging of all messages."

Anton Tananaev6 years ago

Description is outdated. Default is here:

https://github.com/traccar/traccar/blob/master/setup/default.xml#L22

tw6 years ago

Ok, new try, see below.

First a strange user seems to connect @ 20:55:57 (userid 046641676) (although this is at my location...)
Then my gps tracker is connecting @20:56:07

2019-09-22 20:45:38  INFO: Started o.e.j.s.ServletContextHandler@7bbcf6f0{/,null,AVAILABLE}
2019-09-22 20:45:38  INFO: Started ServerConnector@10ad20cb{HTTP/1.1,[http/1.1]}{0.0.0.0:18082}
2019-09-22 20:45:38  INFO: Started @39250ms
2019-09-22 20:50:58  INFO: [f13927cc] connected
2019-09-22 20:50:59  INFO: [f13927cc] disconnected
2019-09-22 20:55:56  INFO: [091062cf] connected
2019-09-22 20:55:57  INFO: [091062cf: osmand < 32.133.103.228] HEX: 757365723d3034363634313637363b7077643d3034363634313637363b636d643d6570683b6c61743d35312e353435393b6c6f6e3d352e303938343b706163633d313530306533
2019-09-22 20:56:02  INFO: [091062cf] disconnected
2019-09-22 20:56:06  INFO: [bbfe207a] connected
2019-09-22 20:56:07  INFO: [bbfe207a: osmand < 32.161.144.29] HEX: 283032373034363634313637364250303533353532323730343636343136373631393039323241353133322e373531354e30303530352e39303230453030302e303138353630343030302e303030303030303030314c303030303030303029
2019-09-22 20:56:10  INFO: [bbfe207a: osmand < 32.161.144.29] HEX: 283032373034363634313637364250303147543032442d544b3030362d48532d534c572d4a4c585f56322e355f313930373138434349443a383933313038393631393035393938303739364629
2019-09-22 20:56:10  INFO: [bbfe207a] disconnected
2019-09-22 20:56:15  INFO: [8e4280b3] connected
2019-09-22 20:56:16  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364250303533353532323730343636343136373631393039323241353133322e373530334e30303530352e39303134453030302e313138353631323030302e303030303030303030314c303030303030303029
2019-09-22 20:56:19  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034636353303547363429
2019-09-22 20:56:23  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364250303147543032442d544b3030362d48532d534c572d4a4c585f56322e355f313930373138434349443a383933313038393631393035393938303739364629
2019-09-22 20:56:25  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034633453303547363429
2019-09-22 20:56:28  INFO: user: 2, action: create, object: device, id: 65
2019-09-22 20:56:28  INFO: user: 2, action: link, owner: user, id: 2, property: device, id: 65
2019-09-22 20:56:28  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364252303131393039323241353133322e373338354e30303530352e39303231453030302e343138313133373030302e303030303030303030314c303030303030303029
2019-09-22 20:56:32  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364252303131393039323241353133322e373531384e30303530352e39323639453030302e323138323134333030302e303030303030303030314c303030303030303029
2019-09-22 20:56:34  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364252303131393039323241353133322e373539344e30303530352e38393431453030302e303138343635393030302e303030303030303030314c303030303030303029
2019-09-22 20:56:37  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364252303131393039323241353133322e373536334e30303530352e39303231453030302e303138353431333030302e303030303030303030314c303030303030303029
2019-09-22 20:56:40  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364252303131393039323241353133322e373536344e30303530352e39303139453030302e303138353531333030302e303030303030303030314c303030303030303029
2019-09-22 20:56:43  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364252303131393039323241353133322e373439374e30303530352e39303136453030302e303138353630393030302e303030303030303030314c303030303030303029
2019-09-22 20:56:47  INFO: [585abf66] connected
2019-09-22 20:56:47  INFO: [585abf66: osmand < 77.247.110.243] HEX: 474554202f20485454502f312e310d0a54453a206465666c6174652c677a69703b713d302e330d0a436f6e6e656374696f6e3a2054452c20636c6f73650d0a486f73743a2038372e3231342e3139392e39393a353033380d0a557365722d4167656e743a206c69627777772d7065726c2f362e33390d0a0d0a
2019-09-22 20:56:47  INFO: [585abf66: osmand > 77.247.110.243] HEX: 485454502f312e31203430302042616420526571756573740d0a636f6e74656e742d6c656e6774683a20300d0a0d0a
2019-09-22 20:56:47  INFO: [585abf66] disconnected
2019-09-22 20:56:56  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034643553303847363429
2019-09-22 20:57:27  INFO: [8e4280b3: osmand < 89.210.43.121] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034643553303847363429
2019-09-22 20:57:57  INFO: [8e4280b3] disconnected
2019-09-22 20:58:32  INFO: [412d7c52] connected
2019-09-22 20:58:33  INFO: [412d7c52: osmand < 31.161.159.153] HEX: 283032373034363634313637364250303533353532323730343636343136373631393039323241353133322e373536334e30303530352e39303431453030302e303138353832393030302e303030303030303030314c303030303030303029
2019-09-22 20:58:36  INFO: [412d7c52: osmand < 31.161.159.153] HEX: 283032373034363634313637364250303147543032442d544b3030362d48532d534c572d4a4c585f56322e355f313930373138434349443a383933313038393631393035393938303739364629
2019-09-22 20:58:39  INFO: [412d7c52: osmand < 31.161.159.153] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034643553303847363429
2019-09-22 20:59:10  INFO: [412d7c52: osmand < 31.161.159.153] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034646553303947363429
2019-09-22 20:59:41  INFO: [412d7c52: osmand < 31.161.159.153] HEX: 283032373034363634313637364250303033353532323730343636343136373648534f3034646553303947363429
tw6 years ago

hmm, this string says something about port 5038..? 

474554202f20485454502f312e310d0a54453a206465666c6174652c677a69703b713d302e330d0a436f6e6e656374696f6e3a2054452c20636c6f73650d0a486f73743a2038372e3231342e3139392e39393a353033380d0a557365722d4167656e743a206c69627777772d7065726c2f362e33390d0a0d0a
Anton Tananaev6 years ago

As you can see from the logs, your device is clearly still sending data to the wrong port.

tw6 years ago

Strange, as when I send the command to the device it reports back with "OK"..

Hmm, not sure how to get it changed.

tw6 years ago

Plus you say from the logs it clearly is sending via the wrong port... You mean that port 5038?
The strange thing is that I have that port blocked on my router (i.e. that port is not forwarded to the NAS). How can it be that it ends up in the traccar.log....

tw6 years ago

well, I did a factory reset on the device but no luck unfortunately