Command do not send to device some time
And last update some times go to 4 min 8 min , when sending command it show (Command queued)
Can you please point out where you see the delay in the log.
I see it from Last Update from web interface (Last Update) column from devices list
if you can see at the end of the log when i will send the command i will get error as below
2021-05-14 05:11:14 INFO: [c0a6644a: teltonika > 185.84.70.23] HEX: 00000000000000170c01050000000f7365746469676f7574203030300d0a010000e6f4
2021-05-14 05:11:15 WARN: [c0a6644a] error - Connection reset by peer - IOException (...)
2021-05-14 05:11:15 INFO: [c0a6644a] disconnectedThe error simply means that device has disconnected from the server. And it happened after the command was already sent, as you can see.
I contacted Teltonika support they said the issue is from the software on the server (traccar) is there any setting can i change to fix this issue my traccar is last version (4.12) and my device is FMC130
And what is the issue exactly?
The issue is that when i send command to the device the command will not reach the devices mostly just some times it will reach the device
And what kind of evidence Teltonika support provided? So far we have evidence from the server log that the command has been sent.
based on there support clarification when send command from traccar there should be a reply from the device (the device will send back replay) they said where is the reply from you software
It sounds like you completely ignored what I said previously.
Sorry Anton , but we complty missup we do not know where is the issue ,I made another test below is the log from server and device please check to see it help to find the issue
2021-05-14 19:53:53 INFO: [9ef3e84f: teltonika > 185.84.70.27] HEX: 00000002
2021-05-14 19:53:53 INFO: [9ef3e84f] id: 864394040099527, time: 2021-05-14 19:53:46, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:53:53 INFO: [9ef3e84f] id: 864394040099527, time: 2021-05-14 19:53:16, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:54:22 INFO: [9ef3e84f] id: 864394040099527, command type: custom sent
2021-05-14 19:54:22 INFO: [9ef3e84f: teltonika > 185.84.70.27] HEX: 00000000000000170c01050000000f7365746469676f7574203131310d0a0100000bc9
2021-05-14 19:54:27 INFO: [9ef3e84f] id: 864394040099527, command type: custom sent
2021-05-14 19:54:27 INFO: [9ef3e84f: teltonika > 185.84.70.27] HEX: 00000000000000170c01050000000f7365746469676f7574203030300d0a010000e6f4
2021-05-14 19:54:52 INFO: [7f13aea1] connected
2021-05-14 19:54:52 INFO: [7f13aea1: teltonika < 185.84.70.23] HEX: 000f383634333934303430303939353237
2021-05-14 19:54:52 INFO: [7f13aea1: teltonika > 185.84.70.23] HEX: 01
2021-05-14 19:54:52 INFO: [7f13aea1: teltonika < 185.84.70.23] HEX: 00000000000000930802000001796bcd4fc000000000000000000000000000000000000e06ef00f0001504c8004502010005b50000b60000423d6b430faf44000003f100000000c700000000100004fdb000000001796bccd6a800000000000000000000000000000000000e06ef00f0001503c8004502010005b50000b60000423dc5430faf44000003f100000000c700000000100004fdb00002000067c6
2021-05-14 19:54:52 INFO: [7f13aea1: teltonika > 185.84.70.23] HEX: 00000002
2021-05-14 19:54:52 INFO: [7f13aea1] id: 864394040099527, time: 2021-05-14 19:54:48, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:54:52 INFO: [7f13aea1] id: 864394040099527, time: 2021-05-14 19:54:17, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:55:34 WARN: [9ef3e84f] error - Connection reset by peer - IOException (...)
2021-05-14 19:55:34 INFO: [9ef3e84f] disconnected
2021-05-14 19:55:52 INFO: [436e9c04] connected
2021-05-14 19:55:52 INFO: [436e9c04: teltonika < 185.84.70.22] HEX: 000f383634333934303430303939353237
2021-05-14 19:55:52 INFO: [436e9c04: teltonika > 185.84.70.22] HEX: 01
2021-05-14 19:55:52 INFO: [436e9c04: teltonika < 185.84.70.22] HEX: 00000000000000930802000001796bce363800000000000000000000000000000000000e06ef00f0001503c8004502010005b50000b60000423d88430faa44000003f100000000c700000000100004fdb000000001796bcdc10800000000000000000000000000000000000e06ef00f0001503c8004502010005b50000b60000423dc5430faa44000003f100000000c700000000100004fdb0000200008bd4
2021-05-14 19:55:52 INFO: [436e9c04: teltonika > 185.84.70.22] HEX: 00000002
2021-05-14 19:55:52 INFO: [436e9c04] id: 864394040099527, time: 2021-05-14 19:55:47, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:55:52 INFO: [436e9c04] id: 864394040099527, time: 2021-05-14 19:55:17, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:56:04 INFO: [436e9c04] id: 864394040099527, command type: custom sent
2021-05-14 19:56:04 INFO: [436e9c04: teltonika > 185.84.70.22] HEX: 00000000000000170c01050000000f7365746469676f7574203131310d0a0100000bc9
2021-05-14 19:56:39 INFO: [436e9c04] id: 864394040099527, command type: custom sent
2021-05-14 19:56:39 INFO: [436e9c04: teltonika > 185.84.70.22] HEX: 00000000000000170c01050000000f7365746469676f7574203030300d0a010000e6f4
2021-05-14 19:56:50 INFO: [e8dad2be] connected
2021-05-14 19:56:50 INFO: [e8dad2be: teltonika < 185.84.70.22] HEX: 000f383634333934303430303939353237
2021-05-14 19:56:50 INFO: [e8dad2be: teltonika > 185.84.70.22] HEX: 01
2021-05-14 19:56:50 INFO: [e8dad2be: teltonika < 185.84.70.22] HEX: 00000000000000930802000001796bcf209800000000000000000000000000000000000e06ef00f0001504c8004502010005b50000b60000423d6a430fa644000003f10000a370c700000000100004fdb000000001796bceab6800000000000000000000000000000000000e06ef00f0001504c8004502010005b50000b60000423dc5430faa44000003f100000000c700000000100004fdb0000200000ace
2021-05-14 19:56:50 INFO: [e8dad2be: teltonika > 185.84.70.22] HEX: 00000002
2021-05-14 19:56:50 INFO: [e8dad2be] id: 864394040099527, time: 2021-05-14 19:56:47, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:56:50 INFO: [e8dad2be] id: 864394040099527, time: 2021-05-14 19:56:17, lat: 0.00000, lon: 0.00000, course: 0.0
2021-05-14 19:57:03 WARN: [436e9c04] error - Connection reset by peer - IOException (...)
2021-05-14 19:57:03 INFO: [436e9c04] disconnectedlog from Teltonika
[2021.05.14 16:55:58]-[OVERSPD] Scenario disabled!
[2021.05.14 16:55:58]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF
[2021.05.14 16:55:58]-[REC.SEND.1] Mode: 0/Home on Stop. Time for next periodic data sending: 11 / 30
[2021.05.14 16:55:58]-[SLEEP] Sleep:0, not allowed! Reason:Disabled by CFG
[2021.05.14 16:55:59]-[LVCAN] Unknown protocol!
[2021.05.14 16:56:01]-[ATCMD] >>[201] +QIURC: "pdpdeact",1
[2021.05.14 16:56:01]-[MODEM.ST] AT dead RSNs: 132, 132, 132, 0, 0, 0, 0, 0, 0, 0
[2021.05.14 16:56:01]-[MODEM.ACTION] GSM Update Reason:Eventual
[2021.05.14 16:56:01]-[ATCMD] <<AT+CPAS
[2021.05.14 16:56:01]-[NETWORK] [IND] unexpected deactivation of pdp detected!
[2021.05.14 16:56:01]-[NETWORK] pdp deactivation detected! account id: 52, cbm result: 0 / ok, error cause: 0
[2021.05.14 16:56:01]-[NETWORK] Flags Cleared, Caller:[959]
[2021.05.14 16:56:01]-[REC.SEND.1] Caller: 0976, queueing recsend1 task job type: 2/close link
[2021.05.14 16:56:01]-[REC.SEND.1] [CHANGE.STATE.0230] Server: 0, waiting => finish
[2021.05.14 16:56:01]-[REC.SEND.1] finish
[2021.05.14 16:56:01]-[SCH] Task eM2M_Tid_link_tmo_checking[11] dropped
[2021.05.14 16:56:01]-[REC.SEND.1] [CHANGE.STATE.1085] Server: 0, finish => finished
[2021.05.14 16:56:01]-[REC.SEND.1] finished
[2021.05.14 16:56:01]-[ATCMD] >>[132] +CPAS: 0
[2021.05.14 16:56:01]-[ATCMD] >>[132] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+CIMI
[2021.05.14 16:56:01]-[ATCMD] >>[168] 418400121298523
[2021.05.14 16:56:01]-[ATCMD] >>[168] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+CREG?
[2021.05.14 16:56:01]-[ATCMD] >>[137] +CREG: 2,1,"00FC","0023068",2
[2021.05.14 16:56:01]-[AT.RSP] Status: 1
[2021.05.14 16:56:01]-[AT.RSP] Area Code: 252
[2021.05.14 16:56:01]-[AT.RSP] Cell ID: 143464
[2021.05.14 16:56:01]-[AT.RSP] Registration Mode: 2
[2021.05.14 16:56:01]-[AT.RSP] Registered To Home Network
[2021.05.14 16:56:01]-[ATCMD] >>[137] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+QGMR
[2021.05.14 16:56:01]-[ATCMD] >>[164] EC21ECGAR06A04M1G_01.004.01.004
[2021.05.14 16:56:01]-[ATCMD] >>[164] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+CSQ
[2021.05.14 16:56:01]-[ATCMD] >>[129] +CSQ: 15,99
[2021.05.14 16:56:01]-[ATCMD] >>[129] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+CFUN?
[2021.05.14 16:56:01]-[ATCMD] >>[149] +CFUN: 1
[2021.05.14 16:56:01]-[ATCMD] >>[149] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+COPS?
[2021.05.14 16:56:01]-[ATCMD] >>[133] +COPS: 0,2,"41840",2
[2021.05.14 16:56:01]-[ATCMD] >>[133] OK
[2021.05.14 16:56:01]-[ATCMD] <<AT+CMGL=4
[2021.05.14 16:56:01]-[ATCMD] >>[135] OK
[2021.05.14 16:56:01]-[MOD.TSK] SMS Read Map:0,0,0,0 Index:32
[2021.05.14 16:56:01]-[MODEM.ACTION] SMS Not Found
[2021.05.14 16:56:01]-[ATCMD] <<AT+QIACT?
[2021.05.14 16:56:01]-[ATCMD] >>[185] OK
[2021.05.14 16:56:01]-[MODEM.ACTION] status done
[2021.05.14 16:56:03]-[ATCMD] >>[201] +CREG: 2
[2021.05.14 16:56:03]-[AT.RSP] Status: 2
[2021.05.14 16:56:03]-[AT.RSP] Not Registered, But searching for OP
[2021.05.14 16:56:03]-[ATCMD] >>[201] +CGEV: NW DETACH
[2021.05.14 16:56:03]-[AT.RSP] Network detached
[2021.05.14 16:56:03]-[TM] Queued Task List -> Tid:7 already queued, skip
[2021.05.14 16:56:03]-[MODEM.ST] AT dead RSNs: 132, 132, 132, 0, 0, 0, 0, 0, 0, 0
[2021.05.14 16:56:03]-[MODEM.ACTION] GSM Update Reason:Eventual
[2021.05.14 16:56:03]-[ATCMD] <<AT+CPAS
[2021.05.14 16:56:03]-[NETWORK] [IND] unexpected deactivation of pdp detected!
[2021.05.14 16:56:03]-[NETWORK] pdp deactivation detected! account id: 52, cbm result: 0 / ok, error cause: 0
[2021.05.14 16:56:03]-[NETWORK] Flags Cleared, Caller:[959]
[2021.05.14 16:56:03]-[ATCMD] >>[132] +CPAS: 0
[2021.05.14 16:56:03]-[ATCMD] >>[132] OK
[2021.05.14 16:56:03]-[ATCMD] <<AT+CIMI
[2021.05.14 16:56:03]-[ATCMD] >>[168] 418400121298523
[2021.05.14 16:56:03]-[ATCMD] >>[168] OK
[2021.05.14 16:56:03]-[ATCMD] <<AT+CREG?
[2021.05.14 16:56:03]-[ATCMD] >>[137] +CREG: 2,2
[2021.05.14 16:56:03]-[AT.RSP] Status: 2
[2021.05.14 16:56:03]-[AT.RSP] Not Registered, But searching for OP
[2021.05.14 16:56:03]-[ATCMD] >>[137] OK
[2021.05.14 16:56:03]-[ATCMD] <<AT+QGMR
[2021.05.14 16:56:03]-[ATCMD] >>[164] EC21ECGAR06A04M1G_01.004.01.004
[2021.05.14 16:56:03]-[ATCMD] >>[164] OK
[2021.05.14 16:56:03]-[ATCMD] <<AT+CSQ
[2021.05.14 16:56:03]-[ATCMD] >>[129] +CSQ: 15,99
[2021.05.14 16:56:03]-[ATCMD] >>[129] OK
[2021.05.14 16:56:03]-[ATCMD] <<AT+CFUN?
[2021.05.14 16:56:03]-[ATCMD] >>[149] +CFUN: 1
[2021.05.14 16:56:03]-[ATCMD] >>[149] OK
[2021.05.14 16:56:03]-[ATCMD] <<AT+COPS?
[2021.05.14 16:56:03]-[ATCMD] >>[133] +COPS: 0
[2021.05.14 16:56:03]-[ATCMD] >>[133] OK
[2021.05.14 16:56:03]-[MODEM.ACTION] Update Eventual
[2021.05.14 16:56:03]-[MODEM.ACTION] op wait tmo:330s
[2021.05.14 16:56:03]-[MODEM.ACTION] CNT CME_ERR:0, CME_ERR_R:0, OP_FAIL:0/11
[2021.05.14 16:56:04]-[ModeChange] [Mode.Change]-> eOperType_Null :0
[2021.05.14 16:56:04]-[ModeChange] Home on Stop profile is updated and reloaded
[2021.05.14 16:56:08]-[OVERSPD] Scenario disabled!
[2021.05.14 16:56:08]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF, TripBT:0
[2021.05.14 16:56:08]-[TRIP] Distance driven: 0 km
[2021.05.14 16:56:08]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF
[2021.05.14 16:56:08]-[REC.SEND.1] Mode: 0/Home on Stop. Time for next periodic data sending: 21 / 30
[2021.05.14 16:56:08]-[SLEEP] Sleep:0, not allowed! Reason:Disabled by CFG
[2021.05.14 16:56:08]-[LiPo] BatState: 1 FSMState: PROTECT ChargerIC: OFF ExtV: 15813 BatV: 4010 BatI: 0
[2021.05.14 16:56:08]-[WD.FUNC] WARNING @ 190:[1] counter:0 Cnt:5 Limit:10800
[2021.05.14 16:56:08]-[WD.FUNC] WARNING @ 190:[2] counter:0 Cnt:5 Limit:10800
[2021.05.14 16:56:08]-[WD.FUNC] WARNING @ 190:[1] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:08]-[WD.FUNC] WARNING @ 190:[2] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:08]-[MODEM.ST] AT dead RSNs: 132, 132, 132, 0, 0, 0, 0, 0, 0, 0
[2021.05.14 16:56:08]-[MODEM.ACTION] GSM Update Reason:Periodic
[2021.05.14 16:56:08]-[ATCMD] <<AT+CPAS
[2021.05.14 16:56:08]-[ATCMD] >>[132] +CPAS: 0
[2021.05.14 16:56:08]-[ATCMD] >>[132] OK
[2021.05.14 16:56:08]-[ATCMD] <<AT+CIMI
[2021.05.14 16:56:08]-[ATCMD] >>[168] 418400121298523
[2021.05.14 16:56:08]-[ATCMD] >>[168] OK
[2021.05.14 16:56:08]-[ATCMD] <<AT+CREG?
[2021.05.14 16:56:08]-[ATCMD] >>[137] +CREG: 2,2
[2021.05.14 16:56:08]-[AT.RSP] Status: 2
[2021.05.14 16:56:08]-[AT.RSP] Not Registered, But searching for OP
[2021.05.14 16:56:08]-[ATCMD] >>[137] OK
[2021.05.14 16:56:08]-[ATCMD] <<AT+QGMR
[2021.05.14 16:56:08]-[ATCMD] >>[164] EC21ECGAR06A04M1G_01.004.01.004
[2021.05.14 16:56:08]-[ATCMD] >>[164] OK
[2021.05.14 16:56:08]-[ATCMD] <<AT+CSQ
[2021.05.14 16:56:08]-[ATCMD] >>[129] +CSQ: 15,99
[2021.05.14 16:56:08]-[ATCMD] >>[129] OK
[2021.05.14 16:56:08]-[ATCMD] <<AT+CFUN?
[2021.05.14 16:56:08]-[ATCMD] >>[149] +CFUN: 1
[2021.05.14 16:56:08]-[ATCMD] >>[149] OK
[2021.05.14 16:56:08]-[ATCMD] <<AT+COPS?
[2021.05.14 16:56:08]-[ATCMD] >>[133] +COPS: 0
[2021.05.14 16:56:08]-[ATCMD] >>[133] OK
[2021.05.14 16:56:08]-[REC.SEND.1] gprs is closed - nothing to do
[2021.05.14 16:56:08]-[REC.SEND.1] WARNING @ 1313:data send ilm sending FAILED
[2021.05.14 16:56:08]-[MODEM.ACTION] op wait tmo:330s
[2021.05.14 16:56:08]-[MODEM.ACTION] CNT CME_ERR:0, CME_ERR_R:0, OP_FAIL:0/11
[2021.05.14 16:56:09]-[LVCAN] Unknown protocol!
[2021.05.14 16:56:10]-[OBDII] State:OFF(0) Lock:0 Protocol:0 (last:0) Vext:15813 MovI:0 MovD:0 Header:0x0 Phy:0
[2021.05.14 16:56:10]-[OBDII] VIN:N/A Avail:0x00000000;0x00000000;0x00000000;0x00000000;A1_C0:0x00000000 PidCnt:0 ICtyp:0 ICver:v0.0.0 CfgMode:1
[2021.05.14 16:56:17]-[REC.SEND.1] Mode: 0/Home on Stop. Period: 30. Starting periodic data sending
[2021.05.14 16:56:17]-[REC.SEND.1] Caller: 1560, queueing recsend1 task job type: 0/records
[2021.05.14 16:56:17]-[REC.SEND.1] [CHANGE.STATE.0226] Server: 0, finished => check rec no
[2021.05.14 16:56:17]-[REC.SEND.1] check rec no
[2021.05.14 16:56:17]-[REC.SEND.1] [CHANGE.STATE.0454] Server: 0, check rec no => finished
[2021.05.14 16:56:17]-[REC.SEND.1] finished
[2021.05.14 16:56:17]-[TRACK] MinPeriod record action [30=<30]
[2021.05.14 16:56:17]-[TRACK] Periodic Record Action!
[2021.05.14 16:56:17]-[REC.GEN] Periodic low priority record saved @ 0x00004477
[2021.05.14 16:56:17]-[REC.GEN] Record Content:
Timestamp: 1621011377000
Priority : 1
Latitude : 0.0000
Longitude: 0.0000
Altitude : 0
Angle : 0
Speed : 0
HDOP : 0.0
SatInUse : 0
GPS Fix : 0
GSpeed : 0, src: NONE
Event AVL ID : 0
IO ID[239]: 0
IO ID[240]: 0
IO ID[ 21]: 4
IO ID[200]: 0
IO ID[ 69]: 2
IO ID[ 1]: 0
IO ID[181]: 0
IO ID[182]: 0
IO ID[ 66]: 15813
IO ID[ 67]: 4010
IO ID[ 68]: 0
IO ID[241]: 0
IO ID[199]: 0
IO ID[ 16]: 327088
Record Size: 131 Bytes
[2021.05.14 16:56:18]-[OVERSPD] Scenario disabled!
[2021.05.14 16:56:18]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF
[2021.05.14 16:56:18]-[REC.SEND.1] Mode: 0/Home on Stop. Time for next periodic data sending: 1 / 30
[2021.05.14 16:56:18]-[SLEEP] Sleep:0, not allowed! Reason:Disabled by CFG
[2021.05.14 16:56:18]-[WD.FUNC] WARNING @ 190:[1] counter:0 Cnt:15 Limit:10800
[2021.05.14 16:56:18]-[WD.FUNC] WARNING @ 190:[2] counter:0 Cnt:15 Limit:10800
[2021.05.14 16:56:18]-[WD.FUNC] WARNING @ 190:[1] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:18]-[WD.FUNC] WARNING @ 190:[2] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:19]-[LVCAN] Unknown protocol!
[2021.05.14 16:56:23]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF, TripBT:0
[2021.05.14 16:56:23]-[TRIP] Distance driven: 0 km
[2021.05.14 16:56:25]-[ADC] Measured Values Table:
[Core Voltage ]: 1.000 (3957) 0
[External V ]: 16.000 (15811) 430
[Battery V ]: 1.727 (4010) 0
[Ch. current ]: 0.071 (0) 0
[AIN1 ]: 16.000 (87) 0
[AIN2 ]: 8.000 (87) 0
[NTC ]: 1.000 (1844) 0
Using Conv Table:FMU1XX CalibVals:1
[2021.05.14 16:56:28]-[OVERSPD] Scenario disabled!
[2021.05.14 16:56:28]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF
[2021.05.14 16:56:28]-[REC.SEND.1] Mode: 0/Home on Stop. Time for next periodic data sending: 11 / 30
[2021.05.14 16:56:28]-[SLEEP] Sleep:0, not allowed! Reason:Disabled by CFG
[2021.05.14 16:56:28]-[WD.FUNC] WARNING @ 190:[1] counter:0 Cnt:25 Limit:10800
[2021.05.14 16:56:28]-[WD.FUNC] WARNING @ 190:[2] counter:0 Cnt:25 Limit:10800
[2021.05.14 16:56:28]-[WD.FUNC] WARNING @ 190:[1] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:28]-[WD.FUNC] WARNING @ 190:[2] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:29]-[LVCAN] Unknown protocol!
[2021.05.14 16:56:38]-[OVERSPD] Scenario disabled!
[2021.05.14 16:56:38]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF, TripBT:0
[2021.05.14 16:56:38]-[TRIP] Distance driven: 0 km
[2021.05.14 16:56:38]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF
[2021.05.14 16:56:38]-[REC.SEND.1] Mode: 0/Home on Stop. Time for next periodic data sending: 21 / 30
[2021.05.14 16:56:38]-[SLEEP] Sleep:0, not allowed! Reason:Disabled by CFG
[2021.05.14 16:56:38]-[LiPo] BatState: 1 FSMState: PROTECT ChargerIC: OFF ExtV: 15813 BatV: 4006 BatI: 0
[2021.05.14 16:56:38]-[WD.FUNC] WARNING @ 190:[1] counter:0 Cnt:35 Limit:10800
[2021.05.14 16:56:38]-[WD.FUNC] WARNING @ 190:[2] counter:0 Cnt:35 Limit:10800
[2021.05.14 16:56:38]-[WD.FUNC] WARNING @ 190:[1] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:38]-[WD.FUNC] WARNING @ 190:[2] counter:1 Cnt:3 Limit:10800
[2021.05.14 16:56:38]-[MODEM.ST] AT dead RSNs: 132, 132, 132, 0, 0, 0, 0, 0, 0, 0
[2021.05.14 16:56:38]-[MODEM.ACTION] GSM Update Reason:Periodic
[2021.05.14 16:56:38]-[ATCMD] <<AT+CPAS
[2021.05.14 16:56:38]-[ATCMD] >>[132] +CPAS: 0
[2021.05.14 16:56:38]-[ATCMD] >>[132] OK
[2021.05.14 16:56:38]-[ATCMD] <<AT+CIMI
[2021.05.14 16:56:38]-[ATCMD] >>[168] 418400121298523
[2021.05.14 16:56:38]-[ATCMD] >>[168] OK
[2021.05.14 16:56:38]-[ATCMD] <<AT+CREG?
[2021.05.14 16:56:38]-[ATCMD] >>[137] +CREG: 2,2
[2021.05.14 16:56:38]-[AT.RSP] Status: 2
[2021.05.14 16:56:38]-[AT.RSP] Not Registered, But searching for OP
[2021.05.14 16:56:38]-[ATCMD] >>[137] OK
[2021.05.14 16:56:38]-[ATCMD] <<AT+QGMR
[2021.05.14 16:56:38]-[ATCMD] >>[164] EC21ECGAR06A04M1G_01.004.01.004
[2021.05.14 16:56:38]-[ATCMD] >>[164] OK
[2021.05.14 16:56:38]-[ATCMD] <<AT+CSQ
[2021.05.14 16:56:38]-[ATCMD] >>[129] +CSQ: 15,99
[2021.05.14 16:56:38]-[ATCMD] >>[129] OK
[2021.05.14 16:56:38]-[ATCMD] <<AT+CFUN?
[2021.05.14 16:56:38]-[ATCMD] >>[149] +CFUN: 1
[2021.05.14 16:56:38]-[ATCMD] >>[149] OK
[2021.05.14 16:56:38]-[ATCMD] <<AT+COPS?
[2021.05.14 16:56:38]-[ATCMD] >>[133] +COPS: 0
[2021.05.14 16:56:38]-[ATCMD] >>[133] OK
[2021.05.14 16:56:38]-[REC.SEND.1] gprs is closed - nothing to do
[2021.05.14 16:56:38]-[REC.SEND.1] WARNING @ 1313:data send ilm sending FAILED
[2021.05.14 16:56:38]-[MODEM.ACTION] op wait tmo:330s
[2021.05.14 16:56:38]-[MODEM.ACTION] CNT CME_ERR:0, CME_ERR_R:0, OP_FAIL:1/11
[2021.05.14 16:56:39]-[LVCAN] Unknown protocol!
[2021.05.14 16:56:40]-[SYS.DIAG]
Code Version:03.27.04
Code Rev:01
IMEI: 864394040099527
HW ver: FMC130
HW mod: 4
HW volt:1119mV
HW tbl: 6
BL ver: 1.7
BLE: 1
NAND: 2
AXL: 2/LIS2DH
BT ADDR:{00:1E:42:24:8F:8D}
Started:2021-5-14 15:51
Uptime: 0d 1h 5min 2s
PWR rsn:PwrVoltage Cnt:0 Abn:0
Mode:Home on Stop
SpcId:1
UpdRes:-65536
Calib: 1
Run: 0
Qual: 0.83
Dbg: 0
Iter: 0,0
G{0.092124;-0.773723;0.626790}
R{-0.895599;-0.339509;-0.287464}
GPS Fix: 0
No fix reason: 3585
GPS state: 1
TTFF: 0s
TTLF: 0s
NoFix: 3902s
Latitude :0.0000
Longitude:0.0000
Altitude :0
FW Ver: AXN_5.10
SN mode: 0
TSYNC: Last Sync:NITZ at 1621011348s, NTP cnt:0, Fails:0
[2021.05.14 16:56:46]-[ATCMD] >>[201] +CTZE: "+12",0,"2021/05/14,16:56:46"
[2021.05.14 16:56:46]-[MODEM.ST] AT dead RSNs: 132, 132, 132, 0, 0, 0, 0, 0, 0, 0
[2021.05.14 16:56:46]-[MODEM.ACTION] GSM Update Reason:Eventual
[2021.05.14 16:56:46]-[ATCMD] <<AT+CPAS
[2021.05.14 16:56:46]-[ATCMD] >>[132] +CPAS: 0
[2021.05.14 16:56:46]-[ATCMD] >>[132] OK
[2021.05.14 16:56:46]-[ATCMD] <<AT+CIMI
[2021.05.14 16:56:46]-[ATCMD] >>[168] 418400121298523
[2021.05.14 16:56:46]-[ATCMD] >>[168] OK
[2021.05.14 16:56:46]-[ATCMD] <<AT+CREG?
[2021.05.14 16:56:46]-[ATCMD] >>[137] +CREG: 2,2
[2021.05.14 16:56:46]-[AT.RSP] Status: 2
[2021.05.14 16:56:46]-[AT.RSP] Not Registered, But searching for OP
[2021.05.14 16:56:46]-[ATCMD] >>[137] OK
[2021.05.14 16:56:46]-[ATCMD] <<AT+QGMR
[2021.05.14 16:56:46]-[ATCMD] >>[164] EC21ECGAR06A04M1G_01.004.01.004
[2021.05.14 16:56:46]-[ATCMD] >>[164] OK
[2021.05.14 16:56:46]-[ATCMD] <<AT+CSQ
[2021.05.14 16:56:46]-[ATCMD] >>[129] +CSQ: 15,99
[2021.05.14 16:56:46]-[ATCMD] >>[129] OK
[2021.05.14 16:56:46]-[ATCMD] <<AT+CFUN?
[2021.05.14 16:56:46]-[ATCMD] >>[149] +CFUN: 1
[2021.05.14 16:56:46]-[ATCMD] >>[149] OK
[2021.05.14 16:56:46]-[ATCMD] <<AT+COPS?
[2021.05.14 16:56:46]-[ATCMD] >>[133] +COPS: 0
[2021.05.14 16:56:46]-[ATCMD] >>[133] OK
[2021.05.14 16:56:46]-[MODEM.ACTION] Update Eventual
[2021.05.14 16:56:46]-[MODEM.ACTION] op wait tmo:330s
[2021.05.14 16:56:46]-[MODEM.ACTION] CNT CME_ERR:0, CME_ERR_R:0, OP_FAIL:2/11
[2021.05.14 16:56:46]-[ATCMD] >>[201] +CTZE: "+12",0,"2021/05/14,16:56:47"
[2021.05.14 16:56:47]-[ATCMD] >>[201] +CREG: 1
[2021.05.14 16:56:47]-[AT.RSP] Status: 1
[2021.05.14 16:56:47]-[AT.RSP] Registered To Home Network
[2021.05.14 16:56:47]-[TM] Queued Task List -> Tid:7 already queued, skip
[2021.05.14 16:56:47]-[MODEM.ST] AT dead RSNs: 132, 132, 132, 0, 0, 0, 0, 0, 0, 0
[2021.05.14 16:56:47]-[MODEM.ACTION] GSM Update Reason:Eventual
[2021.05.14 16:56:47]-[ATCMD] <<AT+CPAS
[2021.05.14 16:56:47]-[ATCMD] >>[132] +CPAS: 0
[2021.05.14 16:56:47]-[ATCMD] >>[132] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+CIMI
[2021.05.14 16:56:47]-[ATCMD] >>[168] 418400121298523
[2021.05.14 16:56:47]-[ATCMD] >>[168] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+CREG?
[2021.05.14 16:56:47]-[ATCMD] >>[137] +CREG: 2,1,"00FC","0023068",2
[2021.05.14 16:56:47]-[AT.RSP] Status: 1
[2021.05.14 16:56:47]-[AT.RSP] Area Code: 252
[2021.05.14 16:56:47]-[AT.RSP] Cell ID: 143464
[2021.05.14 16:56:47]-[AT.RSP] Registration Mode: 2
[2021.05.14 16:56:47]-[AT.RSP] Registered To Home Network
[2021.05.14 16:56:47]-[ATCMD] >>[137] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+QGMR
[2021.05.14 16:56:47]-[ATCMD] >>[164] EC21ECGAR06A04M1G_01.004.01.004
[2021.05.14 16:56:47]-[ATCMD] >>[164] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+CSQ
[2021.05.14 16:56:47]-[ATCMD] >>[129] +CSQ: 16,99
[2021.05.14 16:56:47]-[ATCMD] >>[129] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+CFUN?
[2021.05.14 16:56:47]-[ATCMD] >>[149] +CFUN: 1
[2021.05.14 16:56:47]-[ATCMD] >>[149] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+COPS?
[2021.05.14 16:56:47]-[ATCMD] >>[133] +COPS: 0,2,"41840",2
[2021.05.14 16:56:47]-[ATCMD] >>[133] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+CMGL=4
[2021.05.14 16:56:47]-[ATCMD] >>[135] OK
[2021.05.14 16:56:47]-[MOD.TSK] SMS Read Map:0,0,0,0 Index:32
[2021.05.14 16:56:47]-[MODEM.ACTION] SMS Not Found
[2021.05.14 16:56:47]-[ATCMD] <<AT+QIACT?
[2021.05.14 16:56:47]-[ATCMD] >>[185] OK
[2021.05.14 16:56:47]-[MODEM.ACTION] status done
[2021.05.14 16:56:47]-[ModeChange] Home on Stop profile is updated and reloaded
[2021.05.14 16:56:47]-[REC.SEND.1] Mode: 0/Home on Stop. Period: 30. Starting periodic data sending
[2021.05.14 16:56:47]-[REC.SEND.1] Caller: 1560, queueing recsend1 task job type: 0/records
[2021.05.14 16:56:47]-[REC.SEND.1] [CHANGE.STATE.0226] Server: 0, finished => check rec no
[2021.05.14 16:56:47]-[REC.SEND.1] check rec no
[2021.05.14 16:56:47]-[REC.GEN] .0 Set 1 min required records to send!
[2021.05.14 16:56:47]-[CODEC.APP] Rec found at 0x00004477, sort:0
[2021.05.14 16:56:47]-[TRACK] MinPeriod record action [30=<30]
[2021.05.14 16:56:47]-[TRACK] Periodic Record Action!
[2021.05.14 16:56:47]-[REC.GEN] Periodic low priority record saved @ 0x00004478
[2021.05.14 16:56:47]-[REC.GEN] Record Content:
Timestamp: 1621011407000
Priority : 1
Latitude : 0.0000
Longitude: 0.0000
Altitude : 0
Angle : 0
Speed : 0
HDOP : 0.0
SatInUse : 0
GPS Fix : 0
GSpeed : 0, src: NONE
Event AVL ID : 0
IO ID[239]: 0
IO ID[240]: 0
IO ID[ 21]: 4
IO ID[200]: 0
IO ID[ 69]: 2
IO ID[ 1]: 0
IO ID[181]: 0
IO ID[182]: 0
IO ID[ 66]: 15722
IO ID[ 67]: 4006
IO ID[ 68]: 0
IO ID[241]: 41840
IO ID[199]: 0
IO ID[ 16]: 327088
Record Size: 131 Bytes
[2021.05.14 16:56:47]-[REC.SEND.1] Have enough records to send!
[2021.05.14 16:56:47]-[REC.SEND.1] [CHANGE.STATE.0434] Server: 0, check rec no => check gprs
[2021.05.14 16:56:47]-[REC.SEND.1] check gprs
[2021.05.14 16:56:47]-[REC.SEND.1] gprs is closed, try to open
[2021.05.14 16:56:47]-[ATCMD] <<AT+QIACT?
[2021.05.14 16:56:47]-[ATCMD] >>[185] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+QICSGP=1
[2021.05.14 16:56:47]-[ATCMD] >>[184] +QICSGP: 1,"net.korek.com","korek","korek",2
[2021.05.14 16:56:47]-[ATCMD] >>[184] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+QICSGP=1,1,"net.korek.com","korek","korek",0
[2021.05.14 16:56:47]-[ATCMD] >>[184] OK
[2021.05.14 16:56:47]-[ATCMD] <<AT+QIACT=1
[2021.05.14 16:56:48]-[OVERSPD] Scenario disabled!
[2021.05.14 16:56:48]-[TRIP] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF
[2021.05.14 16:56:48]-[REC.SEND.1] Mode: 0/Home on Stop. Time for next periodic data sending: 1 / 30
[2021.05.14 16:56:48]-[SLEEP] Sleep:0, not allowed! Reason:Disabled by CFG
[2021.05.14 16:56:48]-[WD.FUNC] WARNING @ 190:[1] counter:1 Cnt:5 Limit:10800
[2021.05.14 16:56:48]-[WD.FUNC] WARNING @ 190:[2] counter:1 Cnt:5 Limit:10800
[2021.05.14 16:56:48]-[WD.FUNC] WARNING @ 181:[1] counter:2 Cnt:2 Limit:10800
[2021.05.14 16:56:49]-[LVCAN] Unknown protocol!
[2021.05.14 16:56:50]-[ATCMD] >>[185] OK
[2021.05.14 16:56:50]-[REC.SEND.1] Last GPRS open result: 1
[2021.05.14 16:56:50]-[REC.SEND.1] [CHANGE.STATE.0543] Server: 0, check gprs => check link
[2021.05.14 16:56:50]-[REC.SEND.1] check link
[2021.05.14 16:56:50]-[REC.SEND.1] link is closed, try to open
[2021.05.14 16:56:50]-[NETWORK] [SOCKET.OPEN.0661] Target:0 Events Cleared
[2021.05.14 16:56:50]-[NETWORK] [SOCKET.OPEN.0661] IP not in x.y.z.q format, need to convert
[2021.05.14 16:56:50]-[NETWORK] Getting IP for:gps.iflex-solutions.com
[2021.05.14 16:56:50]-[ATCMD] <<AT+QIDNSGIP=1,"gps.iflex-solutions.com"
[2021.05.14 16:56:50]-[ATCMD] >>[189] OK
[2021.05.14 16:56:50]-[NETWORK] Waiting DNS result: 60 sec
[2021.05.14 16:56:50]-[ATCMD] >>[201] +QIURC: "dnsgip",0,1,1497
[2021.05.14 16:56:50]-[ATCMD] >>[201] +QIURC: "dnsgip","185.253.78.62"
[2021.05.14 16:56:50]-[NETWORK] DNS req result: 1, evt sending: enabled, VR socket[0] state: 4
[2021.05.14 16:56:50]-[NETWORK] Domain:, IP: 185.253.78.62
[2021.05.14 16:56:50]-[NETWORK] Target IP:185.253.78.62 (valid:1)
[2021.05.14 16:56:50]-[NETWORK] [SOCKET.OPEN.0661] Socket target: 0, attempt: 1/3
[2021.05.14 16:56:50]-[ATCMD] <<AT+QICLOSE=0
[2021.05.14 16:56:50]-[ATCMD] >>[188] OK
[2021.05.14 16:56:50]-[ATCMD] <<AT+QIOPEN=1,0,"TCP","185.253.78.62",5028,0,0
[2021.05.14 16:56:50]-[ATCMD] >>[187] OK
[2021.05.14 16:56:50]-[NETWORK] [SOCKET.OPEN.0661] Waiting for create conn result, waiting: 160 sec
[2021.05.14 16:56:50]-[ATCMD] >>[201] +QIOPEN: 0,0
[2021.05.14 16:56:50]-[NETWORK] connection established! acc id: 0, socket id: 0, evt sending: enabled
[2021.05.14 16:56:50]-[NETWORK] VR socket 0 open! real socket id: 0
[2021.05.14 16:56:50]-[REC.SEND.1] link timeout reset for server: 0
[2021.05.14 16:56:50]-[REC.SEND.1] launching link monitoring
[2021.05.14 16:56:50]-[SCH] Task eM2M_Tid_link_tmo_checking[11] has been scheduled. Period: 1s
[2021.05.14 16:56:50]-[REC.SEND.1] [CHANGE.STATE.0720] Server: 0, check link => send imei
[2021.05.14 16:56:50]-[REC.SEND.1] send imei
[2021.05.14 16:56:50]-[ATCMD] <<AT+QISEND=0,17
[2021.05.14 16:56:50]-[ATCMD] >>[191] >QIOPEN: 0,0
[2021.05.14 16:56:50]-[ATCMD] >>[192]
[2021.05.14 16:56:50]-[ATCMD] >>[192] SEND OK
[2021.05.14 16:56:50]-[ATCMD] <<AT+QISEND=0,0
[2021.05.14 16:56:50]-[ATCMD] >>[191] +QISEND: 17,0,17
[2021.05.14 16:56:50]-[ATCMD] >>[191] OK
[2021.05.14 16:56:50]-[NETWORK] [SEND.DATA.0764] Waiting for socket send result, waiting: 10 sec
[2021.05.14 16:56:50]-[REC.SEND.1] imei send OK, waiting 300 sec for imei answer
[2021.05.14 16:56:50]-[REC.SEND.1] link timeout reset for server: 0
[2021.05.14 16:56:50]-[ATCMD] <<AT+QIRD=0,1500
[2021.05.14 16:56:50]-[ATCMD] >>[152] OK
[2021.05.14 16:56:50]-[NETWORK.PARSER] Parsing data from 0 socket, data len: 1
DUMP DATA START:
01
DUMP DATA END
[2021.05.14 16:56:50]-[NETWORK.PARSER] imei answer received!
[2021.05.14 16:56:50]-[REC.SEND.1] server accepted imei
[2021.05.14 16:56:50]-[REC.SEND.1] [CHANGE.STATE.0772] Server: 0, send imei => send records
[2021.05.14 16:56:50]-[REC.SEND.1] send records
[2021.05.14 16:56:50]-[REC.GEN] Server: 0, not all min records send: 1/1
[2021.05.14 16:56:50]-[CODEC.APP] Rec found at 0x00004478, sort:0
[2021.05.14 16:56:50]-[CODEC.APP] Rec found at 0x00004477, sort:0
[2021.05.14 16:56:50]-[ATCMD] <<AT+QISEND=0,159
[2021.05.14 16:56:50]-[ATCMD] >>[191] >K
[2021.05.14 16:56:50]-[ATCMD] >>[192]
[2021.05.14 16:56:50]-[ATCMD] >>[192] SEND OK
[2021.05.14 16:56:50]-[ATCMD] <<AT+QISEND=0,0
[2021.05.14 16:56:50]-[ATCMD] >>[191] +QISEND: 176,17,159
[2021.05.14 16:56:50]-[ATCMD] >>[191] OK
[2021.05.14 16:56:50]-[NETWORK] [SEND.DATA.0844] Waiting for socket send result, waiting: 10 sec
[2021.05.14 16:56:50]-[REC.SEND.1] records packed: 2, waiting 300 sec for answer
[2021.05.14 16:56:50]-[REC.SEND.1] link timeout reset for server: 0
[2021.05.14 16:56:50]-[ATCMD] <<AT+QIRD=0,1500
[2021.05.14 16:56:50]-[ATCMD] >>[152] OK
[2021.05.14 16:56:50]-[NETWORK.PARSER] Parsing data from 0 socket, data len: 4
DUMP DATA START:
00 00 00 02
DUMP DATA END
[2021.05.14 16:56:50]-[NETWORK.PARSER] Server: 0, Record ACK received: 2, expected 2
[2021.05.14 16:56:50]-[CODEC.APP] 0x00004477 record marked for server:0
[2021.05.14 16:56:50]-[CODEC.APP] 0x00004478 record marked for server:0
[2021.05.14 16:56:50]-[REC.SEND.1] server accepted records
[2021.05.14 16:56:50]-[REC.SEND.1] Sent 2 records of min required 1
[2021.05.14 16:56:50]-[REC.SEND.1] Min required records was sent
[2021.05.14 16:56:50]-[REC.GEN] .0 Not enough records to send! Min:1/0I see some messages like "Unknown protocol!" in the Teltonika log. Not sure if it's related. That's why you need to clarify with Teltonika support. I already explained what's happening from the server side.
I have Teltonika FMC130 connect with traccar , when sending command to a device some time it is send to the device some time will not send to the device and may delay , below is the log