Terminal log file Date: 10.03.2018 - 14:25:31 ----------------------------------------------- [2015.01.01 00:00:05]-[SCHED] LvCan I/O elements disabled 14:26:20.554> [2015.01.01 00:00:05]-[ModeChange] eOperType_Null 14:26:20.554> [2015.01.01 00:00:05]-[ModeChange] Unknown on Stop profile is updated and reloaded 14:26:20.614> [2015.01.01 00:00:05]-[ModeChange] Mode changed 14:26:20.614> [2015.01.01 00:00:05]-[AppDatSav] No record has been saved in 1420070404 second(s), MinPeriod: 5 second(s) 14:26:20.614> [2015.01.01 00:00:05]-[AppDatSav] uiMinPeriod (1420070404) record action 14:26:20.614> [2015.01.01 00:00:05]-[AppDatSav] Saving Periodic Record 14:26:20.614> [2015.01.01 00:00:05]-[MODEM] Modem is Initializing (tmo:299) 14:26:20.735> [2015.01.01 00:00:05]-[GPRS] Repeating task after delay 14:26:20.856> [2015.01.01 00:00:05]-[InitModem] Cycle: 1 of 10 14:26:20.856> [2015.01.01 00:00:05]-[InitModem] Modem power up 14:26:20.856> [2015.01.01 00:00:05]-[InitModem] Wait For Ready 14:26:20.856> [2015.01.01 00:00:05]-[MODEM] RX:0, TX:1, CTS:0, RTS:0, ON:0, PWR:1, RESET:1, DTR:0 14:26:20.856> [2015.01.01 00:00:06]-[MODEM] Startup Detected! 14:26:21.358> [2015.01.01 00:00:06]->> +TDRVI: 9,1,5 14:26:21.358> [2015.01.01 00:00:06]-[MODEM] SIM STATUS 2 SIM CARD WITHOUT PIN 14:26:22.045> [2015.01.01 00:00:06]->> +TSIM: 2 14:26:22.045> [2015.01.01 00:00:07]->> +TLOCK: "PN",5,0,"PU",5,0,"PP",5,0,"PC",5,0,"PS",5,0 14:26:22.233> [2015.01.01 00:00:07]-[MODEM] SIM STATUS 7 SIMTK RESET FINISHED 14:26:22.233> [2015.01.01 00:00:07]->> +TSIM: 7 14:26:22.233> [2015.01.01 00:00:07]-[GNSS] $GPTXT,(C)2000-2011 ST Microelectronics*20 14:26:22.858> [2015.01.01 00:00:07]- [GGG.VERSION]-> GGG HW.Parse. 14:26:22.858> [2015.01.01 00:00:07]-[GNSS] $GPTXT,GGG303_002ÿ 14:26:22.858> [2015.01.01 00:00:07]- [GGG.VERSION]-> GGG HW.Parse. 14:26:22.920> [2015.01.01 00:00:07]-[GNSS] Detected at 115200 baudrate 14:26:22.983> [2015.01.01 00:00:08]-[StackCheck] TaskId: 1 Name: GGGCFG Stack: 0x20005398 Free: 1648/1720 @ TaskCreate 14:26:23.608> [2015.01.01 00:00:08]-[GNSSConfig] Task created 14:26:23.608> [2015.01.01 00:00:08]-[GNSSConfig] sending: $PSTMGETPAR,1201*21 14:26:23.608> [2015.01.01 00:00:08]-[GNSS] $PSTMSETPAR,1201,0x00080046*5b 14:26:23.608> [2015.01.01 00:00:08]-[GNSSConfig] SETPAR REPEAT OK 14:26:23.608> [2015.01.01 00:00:09]-[GNSS] $PSTMGETPAR,1201*21 14:26:24.358> [2015.01.01 00:00:09]-[GNSSConfig] CONFIRM OK, value: 80046 14:26:24.358> [2015.01.01 00:00:09]-[GNSSConfig] Result: OK 14:26:24.358> [2015.01.01 00:00:09]-[GNSSConfig] sending: $PSTMGETPAR,1200*20 14:26:25.358> [2015.01.01 00:00:10]-[GNSS] $PSTMGETPAR,1200*20 14:26:25.358> [2015.01.01 00:00:10]-[GNSSConfig] OK 14:26:25.358> [2015.01.01 00:00:12]-[GNSSConfig] REEVALUATE 14:26:27.358> [2015.01.01 00:00:12]-[GNSSConfig] CONFIRM NOT OK, confirm ID: 0, value: 0 14:26:27.358> [2015.01.01 00:00:13]-[GNSSConfig] REPEAT: 1 / 5 14:26:28.358> [2015.01.01 00:00:13]-[GNSSConfig] sending: $PSTMGETPAR,1200*20 14:26:28.420> [2015.01.01 00:00:13]-[GNSS] $PSTMSETPAR,1200,0x19e79654*04 14:26:28.420> [2015.01.01 00:00:13]-[GNSSConfig] SETPAR REPEAT OK 14:26:28.420> [2015.01.01 00:00:14]-[GNSS] $PSTMGETPAR,1200*20 14:26:29.358> [2015.01.01 00:00:14]-[GNSSConfig] CONFIRM OK, value: 19E79654 14:26:29.358> [2015.01.01 00:00:14]-[GNSSConfig] Result: OK 14:26:29.358> [2015.01.01 00:00:14]-[GNSSConfig] NMEA messages list (GGA/GSA/VGT/RMC/GSV) is already configured 14:26:29.421> [2015.01.01 00:00:14]-[GNSSConfig] HYBRID mode is already configured 14:26:29.421> [2015.01.01 00:00:14]-[GNSSConfig] sending: $PSTMGETPAR,1500*27 14:26:29.608> [2015.01.01 00:00:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:26:29.608> [2015.01.01 00:00:14]-[SCHED] lvcan request 14:26:29.608> [2015.01.01 00:00:14]-[AppSend] Successfuly Started 14:26:29.796> [2015.01.01 00:00:14]-[MODEM] Modem is Initializing (tmo:298) 14:26:29.796> [2015.01.01 00:00:14]-[AppSend] Task Finished 14:26:29.796> [2015.01.01 00:00:14]-[SMS Event] Task created 14:26:29.796> [2015.01.01 00:00:15]-[AVL] Record Saved: 14:26:30.046> 14:26:30.046> Timestamp: 1420070415000 14:26:30.046> Priority : 0 14:26:30.046> Latitude : 0 14:26:30.046> Longitude: 0 14:26:30.046> Altitude : 0 14:26:30.046> Angle : 0 14:26:30.046> Speed : 0 14:26:30.046> HDOP : 0.0 14:26:30.046> SatInUse : 0 14:26:30.046> 14:26:30.046> Event ID : 0 14:26:30.046> RecSize : 30 Bytes 14:26:30.296> 14:26:30.296> [2015.01.01 00:00:15]-[RecMng] Found records in block idx 195 14:26:30.296> [2015.01.01 00:00:15]-[RecMng] Recovered addr: 0x00C30000 14:26:30.296> [2015.01.01 00:00:15]-[DeepSleep] dsleep task started 14:26:30.296> [2015.01.01 00:00:15]-[GNSS] $PSTMGETPAR,1500*27 14:26:30.421> [2015.01.01 00:00:15]-[GNSSConfig] OK 14:26:30.421> [2015.01.01 00:00:15]-[MODEM] Modem Phonebook Is Ready Continue 14:26:30.483> [2015.01.01 00:00:15]->> +PBREADY 14:26:30.483> [2015.01.01 00:00:15]-[RecMng] New record address: 0x00076600 len: 30, 102 / 256 14:26:30.483> [2015.01.01 00:00:15]-[NTP] Functionality is disabled 14:26:30.546> [2015.01.01 00:00:15]-[AppDatSav] uiMinPeriod (10) record action 14:26:30.546> [2015.01.01 00:00:15]-[AppDatSav] Saving Periodic Record 14:26:30.546> [2015.01.01 00:00:15]-[AVL] Record Saved: 14:26:30.546> 14:26:30.546> Timestamp: 1420070415050 14:26:30.546> Priority : 0 14:26:30.546> Latitude : 0 14:26:30.546> Longitude: 0 14:26:30.546> Altitude : 0 14:26:30.546> Angle : 0 14:26:30.546> Speed : 0 14:26:30.546> HDOP : 0.0 14:26:30.546> SatInUse : 0 14:26:30.546> 14:26:30.546> Event ID : 0 14:26:30.546> RecSize : 30 Bytes 14:26:30.921> 14:26:30.921> [2015.01.01 00:00:15]-[RecMng] New record address: 0x00076700 len: 30, 103 / 256 14:26:30.921> [2015.01.01 00:00:15]-<< ATE0 14:26:30.921> [2015.01.01 00:00:15]-[MovDetect] state changed 0 -> 1 (mov: 1, speed: -1, tmr: 1) 14:26:30.921> [2015.01.01 00:00:15]->> ATE0 14:26:30.921> 14:26:30.921> [2015.01.01 00:00:15]->> OK 14:26:30.921> [2015.01.01 00:00:15]-<< AT+CMEE=2 14:26:30.921> [2015.01.01 00:00:15]->> OK 14:26:30.921> [2015.01.01 00:00:15]-<< AT+TPSM=2,2000 14:26:30.921> [2015.01.01 00:00:15]->> OK 14:26:30.921> [2015.01.01 00:00:15]-<< AT+CGSN 14:26:30.921> [2015.01.01 00:00:15]-[AT.PARSER] IMEI stored:[356307044112604] 14:26:30.921> [2015.01.01 00:00:15]->> 356307044112604 14:26:30.921> [2015.01.01 00:00:15]->> OK 14:26:30.921> [2015.01.01 00:00:15]-<< AT+TSIMPINCNT 14:26:31.264> [2015.01.01 00:00:15]-[AutoGeo] AutoGeofence is disabled 14:26:31.264> [2015.01.01 00:00:15]->> +TSIMPINCNT: 3,3,10,10 14:26:31.264> [2015.01.01 00:00:15]->> OK 14:26:31.264> [2015.01.01 00:00:16]-<< AT+TGENVER 14:26:31.264> [2015.01.01 00:00:16]->> +TGENVER: "TM11Q_R_01.06.00" 14:26:31.264> [2015.01.01 00:00:16]->> OK 14:26:31.264> [2015.01.01 00:00:16]-<< AT+TGENVER=1 14:26:31.264> [2015.01.01 00:00:16]->> +TGENVER: "TM11Q_R_01.03.06.00_001" 14:26:31.264> [2015.01.01 00:00:16]->> OK 14:26:31.264> [2015.01.01 00:00:16]-<< AT+CGMR 14:26:31.483> [2015.01.01 00:00:16]->> TM11Q 14:26:31.483> [2015.01.01 00:00:16]->> OK 14:26:31.483> [2015.01.01 00:00:16]-<< AT+CCID? 14:26:31.483> [2015.01.01 00:00:16]-[MODEM] CCID 89701011958909679956 14:26:31.483> 14:26:31.483> [2015.01.01 00:00:16]->> +CCID: 89701011958909679956 14:26:31.483> [2015.01.01 00:00:16]->> OK 14:26:31.483> [2015.01.01 00:00:16]-<< AT+CPIN? 14:26:31.608> [2015.01.01 00:00:16]-[ModeChange] eOperType_Null 14:26:31.608> [2015.01.01 00:00:16]-[ModeChange] Unknown on Moving profile is updated and reloaded 14:26:31.608> [2015.01.01 00:00:16]-[ModeChange] Mode changed 14:26:31.608> [2015.01.01 00:00:16]-[MODEM] SIM Card Ready 14:26:31.608> [2015.01.01 00:00:16]->> +CPIN: READY 14:26:31.608> [2015.01.01 00:00:16]->> OK 14:26:31.608> [2015.01.01 00:00:16]-<< AT+CMGF=0 14:26:31.733> [2015.01.01 00:00:16]-<< AT+CMGF=0 14:26:31.733> ]: WORKING, NO PIN REQUIRED 14:26:31.733> [2015.01.01 00:00:16]-<< AT+CREG=2 14:26:31.733> [2015.01.01 00:00:16]->> OK 14:26:31.733> [2015.01.01 00:00:16]->> OK 14:26:31.811> [2015.01.01 00:00:16]-<< AT+CNMI=2,1 14:26:31.858> [2015.01.01 00:00:16]->> OK 14:26:31.921> [2015.01.01 00:00:16]-<< AT+CIMI 14:26:31.921> [2015.01.01 00:00:16]-[AT.PARSER] IMSI Stored:[250015890967995] 14:26:31.983> [2015.01.01 00:00:16]->> 250015890967995 14:26:31.983> [2015.01.01 00:00:16]->> OK 14:26:32.046> [2015.01.01 00:00:17]-<< AT+CGEREP=1,0 14:26:32.046> [2015.01.01 00:00:17]->> OK 14:26:32.108> [2015.01.01 00:00:17]-<< AT+COPS=3,2 14:26:32.171> [2015.01.01 00:00:17]->> OK 14:26:32.233> [2015.01.01 00:00:17]-<< AT+CGREG=2 14:26:32.233> [2015.01.01 00:00:17]->> OK 14:26:32.296> [2015.01.01 00:00:17]-[MODEM] Jamming detection supported. 14:26:32.358> [2015.01.01 00:00:17]-<< AT+TJAMD=50,90 14:26:32.358> [2015.01.01 00:00:17]-[GNSSConfig] REEVALUATE 14:26:32.358> [2015.01.01 00:00:17]-[GNSSConfig] CONFIRM NOT OK, confirm ID: 0, value: 0 14:26:32.421> [2015.01.01 00:00:17]->> OK 14:26:32.421> [2015.01.01 00:00:17]-<< ATS0=0 14:26:32.421> [2015.01.01 00:00:17]->> OK 14:26:32.483> [2015.01.01 00:00:17]-[InitModem] Modem restart and init OK 14:26:32.608> [2015.01.01 00:00:18]-[NMEA Filter] PDOP:99.00, HDOP:99.00, Valid:0 Q: 0, Sats: 0 Retry: 0 FixMode: 1 14:26:33.296> [2015.01.01 00:00:18]-[GNSSConfig] REPEAT: 1 / 5 14:26:33.421> [2015.01.01 00:00:18]-[GNSSConfig] sending: $PSTMGETPAR,1500*27 14:26:33.421> [2015.01.01 00:00:18]-[GNSS] $PSTMSETPAR,1500,GGG303_002ÿ*fa 14:26:33.421> [2015.01.01 00:00:18]- [GGG.VERSION]-> GGG HW.Parse. 14:26:33.421> [2015.01.01 00:00:18]-[GNSSConfig] SETPAR REPEAT OK 14:26:33.421> [2015.01.01 00:00:19]-[GNSS] $PSTMGETPAR,1500*27 14:26:34.421> [2015.01.01 00:00:19]-[GNSSConfig] CONFIRM OK, value: 0 14:26:34.421> [2015.01.01 00:00:19]-[GNSSConfig] Result: OK 14:26:34.421> [2015.01.01 00:00:19]-[GNSSConfig] Task finished 14:26:34.421> [2015.01.01 00:00:20]-[AppDatSav] uiMinPeriod (5) record action 14:26:35.608> [2015.01.01 00:00:20]-[AppDatSav] Saving Periodic Record 14:26:35.608> [2015.01.01 00:00:20]-[AVL] Record Saved: 14:26:35.608> 14:26:35.608> Timestamp: 1420070420000 14:26:35.608> Priority : 0 14:26:35.608> Latitude : 0 14:26:35.608> Longitude: 0 14:26:35.608> Altitude : 0 14:26:35.608> Angle : 0 14:26:35.608> Speed : 0 14:26:35.608> HDOP : 0.0 14:26:35.608> SatInUse : 0 14:26:35.608> 14:26:35.608> Event ID : 0 14:26:35.608> RecSize : 30 Bytes 14:26:35.608> 14:26:35.608> [2015.01.01 00:00:20]-<< AT 14:26:35.608> [2015.01.01 00:00:20]-[RecMng] New record address: 0x00076800 len: 30, 104 / 256 14:26:35.608> [2015.01.01 00:00:20]->> OK 14:26:35.983> [2015.01.01 00:00:21]-[MovDetect] state changed 1 -> 0 (mov: 0, speed: -1, tmr: 0) 14:26:36.608> [2015.01.01 00:00:21]-<< AT+CREG? 14:26:36.671> [2015.01.01 00:00:21]-[MODEM] Not Registered 14:26:36.671> [2015.01.01 00:00:21]->> +CREG: 2,0 14:26:36.671> [2015.01.01 00:00:21]->> OK 14:26:36.749> [2015.01.01 00:00:21]-<< AT 14:26:36.749> [2015.01.01 00:00:21]->> OK 14:26:36.749> [2015.01.01 00:00:21]-<< AT+COPS=2 14:26:36.749> [2015.01.01 00:00:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:26:39.609> [2015.01.01 00:00:24]-[SCHED] lvcan request 14:26:39.609> [2015.01.01 00:00:24]-[MODEM] Modem is Initializing (tmo:297) 14:26:39.796> [2015.01.01 00:00:25]-[AppDatSav] uiMinPeriod (5) record action 14:26:40.609> [2015.01.01 00:00:25]-[AppDatSav] Saving Periodic Record 14:26:40.609> [2015.01.01 00:00:25]-[AVL] Record Saved: 14:26:40.609> 14:26:40.609> Timestamp: 1420070425000 14:26:40.609> Priority : 0 14:26:40.609> Latitude : 0 14:26:40.609> Longitude: 0 14:26:40.609> Altitude : 0 14:26:40.609> Angle : 0 14:26:40.609> Speed : 0 14:26:40.609> HDOP : 0.0 14:26:40.609> SatInUse : 0 14:26:40.609> 14:26:40.609> Event ID : 0 14:26:40.609> RecSize : 30 Bytes 14:26:40.609> 14:26:40.609> [2015.01.01 00:00:25]-[RecMng] New record address: 0x00076900 len: 30, 105 / 256 14:26:40.609> [2015.01.01 00:00:27]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:21[1800] ds18:0[1800] lvcan:0[1800] 14:26:42.796> [2015.01.01 00:00:29]-[NMEA Filter] PDOP:99.00, HDOP:99.00, Valid:0 Q: 0, Sats: 0 Retry: 0 FixMode: 1 14:26:44.296> [2015.01.01 00:00:30]-[AppDatSav] uiMinPeriod (5) record action 14:26:45.609> [2015.01.01 00:00:30]-[AppDatSav] Saving Periodic Record 14:26:45.609> [2015.01.01 00:00:30]-[AVL] Record Saved: 14:26:45.609> 14:26:45.609> Timestamp: 1420070430000 14:26:45.609> Priority : 0 14:26:45.609> Latitude : 0 14:26:45.609> Longitude: 0 14:26:45.609> Altitude : 0 14:26:45.609> Angle : 0 14:26:45.609> Speed : 0 14:26:45.609> HDOP : 0.0 14:26:45.609> SatInUse : 0 14:26:45.609> 14:26:45.609> Event ID : 0 14:26:45.609> RecSize : 30 Bytes 14:26:45.609> 14:26:45.609> [2015.01.01 00:00:30]-[RecMng] New record address: 0x00076A00 len: 30, 106 / 256 14:26:45.609> [2015.01.01 00:00:32]-[MODEM] Not Registered Home Network 14:26:47.671> [2015.01.01 00:00:32]->> +CGREG: 0 14:26:47.671> [2015.01.01 00:00:32]->> OK 14:26:47.671> [2015.01.01 00:00:33]-[AppSend] No sending procedure in 19 sec, configured period: 5 sec 14:26:48.859> [2015.01.01 00:00:34]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:26:49.609> [2015.01.01 00:00:34]-[SCHED] lvcan request 14:26:49.609> [2015.01.01 00:00:34]-[MODEM] Modem is Initializing (tmo:296) 14:26:49.797> [2015.01.01 00:00:35]-[AppDatSav] uiMinPeriod (5) record action 14:26:50.609> [2015.01.01 00:00:35]-[AppDatSav] Saving Periodic Record 14:26:50.609> [2015.01.01 00:00:35]-[AVL] Record Saved: 14:26:50.609> 14:26:50.609> Timestamp: 1420070435000 14:26:50.609> Priority : 0 14:26:50.609> Latitude : 0 14:26:50.609> Longitude: 0 14:26:50.609> Altitude : 0 14:26:50.609> Angle : 0 14:26:50.609> Speed : 0 14:26:50.609> HDOP : 0.0 14:26:50.609> SatInUse : 0 14:26:50.609> 14:26:50.609> Event ID : 0 14:26:50.609> RecSize : 30 Bytes 14:26:50.609> 14:26:50.609> [2015.01.01 00:00:35]-[RecMng] New record address: 0x00076B00 len: 30, 107 / 256 14:26:50.609> [2015.01.01 00:00:37]-<< AT+COPS=0,2 14:26:52.672> [2015.01.01 00:00:39]-[NMEA Filter] PDOP:99.00, HDOP:99.00, Valid:0 Q: 0, Sats: 4 Retry: 0 FixMode: 1 14:26:54.109> [2015.01.01 00:00:39]-<< AT+CREG? 14:26:54.672> [2015.01.01 00:00:39]->> ABORTED 14:26:54.734> [2015.01.01 00:00:40]-[AppDatSav] uiMinPeriod (5) record action 14:26:55.609> [2015.01.01 00:00:40]-[AppDatSav] Saving Periodic Record 14:26:55.609> [2015.01.01 00:00:40]-[AVL] Record Saved: 14:26:55.609> 14:26:55.609> Timestamp: 1420070440000 14:26:55.609> Priority : 0 14:26:55.609> Latitude : 0 14:26:55.609> Longitude: 0 14:26:55.609> Altitude : 0 14:26:55.609> Angle : 0 14:26:55.609> Speed : 0 14:26:55.609> HDOP : 0.0 14:26:55.609> SatInUse : 0 14:26:55.609> 14:26:55.609> Event ID : 0 14:26:55.609> RecSize : 30 Bytes 14:26:55.609> 14:26:55.609> [2015.01.01 00:00:40]-[RecMng] New record address: 0x00076C00 len: 30, 108 / 256 14:26:55.609> [2015.01.01 00:00:42]-[AppDatSav] No GNSS fix 870/900 14:26:57.609> [2015.01.01 00:00:43]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:26:58.859> [2015.01.01 00:00:43]-[AppSend] Checking SMS Data Sending 14:26:58.859> [2015.01.01 00:00:43]-[AppSend] SMS Data Sending is Disabled 14:26:58.922> [2015.01.01 00:00:43]-[AppSend] No sending procedure in 29 sec, configured period: 5 sec 14:26:58.922> [2015.01.01 00:00:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:26:59.609> [2015.01.01 00:00:44]-[SCHED] lvcan request 14:26:59.609> [2015.01.01 00:00:44]-<< AT+COPS=0,2 14:26:59.672> [2015.01.01 00:00:44]-[DEBUG] 14:26:59.672> 14:26:59.672> UARTTxFullCnt: 0 14:26:59.672> SchTaskRunCnt: 10 14:26:59.672> UARTTskRunCnt: 999 14:26:59.672> AppSaveRunCnt: 10 14:26:59.672> AppSendRunCnt: 10 14:26:59.672> ValidNMEAPkts: 110 14:26:59.672> RS232 procesed: 0 14:26:59.672> RS232 droped : 0 14:26:59.672> RS485 procesed: 0 14:26:59.672> RS485 droped : 0 14:26:59.672> 14:26:59.672> [2015.01.01 00:00:44]-[MODEM] Modem is Initializing (tmo:295) 14:27:00.016> [2015.01.01 00:00:45]-[AppDatSav] uiMinPeriod (5) record action 14:27:00.610> [2015.01.01 00:00:45]-[AppDatSav] Saving Periodic Record 14:27:00.610> [2015.01.01 00:00:45]-[AVL] Record Saved: 14:27:00.610> 14:27:00.610> Timestamp: 1420070445000 14:27:00.610> Priority : 0 14:27:00.610> Latitude : 0 14:27:00.610> Longitude: 0 14:27:00.610> Altitude : 0 14:27:00.610> Angle : 0 14:27:00.610> Speed : 0 14:27:00.610> HDOP : 0.0 14:27:00.610> SatInUse : 0 14:27:00.610> 14:27:00.610> Event ID : 0 14:27:00.610> RecSize : 30 Bytes 14:27:00.610> 14:27:00.610> [2015.01.01 00:00:45]-[RecMng] New record address: 0x00076D00 len: 30, 109 / 256 14:27:00.610> [2015.01.01 00:00:48]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:4[1800] pr:42[1800] ds18:0[1800] lvcan:0[1800] 14:27:03.797> [2015.01.01 00:00:49]-[NMEA Filter] PDOP:99.00, HDOP:99.00, Valid:0 Q: 0, Sats: 4 Retry: 0 FixMode: 1 14:27:04.110> [2015.01.01 00:00:49]-[OP.SEARCH] Task started 14:27:04.735> [2015.01.01 00:00:49]-[OP.SEARCH] Operator list is not empty. OP Code: 25001 Index: 0 14:27:04.735> [2015.01.01 00:00:49]-[OP.SEARCH] Time left before optimal operator search: 0 minute(s) 14:27:04.797> [2015.01.01 00:00:49]-[OP.SEARCH] Starting forced operator search... 14:27:04.797> [2015.01.01 00:00:49]-[MODEM] Modem is Initializing (tmo:294) 14:27:04.797> [2015.01.01 00:00:50]-[AppDatSav] uiMinPeriod (5) record action 14:27:05.610> [2015.01.01 00:00:50]-[AppDatSav] Saving Periodic Record 14:27:05.610> [2015.01.01 00:00:50]-[AVL] Record Saved: 14:27:05.610> 14:27:05.610> Timestamp: 1420070450000 14:27:05.610> Priority : 0 14:27:05.610> Latitude : 0 14:27:05.610> Longitude: 0 14:27:05.610> Altitude : 0 14:27:05.610> Angle : 0 14:27:05.610> Speed : 0 14:27:05.610> HDOP : 0.0 14:27:05.610> SatInUse : 0 14:27:05.610> 14:27:05.610> Event ID : 0 14:27:05.610> RecSize : 30 Bytes 14:27:05.610> 14:27:05.610> [2015.01.01 00:00:50]-[RecMng] New record address: 0x00076E00 len: 30, 110 / 256 14:27:05.610> [2015.01.01 00:00:50]-[MODEM] Modem is Initializing (tmo:293) 14:27:06.047> [2015.01.01 00:00:51]-[ModeChange] eOperType_Null 14:27:06.610> [2015.01.01 00:00:51]-[ModeChange] Unknown on Stop profile is updated and reloaded 14:27:06.610> [2015.01.01 00:00:51]-[ModeChange] Mode changed 14:27:06.610> [2015.01.01 00:00:51]-[MODEM] Modem is Initializing (tmo:292) 14:27:06.797> [2015.01.01 00:00:52]-[MODEM] Modem is Initializing (tmo:291) 14:27:07.797> [2015.01.01 00:00:53]-[MODEM] Modem is Initializing (tmo:290) 14:27:08.797> [2015.01.01 00:00:53]-[AppSend] No sending procedure in 39 sec, configured period: 5 sec 14:27:08.922> [2015.01.01 00:00:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:27:09.610> [2015.01.01 00:00:54]-[SCHED] lvcan request 14:27:09.610> [2015.01.01 00:00:54]-[MODEM] Modem is Initializing (tmo:289) 14:27:09.797> [2015.01.01 00:00:54]-[MODEM] Modem is Initializing (tmo:288) 14:27:09.797> [2015.01.01 00:00:55]-[AppDatSav] uiMinPeriod (5) record action 14:27:10.610> [2015.01.01 00:00:55]-[AppDatSav] Saving Periodic Record 14:27:10.610> [2015.01.01 00:00:55]-[AVL] Record Saved: 14:27:10.610> 14:27:10.610> Timestamp: 1420070455000 14:27:10.610> Priority : 0 14:27:10.610> Latitude : 0 14:27:10.610> Longitude: 0 14:27:10.610> Altitude : 0 14:27:10.610> Angle : 0 14:27:10.610> Speed : 0 14:27:10.610> HDOP : 0.0 14:27:10.610> SatInUse : 0 14:27:10.610> 14:27:10.610> Event ID : 0 14:27:10.610> RecSize : 30 Bytes 14:27:10.610> 14:27:10.610> [2015.01.01 00:00:55]-[RecMng] New record address: 0x00076F00 len: 30, 111 / 256 14:27:10.610> [2015.01.01 00:00:55]-[MODEM] Modem is Initializing (tmo:287) 14:27:10.985> [2015.01.01 00:00:56]-[MODEM] Modem is Initializing (tmo:286) 14:27:11.798> [2015.01.01 00:00:57]-[MODEM] Modem is Initializing (tmo:285) 14:27:12.798> [2015.01.01 00:00:58]-[ModeChange] GPS selected as movement indicator 14:27:13.610> [2015.01.01 00:00:58]-[ModeChange] Nothing to change 14:27:13.610> [2015.01.01 00:00:58]-[MODEM] Modem is Initializing (tmo:284) 14:27:13.798> [2015.01.01 00:00:59]-[NMEA Filter] PDOP:99.00, HDOP:99.00, Valid:0 Q: 0, Sats: 4 Retry: 0 FixMode: 1 14:27:14.173> [2015.01.01 00:00:59]-[OP.SEARCH] Wait for registration confirmation from CREG 14:27:14.673> [2015.01.01 00:00:59]-<< AT+COPS=0,2 14:27:14.673> [2015.01.01 00:00:59]->> ABORTED 14:27:14.735> [2015.01.01 00:00:59]-[MODEM] Modem is Initializing (tmo:283) 14:27:14.798> [2015.01.01 00:01:00]-[AppDatSav] uiMinPeriod (5) record action 14:27:15.610> [2015.01.01 00:01:00]-[AppDatSav] Saving Periodic Record 14:27:15.610> [2015.01.01 00:01:00]-[AVL] Record Saved: 14:27:15.610> 14:27:15.610> Timestamp: 1420070460000 14:27:15.610> Priority : 0 14:27:15.610> Latitude : 0 14:27:15.610> Longitude: 0 14:27:15.610> Altitude : 0 14:27:15.610> Angle : 0 14:27:15.610> Speed : 0 14:27:15.610> HDOP : 0.0 14:27:15.610> SatInUse : 0 14:27:15.610> 14:27:15.610> Event ID : 0 14:27:15.610> RecSize : 30 Bytes 14:27:15.610> 14:27:15.610> [2015.01.01 00:01:00]-[RecMng] New record address: 0x00077000 len: 30, 112 / 256 14:27:15.610> [2015.01.01 00:01:00]-[MODEM] Modem is Initializing (tmo:282) 14:27:15.985> [2015.01.01 00:01:01]-[MODEM] Modem is Initializing (tmo:281) 14:27:16.798> [2015.01.01 00:01:02]-[MODEM] Modem is Initializing (tmo:280) 14:27:17.798> [2015.01.01 00:01:02]-[AutoGeo] AutoGeofence is disabled 14:27:17.985> [2015.01.01 00:01:03]-[MODEM] Registered To Home Network 14:27:18.235> [2015.01.01 00:01:03]->> +CREG: 1,"0C81","3590" 14:27:18.235> [2015.01.01 00:01:03]->> +CGEV: NW CLASS B 14:27:18.235> [2015.01.01 00:01:03]-[MODEM] Unknown 14:27:18.235> [2015.01.01 00:01:03]->> +CGREG: 4 14:27:18.235> [2015.01.01 00:01:03]-[MODEM] Modem is Initializing (tmo:279) 14:27:18.798> [2015.01.01 00:01:03]-[AppSend] No sending procedure in 49 sec, configured period: 5 sec 14:27:18.923> [2015.01.01 00:01:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:27:19.610> [2015.01.01 00:01:04]-[SCHED] DTC request 14:27:19.610> [2015.01.01 00:01:04]-[FWInfo] 14:27:19.735> 14:27:19.735> FW version: 01.26.10.Rev.00 14:27:19.735> BL version: 01.09 14:27:19.735> Toolchain : 4.0.0524 14:27:19.735> Compiled : Jun 29 2017 @ 11:24:10 14:27:19.735> HW version: FM11YX_MOD3_A2:B2:C0:D0:E2:F0:G0:H0:I0:J0 14:27:19.735> HW voltage: 123 mV 14:27:19.735> 14:27:19.735> [2015.01.01 00:01:04]-[PERIODIC.ONLINE.INFO] 14:27:19.735> 14:27:19.735> Code Ver : 01.26.10.Rev:0 14:27:19.735> BL Version : 01.09 14:27:19.735> Hw Version : FM11YX_MOD3_A2:B2:C0:D0:E2:F0:G0:H0:I0:J0 14:27:19.735> 14:27:19.735> Device IMEI : 356307044112604 14:27:19.735> Device IMSI : 250015890967995 14:27:19.735> Modem AppVer : 1.06.00 14:27:19.735> Modem FullVer : TM11Q_R_01.03.06.00_001 14:27:19.735> 14:27:19.735> Reset Cause : Power On / Power Down 14:27:20.236> Startup Time : 2015/1/1 0:0:0 14:27:20.236> RTC Clock : 2015/1/1 0:1:4 14:27:20.236> GPS Clock : 2000/1/1 0:0:0 14:27:20.236> Device Uptime : 0:01:04 14:27:20.236> 14:27:20.236> Sesion Upload : 0 Bytes 14:27:20.236> Total Upload : 0 Bytes 14:27:20.236> SMS Sent : 0 14:27:20.236> Received : 0 14:27:20.236> 14:27:20.236> Restarts Made : 1 14:27:20.236> Errors Detected: 0 14:27:20.236> Records Sent : 0 14:27:20.236> BadRec Detected: 0 14:27:20.236> ProfCRC Fails : 0 14:27:20.236> Failed GPRS : 0 14:27:20.236> Failed Link : 0 14:27:20.236> UDP Link TMO : 0 14:27:20.236> 14:27:20.236> No GPS Timer : 0:0:52 14:27:20.236> GPS Status : 3 14:27:20.236> Actual Sat : 0 14:27:20.236> Maximum Sat : 0 14:27:20.236> Last fix acqu : 58 sec (acquired) 14:27:20.236> 14:27:20.236> Working Mode : 4 - Unknown on Stop 14:27:20.236> 14:27:20.236> [2015.01.01 00:01:04]-[MODEM] Modem is Initializing (tmo:278) 14:27:20.236> [2015.01.01 00:01:04]- [Ver]->01.26.10,0,356307044112604,Jun 29 2017,01.09,06,1.06.00,123,GGG303_002,,,,, 14:27:20.236> [2015.01.01 00:01:04]-[MODEM] Modem is Initializing (tmo:278) 14:27:20.236> [2015.01.01 00:01:05]-[AppDatSav] uiMinPeriod (5) record action 14:27:21.126> [2015.01.01 00:01:05]-[AppDatSav] Saving Periodic Record 14:27:21.126> [2015.01.01 00:01:05]-[AVL] Record Saved: 14:27:21.126> 14:27:21.126> Timestamp: 1420070465000 14:27:21.126> Priority : 0 14:27:21.126> Latitude : 0 14:27:21.126> Longitude: 0 14:27:21.126> Altitude : 0 14:27:21.126> Angle : 0 14:27:21.126> Speed : 0 14:27:21.126> HDOP : 0.0 14:27:21.126> SatInUse : 0 14:27:21.126> 14:27:21.126> Event ID : 0 14:27:21.126> RecSize : 30 Bytes 14:27:21.126> 14:27:21.126> [2015.01.01 00:01:05]-[RecMng] New record address: 0x00077100 len: 30, 113 / 256 14:27:21.126> [2015.01.01 00:01:05]-[MODEM] Modem is Initializing (tmo:276) 14:27:21.126> [2015.01.01 00:01:06]-[MODEM] Modem is Initializing (tmo:275) 14:27:21.642> [2015.01.01 00:01:06]-[GPRS] Repeating task after delay 14:27:21.642> [2015.01.01 00:01:06]-[ModeChange] eOperType_Null 14:27:21.642> [2015.01.01 00:01:06]-[ModeChange] Unknown on Moving profile is updated and reloaded 14:27:21.642> [2015.01.01 00:01:06]-[ModeChange] Mode changed 14:27:21.642> [2015.01.01 00:01:06]-[NMEA] Stabilizing input data after GPS FIX lost! 14:27:21.642> [2015.01.01 00:01:06]-[MODEM] Modem is Initializing (tmo:274) 14:27:21.861> [2018.03.10 11:27:23]-[TIME] RTC SYNC (RTC: 1420070467, GNSS: 1520681243, Delta: -100610776.000000) 14:27:22.486> [2018.03.10 11:27:23]-[DISTCNTR] Saved @ 0x000000EC: 80 14:27:22.486> [2018.03.10 11:27:23]-[BBox] Event 0x54, saved to slot 26, offset 208, crc 0xEE, timestamp 1520681243 14:27:22.611> [2018.03.10 11:27:23]-[AppDatSav] uiMinDist (6909177) record action 14:27:22.611> [2018.03.10 11:27:23]-[AppDatSav] Saving Periodic Record 14:27:22.611> [2018.03.10 11:27:23]-[AVL] Record Saved: 14:27:22.611> 14:27:22.611> Timestamp: 1520681243000 14:27:22.611> Priority : 0 14:27:22.611> Latitude : 545581491 14:27:22.611> Longitude: 362931645 14:27:22.611> Altitude : 232 14:27:22.611> Angle : 0 14:27:22.611> Speed : 7 14:27:22.611> HDOP : 1.9 14:27:22.611> SatInUse : 8 14:27:22.611> 14:27:22.611> Event ID : 0 14:27:22.611> RecSize : 30 Bytes 14:27:23.048> 14:27:23.048> [2018.03.10 11:27:23]-[RecMng] New record address: 0x00077200 len: 30, 114 / 256 14:27:23.048> [2018.03.10 11:27:23]-[MODEM] Modem is Initializing (tmo:273) 14:27:23.048> [2018.03.10 11:27:24]-[ModeChange] eOperType_Null 14:27:23.611> [2018.03.10 11:27:24]-[ModeChange] Unknown on Stop profile is updated and reloaded 14:27:23.611> [2018.03.10 11:27:24]-[ModeChange] Mode changed 14:27:23.611> [2018.03.10 11:27:24]-[MODEM] Modem is Initializing (tmo:272) 14:27:23.861> [2018.03.10 11:27:25]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:63[1800] ds18:0[1800] lvcan:0[1800] 14:27:24.798> [2018.03.10 11:27:25]-[MODEM] Modem is Initializing (tmo:271) 14:27:24.861> [2018.03.10 11:27:26]-[NMEA] Stabilizing input data after GPS FIX lost! 14:27:25.611> [2018.03.10 11:27:26]-[MODEM] Modem is Initializing (tmo:270) 14:27:25.861> [2018.03.10 11:27:27]-[MODEM] Modem is Initializing (tmo:269) 14:27:26.861> [2018.03.10 11:27:28]-[NMEA] GPS Data Stable 14:27:27.611> [2018.03.10 11:27:28]-[AppDatSav] uiMinPeriod (5) record action 14:27:27.611> [2018.03.10 11:27:28]-[AppDatSav] Saving Periodic Record 14:27:27.611> [2018.03.10 11:27:28]-[AVL] Record Saved: 14:27:27.611> 14:27:27.611> Timestamp: 1520681248000 14:27:27.611> Priority : 0 14:27:27.611> Latitude : 545581588 14:27:27.611> Longitude: 362931555 14:27:27.611> Altitude : 233 14:27:27.611> Angle : 0 14:27:27.611> Speed : 2 14:27:27.611> HDOP : 0.9 14:27:27.611> SatInUse : 11 14:27:27.611> 14:27:27.611> Event ID : 0 14:27:27.611> RecSize : 30 Bytes 14:27:27.611> 14:27:27.611> [2018.03.10 11:27:28]-[RecMng] New record address: 0x00077300 len: 30, 115 / 256 14:27:27.611> [2018.03.10 11:27:28]-[MODEM] Modem is Initializing (tmo:268) 14:27:28.002> [2018.03.10 11:27:29]-[OP.SEARCH] Wait for registration confirmation from CREG 14:27:28.236> [2018.03.10 11:27:29]-[AppDatSav] Available 14:27:28.611> [2018.03.10 11:27:29]-[AppDatSav] 4 seconds before periodic record save 14:27:28.611> [2018.03.10 11:27:29]-[MODEM] Modem is Initializing (tmo:267) 14:27:28.861> [2018.03.10 11:27:29]-[AppSend] No sending procedure in 100610835 sec, configured period: 5 sec 14:27:28.923> [2018.03.10 11:27:30]-[MODEM] Registered To Home Network 14:27:29.111> [2018.03.10 11:27:30]->> +CREG: 1,"0C81","3521" 14:27:29.111> [2018.03.10 11:27:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:27:29.611> [2018.03.10 11:27:30]-[SCHED] lvcan request 14:27:29.611> [2018.03.10 11:27:30]-[LLS.485] Go to SCAN procedure 14:27:29.611> [2018.03.10 11:27:30]-[DeepSleep] SLEEP MODE DISABLED! 14:27:29.689> [2018.03.10 11:27:30]-<< AT+CPAS 14:27:29.736> [2018.03.10 11:27:30]-[MODEM] 0 - Phone Ready 14:27:29.798> [2018.03.10 11:27:30]->> +CPAS: 0 14:27:29.798> [2018.03.10 11:27:30]->> OK 14:27:29.798> [2018.03.10 11:27:30]-[MODEM] Modem is Initializing (tmo:266) 14:27:29.877> [2018.03.10 11:27:30]-[MODEM] Modem is Initializing (tmo:266) 14:27:29.877> [2018.03.10 11:27:30]-<< AT+CPIN? 14:27:29.877> [2018.03.10 11:27:30]-[MODEM] SIM Card Ready 14:27:29.955> [2018.03.10 11:27:30]->> +CPIN: READY 14:27:29.955> [2018.03.10 11:27:30]->> OK 14:27:29.955> [2018.03.10 11:27:31]->> OK 14:27:30.048> [2018.03.10 11:27:31]->> OK 14:27:30.064> OPS? 14:27:30.064> [2018.03.10 11:27:31]-[MODEM] GSM Op in Num Form 14:27:30.064> [2018.03.10 11:27:31]->> +COPS: 0,2,"25001" 14:27:30.064> [2018.03.10 11:27:31]->> OK 14:27:30.173> [2018.03.10 11:27:31]->> OK 14:27:30.173> IMI 14:27:30.173> [2018.03.10 11:27:31]-[AT.PARSER] IMSI Stored:[250015890967995] 14:27:30.173> [2018.03.10 11:27:31]->> 250015890967995 14:27:30.173> [2018.03.10 11:27:31]-<< AT+CREG? 14:27:30.252> [2018.03.10 11:27:31]-[MODEM] Registered To Home Network 14:27:30.299> [2018.03.10 11:27:31]->> +CREG: 2,1,"0C81","3521" 14:27:30.299> [2018.03.10 11:27:31]->> OK 14:27:30.299> [2018.03.10 11:27:31]-<< AT+CIND? 14:27:30.361> [2018.03.10 11:27:31]-[MODEM.STATUS] 14:27:30.424> 14:27:30.424> Modem Power : 1 14:27:30.424> Modem State : 1 14:27:30.424> Data Link : 0 14:27:30.424> GPRS Status : 0 14:27:30.424> Phone Status: 0 14:27:30.424> SIM PIN : 0 14:27:30.424> PIN Attemts : 3 14:27:30.424> GSM Operator: 25001 14:27:30.424> Bat. Charge : 5 14:27:30.424> GSM Signal : 1 14:27:30.424> Service : 1 14:27:30.424> New Message : 0 14:27:30.424> Roaming : 0 14:27:30.424> SMS Full : 0 14:27:30.424> Voice Call : 0 14:27:30.424> Cell ID : 13601 14:27:30.424> Area Code : 3201 14:27:30.424> MCC : 250 14:27:30.424> MNC : 1 14:27:30.424> 14:27:30.424> [2018.03.10 11:27:31]->> +CIND: 5,1,1,0,0,0,0,0,1,0,0,1 14:27:30.424> [2018.03.10 11:27:31]->> OK 14:27:30.877> [2018.03.10 11:27:31]-<< AT+CSQ 14:27:30.877> [2018.03.10 11:27:31]-[MODEM] Signal rssi 6 14:27:30.877> [2018.03.10 11:27:31]-[BBox] Event 0x5C, saved to slot 27, offset 216, crc 0x99, timestamp 1520681251 14:27:30.877> [2018.03.10 11:27:31]->> +CSQ: 6,99 14:27:30.877> [2018.03.10 11:27:31]-[BBox] Event 0x2A, saved to slot 28, offset 224, crc 0x0E, timestamp 1520681251 14:27:30.877> [2018.03.10 11:27:31]->> OK 14:27:30.877> [2018.03.10 11:27:31]-[ModeChange] Home on Stop profile is updated and reloaded 14:27:30.877> [2018.03.10 11:27:31]-[ModeChange] Mode changed 14:27:30.877> [2018.03.10 11:27:31]-[AppDatSav] Vehicle: Stoped MinPeriod: 600 MinSavedRecords: 1 SendPeriod: 1 14:27:30.877> [2018.03.10 11:27:31]-[InitModem] Modem init procedure successful 14:27:30.877> [2018.03.10 11:27:31]-[OP.SEARCH] Wait for GSM update task 14:27:30.877> [2018.03.10 11:27:31]-[GSM Status] Waiting for Process mutex... 14:27:30.877> [2018.03.10 11:27:31]-[GSM Status] Process mutex taken 14:27:30.877> [2018.03.10 11:27:32]-<< AT+CPAS 14:27:31.361> [2018.03.10 11:27:32]-[MODEM] 0 - Phone Ready 14:27:31.424> [2018.03.10 11:27:32]->> +CPAS: 0 14:27:31.424> [2018.03.10 11:27:32]->> OK 14:27:31.424> [2018.03.10 11:27:32]-<< AT+CPIN? 14:27:31.502> [2018.03.10 11:27:32]-[MODEM] SIM Card Ready 14:27:31.502> [2018.03.10 11:27:32]->> +CPIN: READY 14:27:31.549> [2018.03.10 11:27:32]->> OK 14:27:31.549> [2018.03.10 11:27:32]-<< AT+COPS? 14:27:31.611> [2018.03.10 11:27:32]-[MODEM] GSM Op in Num Form 14:27:31.674> [2018.03.10 11:27:32]->> +COPS: 0,2,"25001" 14:27:31.674> [2018.03.10 11:27:32]->> OK 14:27:31.674> [2018.03.10 11:27:32]-<< AT+CIMI 14:27:31.736> [2018.03.10 11:27:32]-[AT.PARSER] IMSI Stored:[250015890967995] 14:27:31.799> [2018.03.10 11:27:32]->> 250015890967995 14:27:31.799> [2018.03.10 11:27:32]->> OK 14:27:31.799> [2018.03.10 11:27:32]-<< AT+CREG? 14:27:31.861> [2018.03.10 11:27:32]-[MODEM] Registered To Home Network 14:27:31.861> [2018.03.10 11:27:32]->> +CREG: 2,1,"0C81","3521" 14:27:31.924> [2018.03.10 11:27:32]->> OK 14:27:31.924> [2018.03.10 11:27:32]-<< AT+CIND? 14:27:31.986> [2018.03.10 11:27:36]-[OP.SEARCH] Waiting for Process mutex... 14:27:35.861> [2018.03.10 11:27:36]-[MODEM.STATUS] 14:27:35.986> 14:27:35.986> Modem Power : 1 14:27:35.986> Modem State : 0 14:27:35.986> Data Link : 0 14:27:35.986> GPRS Status : 0 14:27:35.986> Phone Status: 0 14:27:35.986> SIM PIN : 0 14:27:35.986> PIN Attemts : 3 14:27:35.986> GSM Operator: 25001 14:27:35.986> Bat. Charge : 5 14:27:35.986> GSM Signal : 1 14:27:35.986> Service : 1 14:27:35.986> New Message : 0 14:27:35.986> Roaming : 0 14:27:35.986> SMS Full : 0 14:27:35.986> Voice Call : 0 14:27:35.986> Cell ID : 13601 14:27:35.986> Area Code : 3201 14:27:35.986> MCC : 250 14:27:35.986> MNC : 1 14:27:35.986> 14:27:35.986> [2018.03.10 11:27:36]->> +CIND: 5,1,1,0,0,0,0,0,1,0,0,1 14:27:35.986> [2018.03.10 11:27:36]->> OK 14:27:35.986> [2018.03.10 11:27:37]-<< AT+CSQ 14:27:36.455> [2018.03.10 11:27:37]-[MODEM] Signal rssi 6 14:27:36.455> [2018.03.10 11:27:37]-[BBox] Event 0x5C, saved to slot 29, offset 232, crc 0xF0, timestamp 1520681257 14:27:36.455> [2018.03.10 11:27:37]->> +CSQ: 6,99 14:27:36.455> [2018.03.10 11:27:37]->> OK 14:27:36.455> [2018.03.10 11:27:37]-[BBox] Event 0x6B, saved to slot 30, offset 240, crc 0xDC, timestamp 1520681257 14:27:36.455> [2018.03.10 11:27:37]-[DTS] Creating Get SMS List Task 14:27:36.611> [2018.03.10 11:27:37]-[StackCheck] TaskId: 1 Name: SMSCHK Stack: 0x20005398 Free: 1648/1720 @ TaskCreate 14:27:36.611> [2018.03.10 11:27:37]-[DTS] tid: 0x0001, prio: 7, evt: 0x0001, dynamic Stack pnt: 20005398 14:27:36.611> [2018.03.10 11:27:38]-[MODEM] Registered To Home Network 14:27:37.361> [2018.03.10 11:27:38]->> +CREG: 1,"0C81","3590" 14:27:37.424> [2018.03.10 11:27:39]-<< AT+CMGL=4 14:27:38.611> [2018.03.10 11:27:39]->> OK 14:27:38.674> [2018.03.10 11:27:39]-[SMS.CHECK] [0]:0,[1]:0,[2]:0,[3]:0,[4]:0,[5]:0,[6]:0,[7]:0,[8]:0,[9]:0 14:27:38.736> [2018.03.10 11:27:39]-[SMS.CHECK] No SMS found 14:27:38.736> [2018.03.10 11:27:39]-[SMS.CHECK] Deleting self 14:27:38.736> [2018.03.10 11:27:39]-[SMS Event] Events not found 14:27:38.736> [2018.03.10 11:27:39]-[GSM Status] Process mutex released 14:27:38.830> [2018.03.10 11:27:39]-[OP.SEARCH] Process mutex taken 14:27:38.830> [2018.03.10 11:27:39]-[OP.SEARCH] Starting operator search procedure 14:27:38.830> [2018.03.10 11:27:39]-<< AT+COPS=? 14:27:38.830> [2018.03.10 11:27:39]-[AppSend] No sending procedure in 100610845 sec, configured period: 1 sec 14:27:38.924> [2018.03.10 11:27:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:27:39.611> [2018.03.10 11:27:40]-[SCHED] lvcan request 14:27:39.611> [2018.03.10 11:27:41]-[DEBUG] 14:27:40.049> 14:27:40.049> UARTTxFullCnt: 0 14:27:40.049> SchTaskRunCnt: 20 14:27:40.049> UARTTskRunCnt: 1976 14:27:40.049> AppSaveRunCnt: 20 14:27:40.049> AppSendRunCnt: 20 14:27:40.049> ValidNMEAPkts: 220 14:27:40.049> RS232 procesed: 0 14:27:40.049> RS232 droped : 0 14:27:40.049> RS485 procesed: 0 14:27:40.049> RS485 droped : 0 14:27:40.049> 14:27:40.049> [2018.03.10 11:27:46]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:7[1800] pr:14[1800] ds18:0[1800] lvcan:0[1800] 14:27:45.799> [2018.03.10 11:27:49]-[AppSend] No sending procedure in 100610855 sec, configured period: 1 sec 14:27:48.924> [2018.03.10 11:27:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:27:49.612> [2018.03.10 11:27:50]-[SCHED] lvcan request 14:27:49.612> [2018.03.10 11:27:59]-[ModeChange] GPS selected as movement indicator 14:27:58.550> [2018.03.10 11:27:59]-[ModeChange] GPS selected as movement indicator 14:27:58.550> [2018.03.10 11:27:59]-[ModeChange] GPS selected as movement indicator 14:27:58.612> [2018.03.10 11:27:59]-[ModeChange] Nothing to change 14:27:58.612> [2018.03.10 11:27:59]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:27:58.925> [2018.03.10 11:27:59]-[AppSend] Checking SMS Data Sending 14:27:58.925> [2018.03.10 11:27:59]-[AppSend] SMS Data Sending is Disabled 14:27:58.925> [2018.03.10 11:27:59]-[AppSend] No sending procedure in 100610865 sec, configured period: 1 sec 14:27:58.925> [2018.03.10 11:28:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:27:59.612> [2018.03.10 11:28:00]-[SCHED] lvcan request 14:27:59.612> [2018.03.10 11:28:05]-[AutoGeo] AutoGeofence is disabled 14:28:04.988> [2018.03.10 11:28:07]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:28[1800] pr:35[1800] ds18:0[1800] lvcan:0[1800] 14:28:06.800> [2018.03.10 11:28:09]-[MODEM] Unknown 14:28:08.550> [2018.03.10 11:28:09]->> +CREG: 4 14:28:08.550> [2018.03.10 11:28:09]-[BBox] Event 0x7A, saved to slot 31, offset 248, crc 0xB5, timestamp 1520681289 14:28:08.613> [2018.03.10 11:28:09]-[AppSend] No sending procedure in 100610875 sec, configured period: 1 sec 14:28:08.925> [2018.03.10 11:28:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:28:09.613> [2018.03.10 11:28:10]-[SCHED] lvcan request 14:28:09.613> [2018.03.10 11:28:14]-[GSM Status] Waiting for Process mutex... 14:28:18.926> [2018.03.10 11:28:19]-[AppSend] No sending procedure in 100610885 sec, configured period: 1 sec 14:28:18.926> [2018.03.10 11:28:20]-[StackCheck] StackInfo: 14:28:19.613> 14:28:19.613> TaskId: 2 Name: TRACE Stack: 0x20001C58 Free: 140/ 576 @ StackInfo 14:28:19.613> TaskId: 3 Name: LVCPAP Stack: 0x2000F1B0 Free: 276/ 664 @ StackInfo 14:28:19.613> TaskId: 4 Name: EVTGEN Stack: 0x200067C0 Free: 596/ 872 @ StackInfo 14:28:19.613> TaskId: 5 Name: SCHEDU Stack: 0x20004DE8 Free: 416/ 768 @ StackInfo 14:28:19.613> TaskId: 6 Name: HEARTB Stack: 0x200050E8 Free: 48/ 128 @ StackInfo 14:28:19.613> TaskId: 7 Name: MODDAT Stack: 0x20005168 Free: 296/ 384 @ StackInfo 14:28:19.613> TaskId: 8 Name: UARTPR Stack: 0x20002A28 Free: 904/1640 @ StackInfo 14:28:19.613> TaskId: 9 Name: DSLEEP Stack: 0x20009B18 Free: 312/ 768 @ StackInfo 14:28:19.832> TaskId: 10 Name: PWRMAN Stack: 0x2000C678 Free: 576/ 768 @ StackInfo 14:28:19.832> TaskId: 11 Name: DATSAV Stack: 0x2000B020 Free: 352/1024 @ StackInfo 14:28:19.832> TaskId: 12 Name: GSMUPD Stack: 0x2000CD00 Free: 856/1504 @ StackInfo 14:28:19.832> TaskId: 13 Name: CHGPRS Stack: 0x2000AC20 Free: 800/1024 @ StackInfo 14:28:19.832> TaskId: 14 Name: OPERS Stack: 0x2000B878 Free: 792/1024 @ StackInfo 14:28:19.832> TaskId: 15 Name: APPSND Stack: 0x2000B420 Free: 864/1096 @ StackInfo 14:28:19.832> TaskId: 16 Name: TCPCMD Stack: 0x2000DD58 Free: 1700/1856 @ StackInfo 14:28:19.832> TaskId: 17 Name: WDOG Stack: 0x20000DB8 Free: 56/ 296 @ StackInfo 14:28:19.832> TaskId: 18 Name: SMSEVT Stack: 0x2000D350 Free: 624/1096 @ StackInfo 14:28:19.832> TaskId: 19 Name: INITM Stack: 0x2000C978 Free: 536/ 904 @ StackInfo 14:28:19.832> 14:28:19.832> [2018.03.10 11:28:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:28:19.832> [2018.03.10 11:28:20]-[SCHED] DTC request 14:28:19.832> [2018.03.10 11:28:21]-[DEBUG] 14:28:20.488> 14:28:20.488> UARTTxFullCnt: 0 14:28:20.488> SchTaskRunCnt: 10 14:28:20.488> UARTTskRunCnt: 990 14:28:20.488> AppSaveRunCnt: 10 14:28:20.488> AppSendRunCnt: 10 14:28:20.488> ValidNMEAPkts: 110 14:28:20.488> RS232 procesed: 0 14:28:20.488> RS232 droped : 0 14:28:20.488> RS485 procesed: 0 14:28:20.488> RS485 droped : 0 14:28:20.488> 14:28:20.488> [2018.03.10 11:28:21]-[PERIODIC.GPS.INFO] 14:28:20.488> 14:28:20.488> Status : 3 (gps on) 14:28:20.488> Sat : 12 14:28:20.488> Lat : 54.558194 14:28:20.488> Long : 36.293139 14:28:20.488> Alt : 237 14:28:20.488> Speed : 0 14:28:20.488> Angle : 0 14:28:20.488> 14:28:20.488> GPS TMO : 900 14:28:20.488> GPS Fix : 1 14:28:20.488> NMEA Flag : 1 14:28:20.488> Time Sync : 1 14:28:20.488> Baudrate : 115200 14:28:20.488> Valid Pkts: 110 14:28:20.488> 14:28:20.488> GPS AP : 1 14:28:20.488> GPS_OFF : 1 14:28:20.488> RAM_GPS_ON: 1 14:28:20.488> SN Config : 1 14:28:20.488> SN State : 2 14:28:20.488> Log2Srv : 0 14:28:20.488> 14:28:20.488> [2018.03.10 11:28:23]-[GPRS] Weektime match detected, scheduled:[11:30] actual:[11:28] 14:28:22.051> [2018.03.10 11:28:23]-[GPRS] Waiting for Process mutex 14:28:22.239> [2018.03.10 11:28:28]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:49[1800] pr:56[1800] ds18:0[1800] lvcan:0[1800] 14:28:27.801> [2018.03.10 11:28:29]-[AppSend] No sending procedure in 100610895 sec, configured period: 1 sec 14:28:29.239> [2018.03.10 11:28:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:28:29.614> [2018.03.10 11:28:30]-[AppDatSav] Available 14:28:29.614> [2018.03.10 11:28:30]-[SCHED] lvcan request 14:28:29.614> [2018.03.10 11:28:30]-[AppDatSav] 538 seconds before periodic record save 14:28:29.614> [2018.03.10 11:28:30]-[DeepSleep] SLEEP MODE DISABLED! 14:28:29.739> [2018.03.10 11:28:39]-[AppSend] No sending procedure in 100610905 sec, configured period: 1 sec 14:28:38.927> [2018.03.10 11:28:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:28:39.614> [2018.03.10 11:28:40]-[SCHED] lvcan request 14:28:39.614> [2018.03.10 11:28:40]-[LLS.485] Go to SCAN procedure 14:28:39.614> [2018.03.10 11:28:45]-[ModeChange] GPS selected as movement indicator 14:28:44.615> [2018.03.10 11:28:45]-[ModeChange] GPS selected as movement indicator 14:28:44.615> [2018.03.10 11:28:45]-[ModeChange] GPS selected as movement indicator 14:28:44.615> [2018.03.10 11:28:45]-[ModeChange] Nothing to change 14:28:44.615> [2018.03.10 11:28:49]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:70[1800] pr:77[1800] ds18:0[1800] lvcan:0[1800] 14:28:48.802> [2018.03.10 11:28:49]-[AppSend] No sending procedure in 100610915 sec, configured period: 1 sec 14:28:48.927> [2018.03.10 11:28:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:28:49.615> [2018.03.10 11:28:50]-[SCHED] lvcan request 14:28:49.615> [2018.03.10 11:28:50]-[Scheduler] GC is scheduled after: 7050 sec 14:28:49.615> [2018.03.10 11:28:52]-[AutoGeo] AutoGeofence is disabled 14:28:51.990> [2018.03.10 11:28:59]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:28:58.928> [2018.03.10 11:28:59]-[AppSend] Checking SMS Data Sending 14:28:58.990> [2018.03.10 11:28:59]-[AppSend] SMS Data Sending is Disabled 14:28:58.990> [2018.03.10 11:28:59]-[AppSend] No sending procedure in 100610925 sec, configured period: 1 sec 14:28:58.990> [2018.03.10 11:29:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:28:59.615> [2018.03.10 11:29:00]-[SCHED] lvcan request 14:28:59.615> [2018.03.10 11:29:01]-[DEBUG] 14:29:00.928> 14:29:00.928> UARTTxFullCnt: 0 14:29:00.928> SchTaskRunCnt: 10 14:29:00.928> UARTTskRunCnt: 990 14:29:00.928> AppSaveRunCnt: 10 14:29:00.928> AppSendRunCnt: 10 14:29:00.928> ValidNMEAPkts: 110 14:29:00.928> RS232 procesed: 0 14:29:00.928> RS232 droped : 0 14:29:00.928> RS485 procesed: 0 14:29:00.928> RS485 droped : 0 14:29:00.928> 14:29:00.928> [2018.03.10 11:29:09]-[AppSend] No sending procedure in 100610935 sec, configured period: 1 sec 14:29:08.991> [2018.03.10 11:29:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:29:09.616> [2018.03.10 11:29:10]-[SCHED] lvcan request 14:29:09.616> [2018.03.10 11:29:10]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:91[1800] pr:98[1800] ds18:0[1800] lvcan:0[1800] 14:29:09.866> [2018.03.10 11:29:19]-[AppSend] No sending procedure in 100610945 sec, configured period: 1 sec 14:29:18.991> [2018.03.10 11:29:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:29:19.616> [2018.03.10 11:29:20]-[SCHED] DTC request 14:29:19.616> [2018.03.10 11:29:22]-[PowerMan] Ext:12293 mV; BatV:0 mV; BatI:0 mA; GPS:3069 mV; Modem:3865 mV; 14:29:21.179> [2018.03.10 11:29:29]-[AppSend] No sending procedure in 100610955 sec, configured period: 1 sec 14:29:28.992> [2018.03.10 11:29:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:29:29.617> [2018.03.10 11:29:30]-[SCHED] lvcan request 14:29:29.617> [2018.03.10 11:29:30]-[DeepSleep] SLEEP MODE DISABLED! 14:29:29.804> [2018.03.10 11:29:31]-[ModeChange] GPS selected as movement indicator 14:29:30.554> [2018.03.10 11:29:31]-[ModeChange] GPS selected as movement indicator 14:29:30.617> [2018.03.10 11:29:31]-[ModeChange] GPS selected as movement indicator 14:29:30.617> [2018.03.10 11:29:31]-[ModeChange] Nothing to change 14:29:30.617> [2018.03.10 11:29:31]-[AppDatSav] Available 14:29:30.617> [2018.03.10 11:29:31]-[AppDatSav] 477 seconds before periodic record save 14:29:30.617> [2018.03.10 11:29:31]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:112[1800] pr:119[1800] ds18:0[1800] lvcan:0[1800] 14:29:30.867> [2018.03.10 11:29:39]-[AppSend] No sending procedure in 100610965 sec, configured period: 1 sec 14:29:38.992> [2018.03.10 11:29:39]-[AutoGeo] AutoGeofence is disabled 14:29:38.992> [2018.03.10 11:29:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:29:39.617> [2018.03.10 11:29:40]-[SCHED] lvcan request 14:29:39.617> [2018.03.10 11:29:42]-[DEBUG] 14:29:41.367> 14:29:41.367> UARTTxFullCnt: 0 14:29:41.367> SchTaskRunCnt: 10 14:29:41.367> UARTTskRunCnt: 989 14:29:41.367> AppSaveRunCnt: 10 14:29:41.367> AppSendRunCnt: 10 14:29:41.367> ValidNMEAPkts: 110 14:29:41.367> RS232 procesed: 0 14:29:41.367> RS232 droped : 0 14:29:41.367> RS485 procesed: 0 14:29:41.367> RS485 droped : 0 14:29:41.367> 14:29:41.367> [2018.03.10 11:29:49]-[AppSend] No sending procedure in 100610975 sec, configured period: 1 sec 14:29:48.993> [2018.03.10 11:29:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:29:49.618> [2018.03.10 11:29:50]-[SCHED] lvcan request 14:29:49.618> [2018.03.10 11:29:50]-[LLS.485] Go to SCAN procedure 14:29:49.618> [2018.03.10 11:29:52]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:133[1800] pr:140[1800] ds18:0[1800] lvcan:0[1800] 14:29:51.868> [2018.03.10 11:29:59]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:29:58.993> [2018.03.10 11:29:59]-[AppSend] Checking SMS Data Sending 14:29:58.993> [2018.03.10 11:29:59]-[AppSend] SMS Data Sending is Disabled 14:29:58.993> [2018.03.10 11:29:59]-[AppSend] No sending procedure in 100610985 sec, configured period: 1 sec 14:29:58.993> [2018.03.10 11:30:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:29:59.618> [2018.03.10 11:30:00]-[SCHED] lvcan request 14:29:59.618> [2018.03.10 11:30:09]-[AppSend] No sending procedure in 100610995 sec, configured period: 1 sec 14:30:08.994> [2018.03.10 11:30:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:30:09.619> [2018.03.10 11:30:10]-[SCHED] lvcan request 14:30:09.619> [2018.03.10 11:30:13]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:154[1800] pr:161[1800] ds18:0[1800] lvcan:0[1800] 14:30:12.869> [2018.03.10 11:30:17]-[ModeChange] GPS selected as movement indicator 14:30:16.557> [2018.03.10 11:30:17]-[ModeChange] GPS selected as movement indicator 14:30:16.557> [2018.03.10 11:30:17]-[ModeChange] GPS selected as movement indicator 14:30:16.619> [2018.03.10 11:30:17]-[ModeChange] Nothing to change 14:30:16.619> [2018.03.10 11:30:19]-[AppSend] No sending procedure in 100611005 sec, configured period: 1 sec 14:30:18.994> [2018.03.10 11:30:20]-[StackCheck] StackInfo: 14:30:19.619> 14:30:19.619> TaskId: 2 Name: TRACE Stack: 0x20001C58 Free: 140/ 576 @ StackInfo 14:30:19.619> TaskId: 3 Name: LVCPAP Stack: 0x2000F1B0 Free: 276/ 664 @ StackInfo 14:30:19.619> TaskId: 4 Name: EVTGEN Stack: 0x200067C0 Free: 596/ 872 @ StackInfo 14:30:19.619> TaskId: 5 Name: SCHEDU Stack: 0x20004DE8 Free: 416/ 768 @ StackInfo 14:30:19.619> TaskId: 6 Name: HEARTB Stack: 0x200050E8 Free: 48/ 128 @ StackInfo 14:30:19.619> TaskId: 7 Name: MODDAT Stack: 0x20005168 Free: 296/ 384 @ StackInfo 14:30:19.682> TaskId: 8 Name: UARTPR Stack: 0x20002A28 Free: 904/1640 @ StackInfo 14:30:19.682> TaskId: 9 Name: DSLEEP Stack: 0x20009B18 Free: 312/ 768 @ StackInfo 14:30:19.682> TaskId: 10 Name: PWRMAN Stack: 0x2000C678 Free: 576/ 768 @ StackInfo 14:30:19.682> TaskId: 11 Name: DATSAV Stack: 0x2000B020 Free: 352/1024 @ StackInfo 14:30:19.682> TaskId: 12 Name: GSMUPD Stack: 0x2000CD00 Free: 856/1504 @ StackInfo 14:30:19.682> TaskId: 13 Name: CHGPRS Stack: 0x2000AC20 Free: 768/1024 @ StackInfo 14:30:19.682> TaskId: 14 Name: OPERS Stack: 0x2000B878 Free: 792/1024 @ StackInfo 14:30:19.744> TaskId: 15 Name: APPSND Stack: 0x2000B420 Free: 864/1096 @ StackInfo 14:30:19.744> TaskId: 16 Name: TCPCMD Stack: 0x2000DD58 Free: 1700/1856 @ StackInfo 14:30:19.744> TaskId: 17 Name: WDOG Stack: 0x20000DB8 Free: 56/ 296 @ StackInfo 14:30:19.744> TaskId: 18 Name: SMSEVT Stack: 0x2000D350 Free: 624/1096 @ StackInfo 14:30:19.744> TaskId: 19 Name: INITM Stack: 0x2000C978 Free: 536/ 904 @ StackInfo 14:30:19.744> 14:30:19.744> [2018.03.10 11:30:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:30:19.744> [2018.03.10 11:30:20]-[SCHED] DTC request 14:30:19.744> [2018.03.10 11:30:22]-[DEBUG] 14:30:21.807> 14:30:21.807> UARTTxFullCnt: 0 14:30:21.807> SchTaskRunCnt: 10 14:30:21.807> UARTTskRunCnt: 991 14:30:21.807> AppSaveRunCnt: 10 14:30:21.807> AppSendRunCnt: 10 14:30:21.807> ValidNMEAPkts: 110 14:30:21.807> RS232 procesed: 0 14:30:21.807> RS232 droped : 0 14:30:21.807> RS485 procesed: 0 14:30:21.807> RS485 droped : 0 14:30:21.807> 14:30:21.807> [2018.03.10 11:30:22]-[PERIODIC.NMEA.INFO] 14:30:21.807> 14:30:21.807> Total Pkts : 2633 14:30:21.807> Bad Chksum : 0 14:30:21.807> No Chksum : 6 14:30:21.807> 14:30:21.807> HDOP Cycles: 180 14:30:21.807> HDOP Limit : 4.0 14:30:21.807> HDOP Actual: 0.8 14:30:21.807> HDOP Mean : 0.8 14:30:21.807> HDOP Min : 0.7 14:30:21.807> HDOP Max : 1.9 14:30:21.807> PDOP Actual: 1.2 14:30:21.807> HDOP Rejct : 0 14:30:21.807> HDOP Taken : 0 14:30:21.807> HDOP Timer : 60 14:30:21.807> HDOP Ready : 0 14:30:21.807> 14:30:21.807> Total sats GPS : 14 Tracked: 8 30 15 7 5 14:30:21.869> Total sats GLONASS: 10 Tracked: 67 76 75 85 84 14:30:21.869> 14:30:21.869> Bad Time: 0 14:30:21.869> Bad Lat : 0 14:30:21.869> Bad Lon : 0 14:30:21.869> Bad Spd : 0 14:30:21.869> Bad Angl: 0 14:30:21.869> Bad HDOP: 0 14:30:21.869> 14:30:21.869> GPS jmps: 0 14:30:21.869> Filtered: 237 14:30:21.869> Rejected: 4 14:30:21.869> 14:30:21.869> [2018.03.10 11:30:26]-[AutoGeo] AutoGeofence is disabled 14:30:25.995> [2018.03.10 11:30:29]-[AppSend] No sending procedure in 100611015 sec, configured period: 1 sec 14:30:28.995> [2018.03.10 11:30:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:30:29.620> [2018.03.10 11:30:30]-[SCHED] lvcan request 14:30:29.620> [2018.03.10 11:30:30]-[DeepSleep] SLEEP MODE DISABLED! 14:30:29.870> [2018.03.10 11:30:32]-[AppDatSav] Available 14:30:31.620> [2018.03.10 11:30:32]-[AppDatSav] 416 seconds before periodic record save 14:30:31.620> [2018.03.10 11:30:34]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:175[1800] pr:182[1800] ds18:0[1800] lvcan:0[1800] 14:30:33.870> [2018.03.10 11:30:39]-[OP.SEARCH] ERROR @ app_search_op.c(236): Checking for reason... 14:30:38.808> [2018.03.10 11:30:39]-[AppSend] No sending procedure in 100611025 sec, configured period: 1 sec 14:30:38.995> [2018.03.10 11:30:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:30:39.620> [2018.03.10 11:30:40]-[SCHED] lvcan request 14:30:39.620> [2018.03.10 11:30:40]-<< AT+CPAS 14:30:39.808> [2018.03.10 11:30:40]->> ABORTED 14:30:39.870> [2018.03.10 11:30:40]-[OP.SEARCH] ERROR @ app_search_op.c(244): [0]: GSM Activity Status Procedure Failed 14:30:39.870> [2018.03.10 11:30:40]-[OP.SEARCH] Setting modem state to UNAVAILABLE 14:30:39.870> [2018.03.10 11:30:40]-[OP.SEARCH] Process mutex released 14:30:39.870> [2018.03.10 11:30:40]-[GSM Status] Process mutex taken 14:30:39.870> [2018.03.10 11:30:40]-[GSM Status] Process mutex released 14:30:39.933> [2018.03.10 11:30:40]-[GPRS] Process mutex taken 14:30:39.933> [2018.03.10 11:30:40]-[GSM Status] Modem is not ready 14:30:39.933> [2018.03.10 11:30:40]-[GPRS] Opening context 14:30:39.933> [2018.03.10 11:30:40]-<< AT+TIPCALL? 14:30:39.933> [2018.03.10 11:30:40]->> +TIPCALL: 0 14:30:39.995> [2018.03.10 11:30:40]->> OK 14:30:39.995> [2018.03.10 11:30:40]-[MODEM] ok got after 11942 ms cmd event after 11941 ms 14:30:39.995> [2018.03.10 11:30:40]-[MODEM] RetVal: 1 14:30:39.995> [2018.03.10 11:30:40]-<< AT+TIPCALL=1,"internet.mts.ru","","" 14:30:39.995> [2018.03.10 11:30:41]->> OK 14:30:40.058> [2018.03.10 11:30:45]-[AppSend] No sending procedure in 100611031 sec, configured period: 1 sec 14:30:44.996> [2018.03.10 11:30:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:30:49.621> [2018.03.10 11:30:50]-[SCHED] lvcan request 14:30:49.621> [2018.03.10 11:30:50]-[MODEM] Initiating Modem Restart Procedure 14:30:49.871> [2018.03.10 11:30:50]-[InitModem] Waiting for Process mutex... 14:30:49.871> [2018.03.10 11:30:55]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:14[1800] pr:14[1800] ds18:0[1800] lvcan:0[1800] 14:30:54.871> [2018.03.10 11:30:55]-[AppSend] No sending procedure in 100611041 sec, configured period: 1 sec 14:30:55.012> [2018.03.10 11:30:59]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:30:58.996> [2018.03.10 11:30:59]-[AppSend] Checking SMS Data Sending 14:30:59.059> [2018.03.10 11:30:59]-[AppSend] SMS Data Sending is Disabled 14:30:59.059> [2018.03.10 11:31:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:30:59.621> [2018.03.10 11:31:00]-[SCHED] lvcan request 14:30:59.621> [2018.03.10 11:31:00]-[LLS.485] Go to SCAN procedure 14:30:59.621> [2018.03.10 11:31:00]-[MODEM] Modem is Initializing (tmo:300) 14:30:59.871> [2018.03.10 11:31:03]-[DEBUG] 14:31:02.246> 14:31:02.246> UARTTxFullCnt: 0 14:31:02.246> SchTaskRunCnt: 10 14:31:02.246> UARTTskRunCnt: 990 14:31:02.246> AppSaveRunCnt: 10 14:31:02.246> AppSendRunCnt: 10 14:31:02.246> ValidNMEAPkts: 110 14:31:02.246> RS232 procesed: 0 14:31:02.246> RS232 droped : 0 14:31:02.246> RS485 procesed: 0 14:31:02.246> RS485 droped : 0 14:31:02.246> 14:31:02.246> [2018.03.10 11:31:03]-[ModeChange] GPS selected as movement indicator 14:31:02.621> [2018.03.10 11:31:03]-[ModeChange] GPS selected as movement indicator 14:31:02.621> [2018.03.10 11:31:03]-[ModeChange] GPS selected as movement indicator 14:31:02.621> [2018.03.10 11:31:03]-[ModeChange] Nothing to change 14:31:02.621> [2018.03.10 11:31:05]-[AppSend] No sending procedure in 100611051 sec, configured period: 1 sec 14:31:05.030> [2018.03.10 11:31:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:31:09.608> [2018.03.10 11:31:10]-[SCHED] lvcan request 14:31:09.608> [2018.03.10 11:31:10]-[MODEM] Modem is Initializing (tmo:299) 14:31:09.853> [2018.03.10 11:31:13]-[AutoGeo] AutoGeofence is disabled 14:31:13.044> [2018.03.10 11:31:15]-[AppSend] No sending procedure in 100611061 sec, configured period: 1 sec 14:31:15.044> [2018.03.10 11:31:16]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:35[1800] pr:35[1800] ds18:0[1800] lvcan:0[1800] 14:31:15.856> [2018.03.10 11:31:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:31:19.607> [2018.03.10 11:31:20]-[SCHED] DTC request 14:31:19.607> [2018.03.10 11:31:20]-[Scheduler] GC is scheduled after: 6900 sec 14:31:19.607> [2018.03.10 11:31:20]-[MODEM] Modem is Initializing (tmo:298) 14:31:19.857> [2018.03.10 11:31:23]-[PERIODIC.BATRAM.INFO] 14:31:22.482> 14:31:22.482> BootSign : 0x0000 14:31:22.482> BootSign Inv: 0x0000 14:31:22.482> 14:31:22.482> Date : 2018.03.10 14:31:22.482> TimeSync : 1 14:31:22.482> Uptime : 307 14:31:22.482> 14:31:22.482> Restart Cnt : 0 14:31:22.482> WDG Restarts: 0 14:31:22.482> Bad Records : 0 14:31:22.482> AuthState : 0 14:31:22.482> GC Timer : 302 14:31:22.482> 14:31:22.482> [2018.03.10 11:31:25]-[AppSend] No sending procedure in 100611071 sec, configured period: 1 sec 14:31:25.044> [2018.03.10 11:31:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:31:29.607> [2018.03.10 11:31:30]-[SCHED] lvcan request 14:31:29.607> [2018.03.10 11:31:30]-[DeepSleep] SLEEP MODE DISABLED! 14:31:29.920> [2018.03.10 11:31:30]-[MODEM] Modem is Initializing (tmo:297) 14:31:29.920> [2018.03.10 11:31:33]-[AppDatSav] Available 14:31:32.607> [2018.03.10 11:31:33]-[AppDatSav] 355 seconds before periodic record save 14:31:32.607> [2018.03.10 11:31:35]-[AppSend] No sending procedure in 100611081 sec, configured period: 1 sec 14:31:35.045> [2018.03.10 11:31:37]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:56[1800] pr:56[1800] ds18:0[1800] lvcan:0[1800] 14:31:36.858> [2018.03.10 11:31:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:31:39.608> [2018.03.10 11:31:40]-[SCHED] lvcan request 14:31:39.608> [2018.03.10 11:31:40]-[MODEM] Modem is Initializing (tmo:296) 14:31:39.858> [2018.03.10 11:31:40]-[OP.SEARCH] Time left before optimal operator search: 0 minute(s) 14:31:39.920> [2018.03.10 11:31:40]-[OP.SEARCH] Starting forced operator search... 14:31:39.920> [2018.03.10 11:31:40]-[MODEM] Modem is Initializing (tmo:295) 14:31:39.920> [2018.03.10 11:31:41]-[MODEM] Modem is Initializing (tmo:294) 14:31:40.920> [2018.03.10 11:31:42]-[MODEM] Modem is Initializing (tmo:293) 14:31:41.920> [2018.03.10 11:31:43]-[DEBUG] 14:31:42.733> 14:31:42.733> UARTTxFullCnt: 0 14:31:42.733> SchTaskRunCnt: 10 14:31:42.733> UARTTskRunCnt: 990 14:31:42.733> AppSaveRunCnt: 10 14:31:42.733> AppSendRunCnt: 10 14:31:42.733> ValidNMEAPkts: 110 14:31:42.733> RS232 procesed: 0 14:31:42.733> RS232 droped : 0 14:31:42.733> RS485 procesed: 0 14:31:42.733> RS485 droped : 0 14:31:42.733> 14:31:42.733> [2018.03.10 11:31:43]-[MODEM] Modem is Initializing (tmo:292) 14:31:42.920> [2018.03.10 11:31:44]-[MODEM] Modem is Initializing (tmo:291) 14:31:43.920> [2018.03.10 11:31:45]-[MODEM] Modem is Initializing (tmo:290) 14:31:44.920> [2018.03.10 11:31:45]-[AppSend] No sending procedure in 100611091 sec, configured period: 1 sec 14:31:45.045> [2018.03.10 11:31:46]-[MODEM] Modem is Initializing (tmo:289) 14:31:45.920> [2018.03.10 11:31:47]-[MODEM] Modem is Initializing (tmo:288) 14:31:46.920> [2018.03.10 11:31:48]-[MODEM] Modem is Initializing (tmo:287) 14:31:47.921> [2018.03.10 11:31:49]-[ModeChange] GPS selected as movement indicator 14:31:48.733> [2018.03.10 11:31:49]-[ModeChange] GPS selected as movement indicator 14:31:48.733> [2018.03.10 11:31:49]-[ModeChange] GPS selected as movement indicator 14:31:48.733> [2018.03.10 11:31:49]-[ModeChange] Nothing to change 14:31:48.733> [2018.03.10 11:31:49]-[MODEM] Modem is Initializing (tmo:286) 14:31:48.936> [2018.03.10 11:31:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:31:49.608> [2018.03.10 11:31:50]-[SCHED] lvcan request 14:31:49.608> [2018.03.10 11:31:50]-[MODEM] Modem is Initializing (tmo:285) 14:31:49.921> [2018.03.10 11:31:50]-[MODEM] Modem is Initializing (tmo:284) 14:31:49.921> [2018.03.10 11:31:51]-[MODEM] Modem is Initializing (tmo:283) 14:31:50.921> [2018.03.10 11:31:52]-[MODEM] Modem is Initializing (tmo:282) 14:31:51.921> [2018.03.10 11:31:53]-[MODEM] Modem is Initializing (tmo:281) 14:31:52.921> [2018.03.10 11:31:54]-[MODEM] Modem is Initializing (tmo:280) 14:31:53.921> [2018.03.10 11:31:55]-[MODEM] Modem is Initializing (tmo:279) 14:31:54.921> [2018.03.10 11:31:55]-[AppSend] No sending procedure in 100611101 sec, configured period: 1 sec 14:31:55.046> [2018.03.10 11:31:56]-[MODEM] Modem is Initializing (tmo:278) 14:31:55.983> [2018.03.10 11:31:57]-[MODEM] Modem is Initializing (tmo:277) 14:31:56.937> [2018.03.10 11:31:58]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:77[1800] pr:77[1800] ds18:0[1800] lvcan:0[1800] 14:31:57.859> [2018.03.10 11:31:58]-[MODEM] Modem is Initializing (tmo:276) 14:31:57.921> [2018.03.10 11:31:59]-[MODEM] Modem is Initializing (tmo:275) 14:31:58.890> [2018.03.10 11:31:59]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:31:59.046> [2018.03.10 11:31:59]-[AppSend] Checking SMS Data Sending 14:31:59.046> [2018.03.10 11:32:00]-[AppSend] SMS Data Sending is Disabled 14:31:59.046> [2018.03.10 11:32:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:31:59.609> [2018.03.10 11:32:00]-[SCHED] lvcan request 14:31:59.609> [2018.03.10 11:32:00]-[MODEM] Modem is Initializing (tmo:274) 14:31:59.859> [2018.03.10 11:32:00]-[MODEM] Modem is Initializing (tmo:273) 14:31:59.921> [2018.03.10 11:32:00]-[AutoGeo] AutoGeofence is disabled 14:31:59.999> [2018.03.10 11:32:01]-[MODEM] Modem is Initializing (tmo:272) 14:32:00.921> [2018.03.10 11:32:02]-[MODEM] Modem is Initializing (tmo:271) 14:32:01.921> [2018.03.10 11:32:03]-[MODEM] Modem is Initializing (tmo:270) 14:32:02.921> [2018.03.10 11:32:04]-[MODEM] Modem is Initializing (tmo:269) 14:32:03.921> [2018.03.10 11:32:05]-[MODEM] Modem is Initializing (tmo:268) 14:32:04.921> [2018.03.10 11:32:06]-[AppSend] No sending procedure in 100611112 sec, configured period: 1 sec 14:32:05.046> [2018.03.10 11:32:06]-[MODEM] Modem is Initializing (tmo:267) 14:32:05.937> [2018.03.10 11:32:07]-[MODEM] Modem is Initializing (tmo:266) 14:32:06.937> [2018.03.10 11:32:08]-[MODEM] Modem is Initializing (tmo:265) 14:32:07.922> [2018.03.10 11:32:09]-[MODEM] Modem is Initializing (tmo:264) 14:32:08.922> [2018.03.10 11:32:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:32:09.609> [2018.03.10 11:32:10]-[SCHED] lvcan request 14:32:09.609> [2018.03.10 11:32:10]-[LLS.485] Go to SCAN procedure 14:32:09.687> [2018.03.10 11:32:10]-[MODEM] Modem is Initializing (tmo:263) 14:32:09.859> [2018.03.10 11:32:10]-[MODEM] Modem is Initializing (tmo:262) 14:32:09.922> [2018.03.10 11:32:11]-[MODEM] Modem is Initializing (tmo:261) 14:32:10.922> [2018.03.10 11:32:12]-[MODEM] Modem is Initializing (tmo:260) 14:32:11.922> [2018.03.10 11:32:13]-[MODEM] Modem is Initializing (tmo:259) 14:32:12.922> [2018.03.10 11:32:14]-[MODEM] Modem is Initializing (tmo:258) 14:32:13.922> [2018.03.10 11:32:15]-[MODEM] Modem is Initializing (tmo:257) 14:32:14.922> [2018.03.10 11:32:16]-[AppSend] No sending procedure in 100611122 sec, configured period: 1 sec 14:32:15.047> [2018.03.10 11:32:16]-[MODEM] Modem is Initializing (tmo:256) 14:32:15.922> [2018.03.10 11:32:17]-[MODEM] Modem is Initializing (tmo:255) 14:32:16.922> [2018.03.10 11:32:18]-[MODEM] Modem is Initializing (tmo:254) 14:32:17.922> [2018.03.10 11:32:19]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:98[1800] pr:98[1800] ds18:0[1800] lvcan:0[1800] 14:32:18.860> [2018.03.10 11:32:19]-[MODEM] Modem is Initializing (tmo:253) 14:32:18.922> [2018.03.10 11:32:20]-[StackCheck] StackInfo: 14:32:19.610> 14:32:19.610> TaskId: 2 Name: TRACE Stack: 0x20001C58 Free: 140/ 576 @ StackInfo 14:32:19.610> TaskId: 3 Name: LVCPAP Stack: 0x2000F1B0 Free: 276/ 664 @ StackInfo 14:32:19.610> TaskId: 4 Name: EVTGEN Stack: 0x200067C0 Free: 596/ 872 @ StackInfo 14:32:19.672> TaskId: 5 Name: SCHEDU Stack: 0x20004DE8 Free: 416/ 768 @ StackInfo 14:32:19.672> TaskId: 6 Name: HEARTB Stack: 0x200050E8 Free: 48/ 128 @ StackInfo 14:32:19.672> TaskId: 7 Name: MODDAT Stack: 0x20005168 Free: 296/ 384 @ StackInfo 14:32:19.672> TaskId: 8 Name: UARTPR Stack: 0x20002A28 Free: 904/1640 @ StackInfo 14:32:19.672> TaskId: 9 Name: DSLEEP Stack: 0x20009B18 Free: 312/ 768 @ StackInfo 14:32:19.672> TaskId: 10 Name: PWRMAN Stack: 0x2000C678 Free: 576/ 768 @ StackInfo 14:32:19.672> TaskId: 11 Name: DATSAV Stack: 0x2000B020 Free: 352/1024 @ StackInfo 14:32:19.672> TaskId: 12 Name: GSMUPD Stack: 0x2000CD00 Free: 856/1504 @ StackInfo 14:32:19.735> TaskId: 13 Name: CHGPRS Stack: 0x2000AC20 Free: 424/1024 @ StackInfo 14:32:19.735> TaskId: 14 Name: OPERS Stack: 0x2000B878 Free: 784/1024 @ StackInfo 14:32:19.735> TaskId: 15 Name: APPSND Stack: 0x2000B420 Free: 864/1096 @ StackInfo 14:32:19.735> TaskId: 16 Name: TCPCMD Stack: 0x2000DD58 Free: 1700/1856 @ StackInfo 14:32:19.735> TaskId: 17 Name: WDOG Stack: 0x20000DB8 Free: 56/ 296 @ StackInfo 14:32:19.735> TaskId: 18 Name: SMSEVT Stack: 0x2000D350 Free: 624/1096 @ StackInfo 14:32:19.735> TaskId: 19 Name: INITM Stack: 0x2000C978 Free: 536/ 904 @ StackInfo 14:32:19.735> 14:32:19.735> [2018.03.10 11:32:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:32:19.735> [2018.03.10 11:32:20]-[SCHED] DTC request 14:32:19.797> [2018.03.10 11:32:20]-[MODEM] Modem is Initializing (tmo:252) 14:32:19.860> [2018.03.10 11:32:20]-[MODEM] Modem is Initializing (tmo:251) 14:32:19.922> [2018.03.10 11:32:21]-[MODEM] Modem is Initializing (tmo:250) 14:32:20.922> [2018.03.10 11:32:22]-[MODEM] Modem is Initializing (tmo:249) 14:32:21.922> [2018.03.10 11:32:23]-[MODEM] Modem is Initializing (tmo:248) 14:32:22.905> [2018.03.10 11:32:24]-[DEBUG] 14:32:23.156> 14:32:23.156> UARTTxFullCnt: 0 14:32:23.156> SchTaskRunCnt: 10 14:32:23.156> UARTTskRunCnt: 990 14:32:23.156> AppSaveRunCnt: 10 14:32:23.156> AppSendRunCnt: 10 14:32:23.156> ValidNMEAPkts: 110 14:32:23.156> RS232 procesed: 0 14:32:23.156> RS232 droped : 0 14:32:23.156> RS485 procesed: 0 14:32:23.156> RS485 droped : 0 14:32:23.156> 14:32:23.156> [2018.03.10 11:32:24]-[FWInfo] 14:32:23.156> 14:32:23.156> FW version: 01.26.10.Rev.00 14:32:23.156> BL version: 01.09 14:32:23.156> Toolchain : 4.0.0524 14:32:23.156> Compiled : Jun 29 2017 @ 11:24:10 14:32:23.156> HW version: FM11YX_MOD3_A2:B2:C0:D0:E2:F0:G0:H0:I0:J0 14:32:23.156> HW voltage: 123 mV 14:32:23.156> 14:32:23.156> [2018.03.10 11:32:24]-[PERIODIC.ONLINE.INFO] 14:32:23.156> 14:32:23.156> Code Ver : 01.26.10.Rev:0 14:32:23.156> BL Version : 01.09 14:32:23.156> Hw Version : FM11YX_MOD3_A2:B2:C0:D0:E2:F0:G0:H0:I0:J0 14:32:23.156> 14:32:23.156> Device IMEI : 356307044112604 14:32:23.156> Device IMSI : 250015890967995 14:32:23.156> Modem AppVer : 1.06.00 14:32:23.156> Modem FullVer : TM11Q_R_01.03.06.00_001 14:32:23.156> 14:32:23.156> Reset Cause : Power On / Power Down 14:32:23.235> Startup Time : 2018/3/10 11:26:16 14:32:23.235> RTC Clock : 2018/3/10 11:32:24 14:32:23.235> GPS Clock : 2018/3/10 11:32:23 14:32:23.235> Device Uptime : 0:06:08 14:32:23.235> 14:32:23.235> Sesion Upload : 0 Bytes 14:32:23.235> Total Upload : 0 Bytes 14:32:23.235> SMS Sent : 0 14:32:23.235> Received : 0 14:32:23.235> 14:32:23.235> Restarts Made : 2 14:32:23.235> Errors Detected: 1 14:32:23.235> Records Sent : 0 14:32:23.235> BadRec Detected: 0 14:32:23.235> ProfCRC Fails : 0 14:32:23.235> Failed GPRS : 0 14:32:23.235> Failed Link : 0 14:32:23.235> UDP Link TMO : 0 14:32:23.235> 14:32:23.235> No GPS Timer : 0:0:54 14:32:23.235> GPS Status : 3 14:32:23.235> Actual Sat : 16 14:32:23.235> Maximum Sat : 17 14:32:23.235> Last fix acqu : 58 sec (acquired) 14:32:23.235> 14:32:23.235> Working Mode : 0 - Home on Stop 14:32:23.235> 14:32:23.235> [2018.03.10 11:32:24]- [Ver]->01.26.10,0,356307044112604,Jun 29 2017,01.09,06,1.06.00,123,GGG303_002,,,,, 14:32:23.235> [2018.03.10 11:32:24]-[MODEM] Modem is Initializing (tmo:247) 14:32:23.905> [2018.03.10 11:32:25]-[MODEM] Modem is Initializing (tmo:246) 14:32:24.905> [2018.03.10 11:32:26]-[AppSend] No sending procedure in 100611132 sec, configured period: 1 sec 14:32:25.047> [2018.03.10 11:32:26]-[MODEM] Modem is Initializing (tmo:245) 14:32:25.922> [2018.03.10 11:32:27]-[MODEM] Modem is Initializing (tmo:244) 14:32:26.922> [2018.03.10 11:32:28]-[MODEM] Modem is Initializing (tmo:243) 14:32:27.922> [2018.03.10 11:32:29]-[MODEM] Modem is Initializing (tmo:242) 14:32:28.923> [2018.03.10 11:32:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:32:29.610> [2018.03.10 11:32:30]-[SCHED] lvcan request 14:32:29.610> [2018.03.10 11:32:30]-[DeepSleep] SLEEP MODE DISABLED! 14:32:29.860> [2018.03.10 11:32:30]-[MODEM] Modem is Initializing (tmo:241) 14:32:29.860> [2018.03.10 11:32:30]-[MODEM] Modem is Initializing (tmo:240) 14:32:29.923> [2018.03.10 11:32:31]-[MODEM] Modem is Initializing (tmo:239) 14:32:30.923> [2018.03.10 11:32:32]-[MODEM] Modem is Initializing (tmo:238) 14:32:31.923> [2018.03.10 11:32:33]-[MODEM] Modem is Initializing (tmo:237) 14:32:32.923> [2018.03.10 11:32:34]-[AppDatSav] Available 14:32:33.610> [2018.03.10 11:32:34]-[AppDatSav] 294 seconds before periodic record save 14:32:33.610> [2018.03.10 11:32:34]-[MODEM] Modem is Initializing (tmo:236) 14:32:33.923> [2018.03.10 11:32:35]-[ModeChange] GPS selected as movement indicator 14:32:34.610> [2018.03.10 11:32:35]-[ModeChange] GPS selected as movement indicator 14:32:34.610> [2018.03.10 11:32:35]-[ModeChange] GPS selected as movement indicator 14:32:34.610> [2018.03.10 11:32:35]-[ModeChange] Nothing to change 14:32:34.610> [2018.03.10 11:32:35]-[MODEM] Modem is Initializing (tmo:235) 14:32:34.923> [2018.03.10 11:32:36]-[AppSend] No sending procedure in 100611142 sec, configured period: 1 sec 14:32:35.048> [2018.03.10 11:32:36]-[MODEM] Modem is Initializing (tmo:234) 14:32:35.923> [2018.03.10 11:32:37]-[MODEM] Modem is Initializing (tmo:233) 14:32:36.923> [2018.03.10 11:32:38]-[MODEM] Modem is Initializing (tmo:232) 14:32:37.923> [2018.03.10 11:32:39]-[MODEM] Modem is Initializing (tmo:231) 14:32:38.923> [2018.03.10 11:32:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:32:39.611> [2018.03.10 11:32:40]-[SCHED] lvcan request 14:32:39.611> [2018.03.10 11:32:40]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:119[1800] pr:119[1800] ds18:0[1800] lvcan:0[1800] 14:32:39.861> [2018.03.10 11:32:40]-[MODEM] Modem is Initializing (tmo:230) 14:32:39.923> [2018.03.10 11:32:40]-[MODEM] Modem is Initializing (tmo:229) 14:32:39.923> [2018.03.10 11:32:41]-[MODEM] Modem is Initializing (tmo:228) 14:32:40.923> [2018.03.10 11:32:42]-[MODEM] Modem is Initializing (tmo:227) 14:32:41.923> [2018.03.10 11:32:43]-[MODEM] Modem is Initializing (tmo:226) 14:32:42.923> [2018.03.10 11:32:44]-[MODEM] Modem is Initializing (tmo:225) 14:32:43.923> [2018.03.10 11:32:45]-[MODEM] Modem is Initializing (tmo:224) 14:32:44.923> [2018.03.10 11:32:46]-[AppSend] No sending procedure in 100611152 sec, configured period: 1 sec 14:32:45.048> [2018.03.10 11:32:46]-[MODEM] Modem is Initializing (tmo:223) 14:32:45.923> [2018.03.10 11:32:47]-[MODEM] Modem is Initializing (tmo:222) 14:32:46.923> [2018.03.10 11:32:47]-[AutoGeo] AutoGeofence is disabled 14:32:47.048> [2018.03.10 11:32:48]-[MODEM] Modem is Initializing (tmo:221) 14:32:47.923> [2018.03.10 11:32:49]-[MODEM] Modem is Initializing (tmo:220) 14:32:48.924> [2018.03.10 11:32:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:32:49.611> [2018.03.10 11:32:50]-[SCHED] lvcan request 14:32:49.611> [2018.03.10 11:32:50]-[MODEM] Modem is Initializing (tmo:219) 14:32:49.924> [2018.03.10 11:32:50]-[MODEM] Modem is Initializing (tmo:219) 14:32:49.924> [2018.03.10 11:32:51]-[MODEM] Modem is Initializing (tmo:217) 14:32:50.924> [2018.03.10 11:32:52]-[MODEM] Modem is Initializing (tmo:216) 14:32:51.924> [2018.03.10 11:32:53]-[MODEM] Modem is Initializing (tmo:215) 14:32:52.924> [2018.03.10 11:32:54]-[MODEM] Modem is Initializing (tmo:214) 14:32:53.924> [2018.03.10 11:32:55]-[MODEM] Modem is Initializing (tmo:213) 14:32:54.924> [2018.03.10 11:32:56]-[AppSend] No sending procedure in 100611162 sec, configured period: 1 sec 14:32:55.049> [2018.03.10 11:32:56]-[MODEM] Modem is Initializing (tmo:212) 14:32:55.924> [2018.03.10 11:32:57]-[MODEM] Modem is Initializing (tmo:211) 14:32:56.924> [2018.03.10 11:32:58]-[MODEM] Modem is Initializing (tmo:210) 14:32:57.924> [2018.03.10 11:32:59]-[MODEM] Modem is Initializing (tmo:209) 14:32:58.924> [2018.03.10 11:33:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:32:59.049> [2018.03.10 11:33:00]-[AppSend] Checking SMS Data Sending 14:32:59.049> [2018.03.10 11:33:00]-[AppSend] SMS Data Sending is Disabled 14:32:59.049> [2018.03.10 11:33:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:32:59.612> [2018.03.10 11:33:00]-[SCHED] lvcan request 14:32:59.612> [2018.03.10 11:33:00]-[MODEM] Modem is Initializing (tmo:208) 14:32:59.924> [2018.03.10 11:33:00]-[MODEM] Modem is Initializing (tmo:208) 14:32:59.924> [2018.03.10 11:33:01]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:140[1800] pr:140[1800] ds18:0[1800] lvcan:0[1800] 14:33:00.862> [2018.03.10 11:33:01]-[MODEM] Modem is Initializing (tmo:206) 14:33:00.924> [2018.03.10 11:33:02]-[MODEM] Modem is Initializing (tmo:205) 14:33:01.924> [2018.03.10 11:33:03]-[MODEM] Modem is Initializing (tmo:204) 14:33:02.924> [2018.03.10 11:33:04]-[DEBUG] 14:33:03.549> 14:33:03.549> UARTTxFullCnt: 0 14:33:03.549> SchTaskRunCnt: 10 14:33:03.549> UARTTskRunCnt: 1001 14:33:03.549> AppSaveRunCnt: 10 14:33:03.549> AppSendRunCnt: 10 14:33:03.549> ValidNMEAPkts: 110 14:33:03.549> RS232 procesed: 0 14:33:03.549> RS232 droped : 0 14:33:03.549> RS485 procesed: 0 14:33:03.549> RS485 droped : 0 14:33:03.549> 14:33:03.549> [2018.03.10 11:33:04]-[MODEM] Modem is Initializing (tmo:203) 14:33:03.924> [2018.03.10 11:33:05]-[MODEM] Modem is Initializing (tmo:202) 14:33:04.924> [2018.03.10 11:33:06]-[AppSend] No sending procedure in 100611172 sec, configured period: 1 sec 14:33:05.049> [2018.03.10 11:33:06]-[MODEM] Modem is Initializing (tmo:201) 14:33:05.924> [2018.03.10 11:33:07]-[MODEM] Modem is Initializing (tmo:200) 14:33:06.924> [2018.03.10 11:33:08]-[MODEM] Modem is Initializing (tmo:199) 14:33:07.924> [2018.03.10 11:33:09]-[MODEM] Modem is Initializing (tmo:198) 14:33:08.925> [2018.03.10 11:33:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:33:09.612> [2018.03.10 11:33:10]-[SCHED] lvcan request 14:33:09.612> [2018.03.10 11:33:10]-[MODEM] Modem is Initializing (tmo:197) 14:33:09.925> [2018.03.10 11:33:10]-[MODEM] Modem is Initializing (tmo:197) 14:33:09.925> [2018.03.10 11:33:11]-[MODEM] Modem is Initializing (tmo:195) 14:33:10.925> [2018.03.10 11:33:12]-[MODEM] Modem is Initializing (tmo:194) 14:33:11.925> [2018.03.10 11:33:13]-[MODEM] Modem is Initializing (tmo:193) 14:33:12.925> [2018.03.10 11:33:14]-[MODEM] Modem is Initializing (tmo:192) 14:33:13.925> [2018.03.10 11:33:15]-[MODEM] Modem is Initializing (tmo:191) 14:33:14.925> [2018.03.10 11:33:16]-[AppSend] No sending procedure in 100611182 sec, configured period: 1 sec 14:33:15.050> [2018.03.10 11:33:16]-[MODEM] Modem is Initializing (tmo:190) 14:33:15.925> [2018.03.10 11:33:17]-[MODEM] Modem is Initializing (tmo:189) 14:33:16.925> [2018.03.10 11:33:18]-[MODEM] Modem is Initializing (tmo:188) 14:33:17.925> [2018.03.10 11:33:19]-[MODEM] Modem is Initializing (tmo:187) 14:33:18.925> [2018.03.10 11:33:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:33:19.613> [2018.03.10 11:33:20]-[SCHED] DTC request 14:33:19.613> [2018.03.10 11:33:20]-[LLS.485] Go to SCAN procedure 14:33:19.675> [2018.03.10 11:33:20]-[MODEM] Modem is Initializing (tmo:186) 14:33:19.925> [2018.03.10 11:33:20]-[MODEM] Modem is Initializing (tmo:186) 14:33:19.925> [2018.03.10 11:33:21]-[ModeChange] GPS selected as movement indicator 14:33:20.613> [2018.03.10 11:33:21]-[ModeChange] GPS selected as movement indicator 14:33:20.613> [2018.03.10 11:33:21]-[ModeChange] GPS selected as movement indicator 14:33:20.613> [2018.03.10 11:33:21]-[ModeChange] Nothing to change 14:33:20.613> [2018.03.10 11:33:21]-[MODEM] Modem is Initializing (tmo:184) 14:33:20.925> [2018.03.10 11:33:22]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:161[1800] pr:161[1800] ds18:0[1800] lvcan:0[1800] 14:33:21.925> [2018.03.10 11:33:22]-[MODEM] Modem is Initializing (tmo:183) 14:33:21.925> [2018.03.10 11:33:23]-[MODEM] Modem is Initializing (tmo:182) 14:33:22.925> [2018.03.10 11:33:24]-[PERIODIC.GPS.INFO] 14:33:23.613> 14:33:23.613> Status : 3 (gps on) 14:33:23.613> Sat : 15 14:33:23.613> Lat : 54.558194 14:33:23.613> Long : 36.293139 14:33:23.613> Alt : 244 14:33:23.613> Speed : 0 14:33:23.613> Angle : 0 14:33:23.613> 14:33:23.613> GPS TMO : 900 14:33:23.613> GPS Fix : 1 14:33:23.613> NMEA Flag : 1 14:33:23.613> Time Sync : 1 14:33:23.613> Baudrate : 115200 14:33:23.613> Valid Pkts: 110 14:33:23.613> 14:33:23.613> GPS AP : 1 14:33:23.613> GPS_OFF : 1 14:33:23.613> RAM_GPS_ON: 1 14:33:23.613> SN Config : 1 14:33:23.613> SN State : 2 14:33:23.613> Log2Srv : 0 14:33:23.613> 14:33:23.613> [2018.03.10 11:33:24]-[MODEM] Modem is Initializing (tmo:181) 14:33:23.925> [2018.03.10 11:33:25]-[MODEM] Modem is Initializing (tmo:180) 14:33:24.925> [2018.03.10 11:33:26]-[AppSend] No sending procedure in 100611192 sec, configured period: 1 sec 14:33:25.050> [2018.03.10 11:33:26]-[MODEM] Modem is Initializing (tmo:179) 14:33:25.925> [2018.03.10 11:33:27]-[MODEM] Modem is Initializing (tmo:178) 14:33:26.925> [2018.03.10 11:33:28]-[MODEM] Modem is Initializing (tmo:177) 14:33:27.925> [2018.03.10 11:33:29]-[MODEM] Modem is Initializing (tmo:176) 14:33:28.925> [2018.03.10 11:33:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:33:29.613> [2018.03.10 11:33:30]-[SCHED] lvcan request 14:33:29.613> [2018.03.10 11:33:30]-[MODEM] Modem is Initializing (tmo:175) 14:33:29.926> [2018.03.10 11:33:30]-[MODEM] Modem is Initializing (tmo:175) 14:33:29.926> [2018.03.10 11:33:30]-[DeepSleep] SLEEP MODE DISABLED! 14:33:29.926> [2018.03.10 11:33:31]-[MODEM] Modem is Initializing (tmo:173) 14:33:30.926> [2018.03.10 11:33:32]-[MODEM] Modem is Initializing (tmo:172) 14:33:31.926> [2018.03.10 11:33:33]-[MODEM] Modem is Initializing (tmo:171) 14:33:32.926> [2018.03.10 11:33:34]-[MODEM] Modem is Initializing (tmo:170) 14:33:33.926> [2018.03.10 11:33:34]-[AutoGeo] AutoGeofence is disabled 14:33:34.051> [2018.03.10 11:33:35]-[AppDatSav] Available 14:33:34.613> [2018.03.10 11:33:35]-[AppDatSav] 233 seconds before periodic record save 14:33:34.613> [2018.03.10 11:33:35]-[MODEM] Modem is Initializing (tmo:169) 14:33:34.926> [2018.03.10 11:33:36]-[AppSend] No sending procedure in 100611202 sec, configured period: 1 sec 14:33:35.051> [2018.03.10 11:33:36]-[MODEM] Modem is Initializing (tmo:168) 14:33:35.926> [2018.03.10 11:33:37]-[MODEM] Modem is Initializing (tmo:167) 14:33:36.926> [2018.03.10 11:33:38]-[MODEM] Modem is Initializing (tmo:166) 14:33:37.926> [2018.03.10 11:33:39]-[MODEM] Modem is Initializing (tmo:165) 14:33:38.926> [2018.03.10 11:33:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:33:39.614> [2018.03.10 11:33:40]-[SCHED] lvcan request 14:33:39.614> [2018.03.10 11:33:40]-[MODEM] Modem is Initializing (tmo:164) 14:33:39.926> [2018.03.10 11:33:40]-[MODEM] Modem is Initializing (tmo:164) 14:33:39.926> [2018.03.10 11:33:41]-[MODEM] Modem is Initializing (tmo:162) 14:33:40.926> [2018.03.10 11:33:42]-[MODEM] Modem is Initializing (tmo:161) 14:33:41.926> [2018.03.10 11:33:43]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:182[1800] pr:182[1800] ds18:0[1800] lvcan:0[1800] 14:33:42.926> [2018.03.10 11:33:43]-[MODEM] Modem is Initializing (tmo:160) 14:33:42.926> [2018.03.10 11:33:44]-[DEBUG] 14:33:43.739> 14:33:43.739> UARTTxFullCnt: 0 14:33:43.739> SchTaskRunCnt: 10 14:33:43.739> UARTTskRunCnt: 991 14:33:43.739> AppSaveRunCnt: 10 14:33:43.739> AppSendRunCnt: 10 14:33:43.739> ValidNMEAPkts: 110 14:33:43.739> RS232 procesed: 0 14:33:43.739> RS232 droped : 0 14:33:43.739> RS485 procesed: 0 14:33:43.739> RS485 droped : 0 14:33:43.739> 14:33:43.739> [2018.03.10 11:33:44]-[MODEM] Modem is Initializing (tmo:159) 14:33:43.926> [2018.03.10 11:33:45]-[MODEM] Modem is Initializing (tmo:158) 14:33:44.926> [2018.03.10 11:33:46]-[AppSend] No sending procedure in 100611212 sec, configured period: 1 sec 14:33:45.051> [2018.03.10 11:33:46]-[MODEM] Modem is Initializing (tmo:157) 14:33:45.926> [2018.03.10 11:33:47]-[MODEM] Modem is Initializing (tmo:156) 14:33:46.926> [2018.03.10 11:33:48]-[MODEM] Modem is Initializing (tmo:155) 14:33:47.926> [2018.03.10 11:33:49]-[MODEM] Modem is Initializing (tmo:154) 14:33:48.926> [2018.03.10 11:33:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:33:49.614> [2018.03.10 11:33:50]-[SCHED] lvcan request 14:33:49.614> [2018.03.10 11:33:50]-[Scheduler] GC is scheduled after: 6750 sec 14:33:49.614> [2018.03.10 11:33:50]-[MODEM] Modem is Initializing (tmo:153) 14:33:49.927> [2018.03.10 11:33:50]-[MODEM] Modem is Initializing (tmo:153) 14:33:49.927> [2018.03.10 11:33:51]-[MODEM] Modem is Initializing (tmo:151) 14:33:50.927> [2018.03.10 11:33:52]-[MODEM] Modem is Initializing (tmo:150) 14:33:51.927> [2018.03.10 11:33:53]-[MODEM] Modem is Initializing (tmo:149) 14:33:52.927> [2018.03.10 11:33:54]-[MODEM] Modem is Initializing (tmo:148) 14:33:53.927> [2018.03.10 11:33:55]-[MODEM] Modem is Initializing (tmo:147) 14:33:54.927> [2018.03.10 11:33:56]-[AppSend] No sending procedure in 100611222 sec, configured period: 1 sec 14:33:55.052> [2018.03.10 11:33:56]-[MODEM] Modem is Initializing (tmo:146) 14:33:55.927> [2018.03.10 11:33:57]-[MODEM] Modem is Initializing (tmo:145) 14:33:56.927> [2018.03.10 11:33:58]-[MODEM] Modem is Initializing (tmo:144) 14:33:57.927> [2018.03.10 11:33:59]-[MODEM] Modem is Initializing (tmo:143) 14:33:58.927> [2018.03.10 11:34:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:33:59.052> [2018.03.10 11:34:00]-[AppSend] Checking SMS Data Sending 14:33:59.052> [2018.03.10 11:34:00]-[AppSend] SMS Data Sending is Disabled 14:33:59.052> [2018.03.10 11:34:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:33:59.614> [2018.03.10 11:34:00]-[SCHED] lvcan request 14:33:59.614> [2018.03.10 11:34:00]-[MODEM] Modem is Initializing (tmo:142) 14:33:59.927> [2018.03.10 11:34:00]-[MODEM] Modem is Initializing (tmo:142) 14:33:59.927> [2018.03.10 11:34:01]-[MODEM] Modem is Initializing (tmo:140) 14:34:00.927> [2018.03.10 11:34:02]-[MODEM] Modem is Initializing (tmo:139) 14:34:01.927> [2018.03.10 11:34:03]-[MODEM] Modem is Initializing (tmo:138) 14:34:02.927> [2018.03.10 11:34:04]-[MODEM] Modem is Initializing (tmo:137) 14:34:03.927> [2018.03.10 11:34:04]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:203[1800] pr:203[1800] ds18:0[1800] lvcan:0[1800] 14:34:03.927> [2018.03.10 11:34:05]-[MODEM] Modem is Initializing (tmo:136) 14:34:04.927> [2018.03.10 11:34:06]-[AppSend] No sending procedure in 100611232 sec, configured period: 1 sec 14:34:05.052> [2018.03.10 11:34:06]-[MODEM] Modem is Initializing (tmo:135) 14:34:05.927> [2018.03.10 11:34:07]-[ModeChange] GPS selected as movement indicator 14:34:06.427> [2018.03.10 11:34:07]-[ModeChange] GPS selected as movement indicator 14:34:06.490> [2018.03.10 11:34:07]-[ModeChange] GPS selected as movement indicator 14:34:06.615> [2018.03.10 11:34:07]-[ModeChange] Nothing to change 14:34:06.615> [2018.03.10 11:34:07]-[MODEM] Modem is Initializing (tmo:134) 14:34:06.927> [2018.03.10 11:34:08]-[MODEM] Modem is Initializing (tmo:133) 14:34:07.927> [2018.03.10 11:34:09]-[MODEM] Modem is Initializing (tmo:132) 14:34:08.927> [2018.03.10 11:34:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:34:09.615> [2018.03.10 11:34:10]-[SCHED] lvcan request 14:34:09.615> [2018.03.10 11:34:10]-[MODEM] Modem is Initializing (tmo:131) 14:34:09.927> [2018.03.10 11:34:10]-[MODEM] Modem is Initializing (tmo:131) 14:34:09.927> [2018.03.10 11:34:11]-[MODEM] Modem is Initializing (tmo:129) 14:34:10.928> [2018.03.10 11:34:12]-[MODEM] Modem is Initializing (tmo:128) 14:34:11.928> [2018.03.10 11:34:13]-[MODEM] Modem is Initializing (tmo:127) 14:34:12.928> [2018.03.10 11:34:14]-[MODEM] Modem is Initializing (tmo:126) 14:34:13.928> [2018.03.10 11:34:15]-[MODEM] Modem is Initializing (tmo:125) 14:34:14.928> [2018.03.10 11:34:16]-[AppSend] No sending procedure in 100611242 sec, configured period: 1 sec 14:34:15.053> [2018.03.10 11:34:16]-[MODEM] Modem is Initializing (tmo:124) 14:34:15.928> [2018.03.10 11:34:17]-[MODEM] Modem is Initializing (tmo:123) 14:34:16.928> [2018.03.10 11:34:18]-[MODEM] Modem is Initializing (tmo:122) 14:34:17.928> [2018.03.10 11:34:19]-[MODEM] Modem is Initializing (tmo:121) 14:34:18.928> [2018.03.10 11:34:20]-[StackCheck] StackInfo: 14:34:19.615> 14:34:19.615> TaskId: 2 Name: TRACE Stack: 0x20001C58 Free: 140/ 576 @ StackInfo 14:34:19.615> TaskId: 3 Name: LVCPAP Stack: 0x2000F1B0 Free: 276/ 664 @ StackInfo 14:34:19.615> TaskId: 4 Name: EVTGEN Stack: 0x200067C0 Free: 596/ 872 @ StackInfo 14:34:19.678> TaskId: 5 Name: SCHEDU Stack: 0x20004DE8 Free: 416/ 768 @ StackInfo 14:34:19.678> TaskId: 6 Name: HEARTB Stack: 0x200050E8 Free: 48/ 128 @ StackInfo 14:34:19.678> TaskId: 7 Name: MODDAT Stack: 0x20005168 Free: 296/ 384 @ StackInfo 14:34:19.678> TaskId: 8 Name: UARTPR Stack: 0x20002A28 Free: 904/1640 @ StackInfo 14:34:19.678> TaskId: 9 Name: DSLEEP Stack: 0x20009B18 Free: 312/ 768 @ StackInfo 14:34:19.678> TaskId: 10 Name: PWRMAN Stack: 0x2000C678 Free: 576/ 768 @ StackInfo 14:34:19.678> TaskId: 11 Name: DATSAV Stack: 0x2000B020 Free: 352/1024 @ StackInfo 14:34:19.678> TaskId: 12 Name: GSMUPD Stack: 0x2000CD00 Free: 856/1504 @ StackInfo 14:34:19.740> TaskId: 13 Name: CHGPRS Stack: 0x2000AC20 Free: 424/1024 @ StackInfo 14:34:19.740> TaskId: 14 Name: OPERS Stack: 0x2000B878 Free: 784/1024 @ StackInfo 14:34:19.740> TaskId: 15 Name: APPSND Stack: 0x2000B420 Free: 864/1096 @ StackInfo 14:34:19.740> TaskId: 16 Name: TCPCMD Stack: 0x2000DD58 Free: 1700/1856 @ StackInfo 14:34:19.740> TaskId: 17 Name: WDOG Stack: 0x20000DB8 Free: 56/ 296 @ StackInfo 14:34:19.740> TaskId: 18 Name: SMSEVT Stack: 0x2000D350 Free: 624/1096 @ StackInfo 14:34:19.740> TaskId: 19 Name: INITM Stack: 0x2000C978 Free: 536/ 904 @ StackInfo 14:34:19.740> 14:34:19.740> [2018.03.10 11:34:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:34:19.740> [2018.03.10 11:34:20]-[SCHED] DTC request 14:34:19.803> [2018.03.10 11:34:20]-[MODEM] Modem is Initializing (tmo:120) 14:34:19.928> [2018.03.10 11:34:20]-[MODEM] Modem is Initializing (tmo:120) 14:34:19.928> [2018.03.10 11:34:21]-[MODEM] Modem is Initializing (tmo:118) 14:34:20.928> [2018.03.10 11:34:21]-[AutoGeo] AutoGeofence is disabled 14:34:21.053> [2018.03.10 11:34:22]-[MODEM] Modem is Initializing (tmo:117) 14:34:21.928> [2018.03.10 11:34:23]-[MODEM] Modem is Initializing (tmo:116) 14:34:22.928> [2018.03.10 11:34:24]-[MODEM] Modem is Initializing (tmo:115) 14:34:23.928> [2018.03.10 11:34:25]-[DEBUG] 14:34:24.053> 14:34:24.053> UARTTxFullCnt: 0 14:34:24.053> SchTaskRunCnt: 10 14:34:24.053> UARTTskRunCnt: 1000 14:34:24.053> AppSaveRunCnt: 10 14:34:24.053> AppSendRunCnt: 10 14:34:24.053> ValidNMEAPkts: 110 14:34:24.053> RS232 procesed: 0 14:34:24.053> RS232 droped : 0 14:34:24.053> RS485 procesed: 0 14:34:24.053> RS485 droped : 0 14:34:24.053> 14:34:24.053> [2018.03.10 11:34:25]-[PowerMan] Ext:12293 mV; BatV:0 mV; BatI:0 mA; GPS:3054 mV; Modem:3865 mV; 14:34:24.053> [2018.03.10 11:34:25]-[MODEM] Modem is Initializing (tmo:114) 14:34:24.928> [2018.03.10 11:34:25]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:224[1800] pr:224[1800] ds18:0[1800] lvcan:0[1800] 14:34:24.928> [2018.03.10 11:34:26]-[AppSend] No sending procedure in 100611252 sec, configured period: 1 sec 14:34:25.053> [2018.03.10 11:34:26]-[MODEM] Modem is Initializing (tmo:113) 14:34:25.928> [2018.03.10 11:34:27]-[MODEM] Modem is Initializing (tmo:112) 14:34:26.928> [2018.03.10 11:34:28]-[MODEM] Modem is Initializing (tmo:111) 14:34:27.928> [2018.03.10 11:34:29]-[MODEM] Modem is Initializing (tmo:110) 14:34:28.928> [2018.03.10 11:34:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:34:29.616> [2018.03.10 11:34:30]-[SCHED] lvcan request 14:34:29.616> [2018.03.10 11:34:30]-[LLS.485] Go to SCAN procedure 14:34:29.678> [2018.03.10 11:34:30]-[MODEM] Modem is Initializing (tmo:109) 14:34:29.928> [2018.03.10 11:34:30]-[MODEM] Modem is Initializing (tmo:109) 14:34:29.928> [2018.03.10 11:34:30]-[DeepSleep] SLEEP MODE DISABLED! 14:34:29.928> [2018.03.10 11:34:31]-[MODEM] Modem is Initializing (tmo:107) 14:34:30.929> [2018.03.10 11:34:32]-[MODEM] Modem is Initializing (tmo:106) 14:34:31.929> [2018.03.10 11:34:33]-[MODEM] Modem is Initializing (tmo:105) 14:34:32.929> [2018.03.10 11:34:34]-[MODEM] Modem is Initializing (tmo:104) 14:34:33.929> [2018.03.10 11:34:35]-[MODEM] Modem is Initializing (tmo:103) 14:34:34.929> [2018.03.10 11:34:36]-[AppSend] No sending procedure in 100611262 sec, configured period: 1 sec 14:34:35.054> [2018.03.10 11:34:36]-[AppDatSav] Available 14:34:35.616> [2018.03.10 11:34:36]-[AppDatSav] 172 seconds before periodic record save 14:34:35.679> [2018.03.10 11:34:36]-[MODEM] Modem is Initializing (tmo:102) 14:34:35.929> [2018.03.10 11:34:37]-[MODEM] Modem is Initializing (tmo:101) 14:34:36.929> [2018.03.10 11:34:38]-[MODEM] Modem is Initializing (tmo:100) 14:34:37.907> [2018.03.10 11:34:39]-[MODEM] Modem is Initializing (tmo:99) 14:34:38.906> [2018.03.10 11:34:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:34:39.657> [2018.03.10 11:34:40]-[SCHED] lvcan request 14:34:39.657> [2018.03.10 11:34:40]-[MODEM] Modem is Initializing (tmo:98) 14:34:39.907> [2018.03.10 11:34:40]-[MODEM] Modem is Initializing (tmo:98) 14:34:39.907> [2018.03.10 11:34:41]-[MODEM] Modem is Initializing (tmo:96) 14:34:40.907> [2018.03.10 11:34:42]-[MODEM] Modem is Initializing (tmo:95) 14:34:41.908> [2018.03.10 11:34:43]-[MODEM] Modem is Initializing (tmo:94) 14:34:42.929> [2018.03.10 11:34:44]-[MODEM] Modem is Initializing (tmo:93) 14:34:43.929> [2018.03.10 11:34:45]-[MODEM] Modem is Initializing (tmo:92) 14:34:44.929> [2018.03.10 11:34:46]-[AppSend] No sending procedure in 100611272 sec, configured period: 1 sec 14:34:45.054> [2018.03.10 11:34:46]-[MODEM] Modem is Initializing (tmo:91) 14:34:45.929> [2018.03.10 11:34:46]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:245[1800] pr:245[1800] ds18:0[1800] lvcan:0[1800] 14:34:45.929> [2018.03.10 11:34:47]-[MODEM] Modem is Initializing (tmo:90) 14:34:46.929> [2018.03.10 11:34:48]-[MODEM] Modem is Initializing (tmo:89) 14:34:47.929> [2018.03.10 11:34:49]-[MODEM] Modem is Initializing (tmo:88) 14:34:49.023> [2018.03.10 11:34:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:34:49.617> [2018.03.10 11:34:50]-[SCHED] lvcan request 14:34:49.617> [2018.03.10 11:34:50]-[MODEM] Modem is Initializing (tmo:87) 14:34:49.929> [2018.03.10 11:34:50]-[MODEM] Modem is Initializing (tmo:87) 14:34:49.929> [2018.03.10 11:34:51]-[MODEM] Modem is Initializing (tmo:85) 14:34:50.929> [2018.03.10 11:34:52]-[MODEM] Modem is Initializing (tmo:84) 14:34:51.930> [2018.03.10 11:34:53]-[ModeChange] GPS selected as movement indicator 14:34:52.492> [2018.03.10 11:34:53]-[ModeChange] GPS selected as movement indicator 14:34:52.492> [2018.03.10 11:34:53]-[ModeChange] GPS selected as movement indicator 14:34:52.617> [2018.03.10 11:34:53]-[ModeChange] Nothing to change 14:34:52.617> [2018.03.10 11:34:53]-[MODEM] Modem is Initializing (tmo:83) 14:34:52.930> [2018.03.10 11:34:54]-[MODEM] Modem is Initializing (tmo:82) 14:34:53.930> [2018.03.10 11:34:55]-[MODEM] Modem is Initializing (tmo:81) 14:34:54.930> [2018.03.10 11:34:56]-[AppSend] No sending procedure in 100611282 sec, configured period: 1 sec 14:34:55.055> [2018.03.10 11:34:56]-[MODEM] Modem is Initializing (tmo:80) 14:34:55.930> [2018.03.10 11:34:57]-[MODEM] Modem is Initializing (tmo:79) 14:34:56.930> [2018.03.10 11:34:58]-[MODEM] Modem is Initializing (tmo:78) 14:34:57.930> [2018.03.10 11:34:59]-[MODEM] Modem is Initializing (tmo:77) 14:34:58.930> [2018.03.10 11:35:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:34:59.055> [2018.03.10 11:35:00]-[AppSend] Checking SMS Data Sending 14:34:59.117> [2018.03.10 11:35:00]-[AppSend] SMS Data Sending is Disabled 14:34:59.117> [2018.03.10 11:35:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:34:59.617> [2018.03.10 11:35:00]-[SCHED] lvcan request 14:34:59.680> [2018.03.10 11:35:00]-[MODEM] Modem is Initializing (tmo:76) 14:34:59.930> [2018.03.10 11:35:00]-[MODEM] Modem is Initializing (tmo:76) 14:34:59.930> [2018.03.10 11:35:01]-[MODEM] Modem is Initializing (tmo:74) 14:35:00.930> [2018.03.10 11:35:02]-[AppSend] No sending procedure in 100611288 sec, configured period: 1 sec 14:35:01.117> [2018.03.10 11:35:02]-[MODEM] Modem is Initializing (tmo:73) 14:35:01.930> [2018.03.10 11:35:03]-[MODEM] Modem is Initializing (tmo:72) 14:35:02.930> [2018.03.10 11:35:04]-[MODEM] Modem is Initializing (tmo:71) 14:35:03.930> [2018.03.10 11:35:05]-[DEBUG] 14:35:04.243> 14:35:04.243> UARTTxFullCnt: 0 14:35:04.243> SchTaskRunCnt: 10 14:35:04.243> UARTTskRunCnt: 990 14:35:04.243> AppSaveRunCnt: 10 14:35:04.243> AppSendRunCnt: 10 14:35:04.243> ValidNMEAPkts: 110 14:35:04.243> RS232 procesed: 0 14:35:04.243> RS232 droped : 0 14:35:04.243> RS485 procesed: 0 14:35:04.243> RS485 droped : 0 14:35:04.243> 14:35:04.243> [2018.03.10 11:35:05]-[MODEM] Modem is Initializing (tmo:70) 14:35:04.930> [2018.03.10 11:35:06]-[MODEM] Modem is Initializing (tmo:69) 14:35:05.930> [2018.03.10 11:35:07]-[MODEM] Modem is Initializing (tmo:68) 14:35:06.930> [2018.03.10 11:35:07]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:266[1800] pr:266[1800] ds18:0[1800] lvcan:0[1800] 14:35:06.930> [2018.03.10 11:35:08]-[MODEM] Modem is Initializing (tmo:67) 14:35:07.930> [2018.03.10 11:35:08]-[AutoGeo] AutoGeofence is disabled 14:35:08.055> [2018.03.10 11:35:09]-[MODEM] Modem is Initializing (tmo:66) 14:35:08.930> [2018.03.10 11:35:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:35:09.618> [2018.03.10 11:35:10]-[SCHED] lvcan request 14:35:09.618> [2018.03.10 11:35:10]-[MODEM] Modem is Initializing (tmo:65) 14:35:09.930> [2018.03.10 11:35:10]-[MODEM] Modem is Initializing (tmo:65) 14:35:09.930> [2018.03.10 11:35:11]-[MODEM] Modem is Initializing (tmo:63) 14:35:10.930> [2018.03.10 11:35:12]-[AppSend] No sending procedure in 100611298 sec, configured period: 1 sec 14:35:11.118> [2018.03.10 11:35:12]-[MODEM] Modem is Initializing (tmo:62) 14:35:11.931> [2018.03.10 11:35:13]-[MODEM] Modem is Initializing (tmo:61) 14:35:12.931> [2018.03.10 11:35:14]-[MODEM] Modem is Initializing (tmo:60) 14:35:13.931> [2018.03.10 11:35:15]-[MODEM] Modem is Initializing (tmo:59) 14:35:14.931> [2018.03.10 11:35:16]-[MODEM] Modem is Initializing (tmo:58) 14:35:15.931> [2018.03.10 11:35:17]-[MODEM] Modem is Initializing (tmo:57) 14:35:16.931> [2018.03.10 11:35:18]-[MODEM] Modem is Initializing (tmo:56) 14:35:17.931> [2018.03.10 11:35:19]-[MODEM] Modem is Initializing (tmo:55) 14:35:18.931> [2018.03.10 11:35:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:35:19.618> [2018.03.10 11:35:20]-[SCHED] DTC request 14:35:19.681> [2018.03.10 11:35:20]-[MODEM] Modem is Initializing (tmo:54) 14:35:19.931> [2018.03.10 11:35:20]-[MODEM] Modem is Initializing (tmo:54) 14:35:19.931> [2018.03.10 11:35:21]-[MODEM] Modem is Initializing (tmo:52) 14:35:20.931> [2018.03.10 11:35:22]-[AppSend] No sending procedure in 100611308 sec, configured period: 1 sec 14:35:21.118> [2018.03.10 11:35:22]-[MODEM] Modem is Initializing (tmo:51) 14:35:21.931> [2018.03.10 11:35:23]-[MODEM] Modem is Initializing (tmo:50) 14:35:22.931> [2018.03.10 11:35:24]-[MODEM] Modem is Initializing (tmo:49) 14:35:23.931> [2018.03.10 11:35:25]-[PERIODIC.NMEA.INFO] 14:35:24.431> 14:35:24.431> Total Pkts : 5955 14:35:24.431> Bad Chksum : 0 14:35:24.431> No Chksum : 6 14:35:24.431> 14:35:24.431> HDOP Cycles: 482 14:35:24.431> HDOP Limit : 4.0 14:35:24.431> HDOP Actual: 0.7 14:35:24.431> HDOP Mean : 0.8 14:35:24.431> HDOP Min : 0.7 14:35:24.431> HDOP Max : 1.9 14:35:24.431> PDOP Actual: 1.2 14:35:24.431> HDOP Rejct : 0 14:35:24.431> HDOP Taken : 0 14:35:24.431> HDOP Timer : 60 14:35:24.431> HDOP Ready : 0 14:35:24.431> 14:35:24.431> Total sats GPS : 14 Tracked: 8 30 15 28 7 13 14:35:24.431> Total sats GLONASS: 10 Tracked: 67 76 75 66 85 84 14:35:24.931> 14:35:24.931> Bad Time: 0 14:35:24.931> Bad Lat : 0 14:35:24.931> Bad Lon : 0 14:35:24.931> Bad Spd : 0 14:35:24.931> Bad Angl: 0 14:35:24.931> Bad HDOP: 0 14:35:24.931> 14:35:24.931> GPS jmps: 0 14:35:24.931> Filtered: 539 14:35:24.931> Rejected: 4 14:35:24.931> 14:35:24.931> [2018.03.10 11:35:25]-[MODEM] Modem is Initializing (tmo:48) 14:35:24.931> [2018.03.10 11:35:26]-[MODEM] Modem is Initializing (tmo:47) 14:35:25.931> [2018.03.10 11:35:27]-[MODEM] Modem is Initializing (tmo:46) 14:35:26.931> [2018.03.10 11:35:28]-[MODEM] Modem is Initializing (tmo:45) 14:35:27.931> [2018.03.10 11:35:28]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:287[1800] pr:287[1800] ds18:0[1800] lvcan:0[1800] 14:35:27.931> [2018.03.10 11:35:29]-[MODEM] Modem is Initializing (tmo:44) 14:35:28.931> [2018.03.10 11:35:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:35:29.619> [2018.03.10 11:35:30]-[SCHED] lvcan request 14:35:29.619> [2018.03.10 11:35:30]-[MODEM] Modem is Initializing (tmo:43) 14:35:29.931> [2018.03.10 11:35:30]-[MODEM] Modem is Initializing (tmo:43) 14:35:29.931> [2018.03.10 11:35:30]-[DeepSleep] SLEEP MODE DISABLED! 14:35:29.994> [2018.03.10 11:35:31]-[MODEM] Modem is Initializing (tmo:41) 14:35:30.931> [2018.03.10 11:35:32]-[AppSend] No sending procedure in 100611318 sec, configured period: 1 sec 14:35:31.119> [2018.03.10 11:35:32]-[MODEM] Modem is Initializing (tmo:40) 14:35:31.932> [2018.03.10 11:35:33]-[MODEM] Modem is Initializing (tmo:39) 14:35:32.932> [2018.03.10 11:35:34]-[MODEM] Modem is Initializing (tmo:38) 14:35:33.932> [2018.03.10 11:35:35]-[MODEM] Modem is Initializing (tmo:37) 14:35:34.932> [2018.03.10 11:35:36]-[MODEM] Modem is Initializing (tmo:36) 14:35:35.932> [2018.03.10 11:35:37]-[AppDatSav] Available 14:35:36.619> [2018.03.10 11:35:37]-[AppDatSav] 111 seconds before periodic record save 14:35:36.619> [2018.03.10 11:35:37]-[MODEM] Modem is Initializing (tmo:35) 14:35:36.932> [2018.03.10 11:35:38]-[MODEM] Modem is Initializing (tmo:34) 14:35:37.932> [2018.03.10 11:35:39]-[ModeChange] GPS selected as movement indicator 14:35:38.619> [2018.03.10 11:35:39]-[ModeChange] GPS selected as movement indicator 14:35:38.619> [2018.03.10 11:35:39]-[ModeChange] GPS selected as movement indicator 14:35:38.619> [2018.03.10 11:35:39]-[ModeChange] Nothing to change 14:35:38.619> [2018.03.10 11:35:39]-[MODEM] Modem is Initializing (tmo:33) 14:35:38.932> [2018.03.10 11:35:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:35:39.619> [2018.03.10 11:35:40]-[SCHED] lvcan request 14:35:39.619> [2018.03.10 11:35:40]-[LLS.485] Go to SCAN procedure 14:35:39.619> [2018.03.10 11:35:40]-[MODEM] ok got after 10801 ms cmd event after 0 ms 14:35:39.932> [2018.03.10 11:35:40]-[MODEM] Modem is Initializing (tmo:32) 14:35:39.932> [2018.03.10 11:35:40]-[MODEM] Modem is Initializing (tmo:32) 14:35:39.932> [2018.03.10 11:35:40]-[MODEM] RetVal: -1 14:35:39.932> [2018.03.10 11:35:40]-[GPRS] GPRS not detected, times left: 3 14:35:40.010> [2018.03.10 11:35:40]-[GPRS] Process mutex released 14:35:40.010> [2018.03.10 11:35:40]-[InitModem] Process mutex taken 14:35:40.057> [2018.03.10 11:35:41]-[BBox] Event 0x31, saved to slot 33, offset 264, crc 0x0D, timestamp 1520681740 14:35:40.057> [2018.03.10 11:35:41]-[MODEM] Modem is Initializing (tmo:30) 14:35:40.932> [2018.03.10 11:35:42]-[InitModem] Cycle: 1 of 10 14:35:41.057> [2018.03.10 11:35:42]-[InitModem] Restarting modem... 14:35:41.057> [2018.03.10 11:35:42]-[AppSend] No sending procedure in 100611328 sec, configured period: 1 sec 14:35:41.119> [2018.03.10 11:35:42]-[ModeChange] eOperType_Null 14:35:41.619> [2018.03.10 11:35:42]-[ModeChange] Home on Stop profile is updated and reloaded 14:35:41.619> [2018.03.10 11:35:42]-[ModeChange] Mode changed 14:35:41.619> [2018.03.10 11:35:42]-[MODEM] Modem is OFF. 14:35:41.932> [2018.03.10 11:35:43]-[GPRS] Weektime match detected, scheduled:[11:30] actual:[11:35] 14:35:42.307> [2018.03.10 11:35:43]-[GPRS] Waiting for Process mutex 14:35:42.557> [2018.03.10 11:35:43]-[MODEM] Modem is OFF. 14:35:42.932> [2018.03.10 11:35:44]-[MODEM] Modem is OFF. 14:35:43.932> [2018.03.10 11:35:45]-[DEBUG] 14:35:44.682> 14:35:44.682> UARTTxFullCnt: 0 14:35:44.682> SchTaskRunCnt: 10 14:35:44.682> UARTTskRunCnt: 989 14:35:44.682> AppSaveRunCnt: 10 14:35:44.682> AppSendRunCnt: 10 14:35:44.682> ValidNMEAPkts: 110 14:35:44.682> RS232 procesed: 0 14:35:44.682> RS232 droped : 0 14:35:44.682> RS485 procesed: 0 14:35:44.682> RS485 droped : 0 14:35:44.682> 14:35:44.682> [2018.03.10 11:35:45]-[MODEM] Modem is OFF. 14:35:44.932> [2018.03.10 11:35:46]-[MODEM] Modem is OFF. 14:35:45.932> [2018.03.10 11:35:47]-[MODEM] Modem is OFF. 14:35:46.932> [2018.03.10 11:35:48]-[MODEM] Modem is OFF. 14:35:47.932> [2018.03.10 11:35:49]-[MODEM] Modem is OFF. 14:35:48.932> [2018.03.10 11:35:49]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:308[1800] ds18:0[1800] lvcan:0[1800] 14:35:48.932> [2018.03.10 11:35:50]-[InitModem] Wait For Ready 14:35:49.057> [2018.03.10 11:35:50]-[MODEM] RX:1, TX:1, CTS:0, RTS:0, ON:0, PWR:1, RESET:1, DTR:0 14:35:49.057> [2018.03.10 11:35:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:35:49.620> [2018.03.10 11:35:50]-[SCHED] lvcan request 14:35:49.620> [2018.03.10 11:35:50]-[MODEM] Modem is Initializing (tmo:300) 14:35:49.932> [2018.03.10 11:35:50]-[MODEM] Modem is Initializing (tmo:300) 14:35:49.932> [2018.03.10 11:35:51]-[MODEM] Startup Detected! 14:35:50.620> [2018.03.10 11:35:51]->> +TDRVI: 9,1,5 14:35:50.620> [2018.03.10 11:35:51]-[MODEM] Modem is Initializing (tmo:298) 14:35:50.948> [2018.03.10 11:35:52]-[AppSend] No sending procedure in 100611338 sec, configured period: 1 sec 14:35:51.120> [2018.03.10 11:35:52]-[MODEM] SIM STATUS 2 SIM CARD WITHOUT PIN 14:35:51.261> [2018.03.10 11:35:52]->> +TSIM: 2 14:35:51.261> [2018.03.10 11:35:52]->> +TLOCK: "PN",5,0,"PU",5,0,"PP",5,0,"PC",5,0,"PS",5,0 14:35:51.495> [2018.03.10 11:35:52]-[MODEM] SIM STATUS 7 SIMTK RESET FINISHED 14:35:51.495> [2018.03.10 11:35:52]->> +TSIM: 7 14:35:51.495> [2018.03.10 11:35:52]-[MODEM] Modem is Initializing (tmo:297) 14:35:51.932> [2018.03.10 11:35:53]-[MODEM] Modem is Initializing (tmo:296) 14:35:52.927> [2018.03.10 11:35:54]-[MODEM] Modem is Initializing (tmo:295) 14:35:53.926> [2018.03.10 11:35:55]-[MODEM] Modem is Initializing (tmo:294) 14:35:54.910> [2018.03.10 11:35:55]-[AutoGeo] AutoGeofence is disabled 14:35:55.046> [2018.03.10 11:35:56]-[MODEM] Modem is Initializing (tmo:293) 14:35:55.924> [2018.03.10 11:35:57]-[MODEM] Modem is Initializing (tmo:292) 14:35:56.923> [2018.03.10 11:35:58]-[MODEM] Modem is Initializing (tmo:291) 14:35:57.922> [2018.03.10 11:35:59]-[MODEM] Modem is Initializing (tmo:290) 14:35:58.921> [2018.03.10 11:36:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:35:59.118> [2018.03.10 11:36:00]-[AppSend] Checking SMS Data Sending 14:35:59.118> [2018.03.10 11:36:00]-[AppSend] SMS Data Sending is Disabled 14:35:59.118> [2018.03.10 11:36:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:35:59.618> [2018.03.10 11:36:00]-[SCHED] lvcan request 14:35:59.618> [2018.03.10 11:36:00]-[MODEM] Modem Phonebook Is Ready Continue 14:35:59.739> [2018.03.10 11:36:00]->> +PBREADY 14:35:59.739> [2018.03.10 11:36:00]-<< ATE0 14:35:59.860> [2018.03.10 11:36:00]->> ATE0 14:35:59.860> 14:35:59.860> [2018.03.10 11:36:00]->> OK 14:35:59.920> [2018.03.10 11:36:00]-<< AT+CMEE=2 14:35:59.920> [2018.03.10 11:36:00]-[MODEM] Modem is Initializing (tmo:289) 14:35:59.920> [2018.03.10 11:36:00]-[MODEM] Modem is Initializing (tmo:289) 14:35:59.920> [2018.03.10 11:36:00]->> OK 14:36:00.026> [2018.03.10 11:36:00]-<< AT+TPSM=2,2000 14:36:00.026> [2018.03.10 11:36:01]->> OK 14:36:00.072> [2018.03.10 11:36:01]-<< AT+CGSN 14:36:00.117> [2018.03.10 11:36:01]-[AT.PARSER] IMEI stored:[356307044112604] 14:36:00.178> [2018.03.10 11:36:01]->> 356307044112604 14:36:00.178> [2018.03.10 11:36:01]->> OK 14:36:00.178> [2018.03.10 11:36:01]-<< AT+TSIMPINCNT 14:36:00.238> [2018.03.10 11:36:01]->> +TSIMPINCNT: 3,3,10,10 14:36:00.300> [2018.03.10 11:36:01]->> OK 14:36:00.300> [2018.03.10 11:36:01]-<< AT+TGENVER 14:36:00.359> [2018.03.10 11:36:01]->> +TGENVER: "TM11Q_R_01.06.00" 14:36:00.420> [2018.03.10 11:36:01]->> OK 14:36:00.420> [2018.03.10 11:36:01]-<< AT+TGENVER=1 14:36:00.496> [2018.03.10 11:36:01]->> +TGENVER: "TM11Q_R_01.03.06.00_001" 14:36:00.556> [2018.03.10 11:36:01]->> OK 14:36:00.556> [2018.03.10 11:36:01]-<< AT+CGMR 14:36:00.617> [2018.03.10 11:36:01]->> TM11Q 14:36:00.617> [2018.03.10 11:36:01]->> OK 14:36:00.680> [2018.03.10 11:36:01]-<< AT+CCID? 14:36:00.739> [2018.03.10 11:36:01]-[MODEM] CCID 89701011958909679956 14:36:00.739> 14:36:00.739> [2018.03.10 11:36:01]->> +CCID: 89701011958909679956 14:36:00.739> [2018.03.10 11:36:01]->> OK 14:36:00.814> [2018.03.10 11:36:01]-<< AT+CPIN? 14:36:00.814> [2018.03.10 11:36:01]-[MODEM] SIM Card Ready 14:36:00.859> [2018.03.10 11:36:01]->> +CPIN: READY 14:36:00.859> [2018.03.10 11:36:01]->> OK 14:36:00.919> [2018.03.10 11:36:01]-[MODEM] Modem is Initializing (tmo:287) 14:36:00.919> [2018.03.10 11:36:01]-<< AT+CREG=2 14:36:00.980> [2018.03.10 11:36:01]->> OK 14:36:00.980> [2018.03.10 11:36:01]-<< AT+CMGF=0 14:36:01.056> [2018.03.10 11:36:02]->> OK 14:36:01.119> [2018.03.10 11:36:02]-[AppSend] No sending procedure in 100611348 sec, configured period: 1 sec 14:36:01.119> [2018.03.10 11:36:02]-<< AT+CNMI=2,1 14:36:01.119> [2018.03.10 11:36:02]->> OK 14:36:01.178> [2018.03.10 11:36:02]-<< AT+CIMI 14:36:01.237> [2018.03.10 11:36:02]-[AT.PARSER] IMSI Stored:[250015890967995] 14:36:01.283> [2018.03.10 11:36:02]->> 250015890967995 14:36:01.283> [2018.03.10 11:36:02]->> OK 14:36:01.283> [2018.03.10 11:36:02]-<< AT+CGEREP=1,0 14:36:01.374> [2018.03.10 11:36:02]->> OK 14:36:01.374> [2018.03.10 11:36:02]-<< AT+COPS=3,2 14:36:01.449> [2018.03.10 11:36:02]->> OK 14:36:01.495> [2018.03.10 11:36:02]-<< AT+CGREG=2 14:36:01.540> [2018.03.10 11:36:02]->> OK 14:36:01.620> [2018.03.10 11:36:02]-[MODEM] Jamming detection supported. 14:36:01.620> [2018.03.10 11:36:02]-<< AT+TJAMD=50,90 14:36:01.676> [2018.03.10 11:36:02]->> OK 14:36:01.676> [2018.03.10 11:36:02]-<< ATS0=0 14:36:01.737> [2018.03.10 11:36:02]->> OK 14:36:01.797> [2018.03.10 11:36:02]-[InitModem] Modem restart and init OK 14:36:01.919> [2018.03.10 11:36:02]-[MODEM] Modem is Initializing (tmo:286) 14:36:01.919> [2018.03.10 11:36:03]-[MODEM] Modem is Initializing (tmo:285) 14:36:02.933> [2018.03.10 11:36:04]-[MODEM] Modem is Initializing (tmo:284) 14:36:03.932> [2018.03.10 11:36:05]-<< AT 14:36:04.870> [2018.03.10 11:36:05]->> OK 14:36:04.931> [2018.03.10 11:36:05]-[MODEM] Modem is Initializing (tmo:283) 14:36:04.931> [2018.03.10 11:36:06]-[MODEM] Modem is Initializing (tmo:282) 14:36:05.915> [2018.03.10 11:36:06]-<< AT+CREG? 14:36:05.915> [2018.03.10 11:36:06]-[MODEM] Not Registered 14:36:05.992> [2018.03.10 11:36:06]->> +CREG: 2,0 14:36:05.992> [2018.03.10 11:36:06]->> OK 14:36:05.992> [2018.03.10 11:36:06]-<< AT 14:36:05.992> [2018.03.10 11:36:06]->> OK 14:36:06.051> [2018.03.10 11:36:06]-<< AT+COPS=2 14:36:06.051> [2018.03.10 11:36:07]-[BBox] Event 0x28, saved to slot 34, offset 272, crc 0x84, timestamp 1520681767 14:36:06.672> [2018.03.10 11:36:07]-[MODEM] Modem is Initializing (tmo:281) 14:36:06.929> [2018.03.10 11:36:08]-[MODEM] Modem is Initializing (tmo:280) 14:36:07.928> [2018.03.10 11:36:09]-[MODEM] Modem is Initializing (tmo:279) 14:36:08.927> [2018.03.10 11:36:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:36:09.608> [2018.03.10 11:36:10]-[SCHED] lvcan request 14:36:09.669> [2018.03.10 11:36:10]-[MODEM] Modem is Initializing (tmo:278) 14:36:09.926> [2018.03.10 11:36:10]-[MODEM] Modem is Initializing (tmo:278) 14:36:09.926> [2018.03.10 11:36:10]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:329[1800] ds18:0[1800] lvcan:0[1800] 14:36:09.926> [2018.03.10 11:36:11]-[MODEM] Not Registered Home Network 14:36:10.808> [2018.03.10 11:36:11]->> +CGREG: 0 14:36:10.808> [2018.03.10 11:36:11]->> OK 14:36:10.808> [2018.03.10 11:36:11]-[MODEM] Modem is Initializing (tmo:276) 14:36:10.933> [2018.03.10 11:36:12]-[AppSend] No sending procedure in 100611358 sec, configured period: 1 sec 14:36:11.101> [2018.03.10 11:36:12]-[MODEM] Modem is Initializing (tmo:275) 14:36:11.933> [2018.03.10 11:36:13]-[MODEM] Modem is Initializing (tmo:274) 14:36:12.934> [2018.03.10 11:36:14]-[MODEM] Modem is Initializing (tmo:273) 14:36:13.934> [2018.03.10 11:36:15]-[MODEM] Modem is Initializing (tmo:272) 14:36:14.934> [2018.03.10 11:36:16]-<< AT+COPS=0,2 14:36:15.809> [2018.03.10 11:36:16]-[MODEM] Modem is Initializing (tmo:271) 14:36:15.934> [2018.03.10 11:36:17]-[MODEM] Modem is Initializing (tmo:270) 14:36:16.934> [2018.03.10 11:36:18]-<< AT+CREG? 14:36:17.809> [2018.03.10 11:36:18]->> ABORTED 14:36:17.871> [2018.03.10 11:36:18]-[MODEM] Modem is Initializing (tmo:269) 14:36:17.934> [2018.03.10 11:36:19]-[MODEM] Modem is Initializing (tmo:268) 14:36:18.934> [2018.03.10 11:36:20]-[StackCheck] StackInfo: 14:36:19.621> 14:36:19.621> TaskId: 2 Name: TRACE Stack: 0x20001C58 Free: 140/ 576 @ StackInfo 14:36:19.621> TaskId: 3 Name: LVCPAP Stack: 0x2000F1B0 Free: 276/ 664 @ StackInfo 14:36:19.621> TaskId: 4 Name: EVTGEN Stack: 0x200067C0 Free: 592/ 872 @ StackInfo 14:36:19.699> TaskId: 5 Name: SCHEDU Stack: 0x20004DE8 Free: 416/ 768 @ StackInfo 14:36:19.699> TaskId: 6 Name: HEARTB Stack: 0x200050E8 Free: 48/ 128 @ StackInfo 14:36:19.699> TaskId: 7 Name: MODDAT Stack: 0x20005168 Free: 296/ 384 @ StackInfo 14:36:19.699> TaskId: 8 Name: UARTPR Stack: 0x20002A28 Free: 904/1640 @ StackInfo 14:36:19.699> TaskId: 9 Name: DSLEEP Stack: 0x20009B18 Free: 312/ 768 @ StackInfo 14:36:19.699> TaskId: 10 Name: PWRMAN Stack: 0x2000C678 Free: 576/ 768 @ StackInfo 14:36:19.699> TaskId: 11 Name: DATSAV Stack: 0x2000B020 Free: 352/1024 @ StackInfo 14:36:19.699> TaskId: 12 Name: GSMUPD Stack: 0x2000CD00 Free: 856/1504 @ StackInfo 14:36:19.699> TaskId: 13 Name: CHGPRS Stack: 0x2000AC20 Free: 424/1024 @ StackInfo 14:36:19.699> TaskId: 14 Name: OPERS Stack: 0x2000B878 Free: 784/1024 @ StackInfo 14:36:19.903> TaskId: 15 Name: APPSND Stack: 0x2000B420 Free: 864/1096 @ StackInfo 14:36:19.903> TaskId: 16 Name: TCPCMD Stack: 0x2000DD58 Free: 1700/1856 @ StackInfo 14:36:19.903> TaskId: 17 Name: WDOG Stack: 0x20000DB8 Free: 56/ 296 @ StackInfo 14:36:19.903> TaskId: 18 Name: SMSEVT Stack: 0x2000D350 Free: 624/1096 @ StackInfo 14:36:19.903> TaskId: 19 Name: INITM Stack: 0x2000C978 Free: 248/ 904 @ StackInfo 14:36:19.903> 14:36:19.903> [2018.03.10 11:36:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:36:19.903> [2018.03.10 11:36:20]-[SCHED] DTC request 14:36:19.903> [2018.03.10 11:36:20]-[Scheduler] GC is scheduled after: 6600 sec 14:36:19.903> [2018.03.10 11:36:20]-[MODEM] Modem is Initializing (tmo:267) 14:36:20.199> [2018.03.10 11:36:20]-[MODEM] Modem is Initializing (tmo:267) 14:36:20.199> [2018.03.10 11:36:21]-[MODEM] Modem is Initializing (tmo:265) 14:36:20.934> [2018.03.10 11:36:22]-[AppSend] No sending procedure in 100611368 sec, configured period: 1 sec 14:36:21.121> [2018.03.10 11:36:22]-[MODEM] Modem is Initializing (tmo:264) 14:36:21.934> [2018.03.10 11:36:23]-<< AT+COPS=0,2 14:36:22.809> [2018.03.10 11:36:23]-[MODEM] Modem is Initializing (tmo:263) 14:36:22.934> [2018.03.10 11:36:24]-[MODEM] Modem is Initializing (tmo:262) 14:36:23.934> [2018.03.10 11:36:25]-[ModeChange] GPS selected as movement indicator 14:36:24.497> [2018.03.10 11:36:25]-[ModeChange] GPS selected as movement indicator 14:36:24.497> [2018.03.10 11:36:25]-[ModeChange] GPS selected as movement indicator 14:36:24.622> [2018.03.10 11:36:25]-[ModeChange] Nothing to change 14:36:24.622> [2018.03.10 11:36:25]-[MODEM] Modem is Initializing (tmo:261) 14:36:24.934> [2018.03.10 11:36:26]-[DEBUG] 14:36:25.122> 14:36:25.122> UARTTxFullCnt: 0 14:36:25.122> SchTaskRunCnt: 10 14:36:25.122> UARTTskRunCnt: 991 14:36:25.122> AppSaveRunCnt: 10 14:36:25.122> AppSendRunCnt: 10 14:36:25.122> ValidNMEAPkts: 110 14:36:25.122> RS232 procesed: 0 14:36:25.122> RS232 droped : 0 14:36:25.122> RS485 procesed: 0 14:36:25.122> RS485 droped : 0 14:36:25.122> 14:36:25.122> [2018.03.10 11:36:26]-[PERIODIC.BATRAM.INFO] 14:36:25.122> 14:36:25.122> BootSign : 0x0000 14:36:25.122> BootSign Inv: 0x0000 14:36:25.122> 14:36:25.122> Date : 2018.03.10 14:36:25.122> TimeSync : 1 14:36:25.122> Uptime : 610 14:36:25.122> 14:36:25.122> Restart Cnt : 0 14:36:25.122> WDG Restarts: 0 14:36:25.122> Bad Records : 0 14:36:25.122> AuthState : 0 14:36:25.122> GC Timer : 605 14:36:25.122> 14:36:25.122> [2018.03.10 11:36:26]-[MODEM] Modem is Initializing (tmo:260) 14:36:25.934> [2018.03.10 11:36:27]-[MODEM] Modem is Initializing (tmo:259) 14:36:26.934> [2018.03.10 11:36:28]-[MODEM] Modem is Initializing (tmo:258) 14:36:27.934> [2018.03.10 11:36:29]-[MODEM] Modem is Initializing (tmo:257) 14:36:28.934> [2018.03.10 11:36:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:36:29.622> [2018.03.10 11:36:30]-[SCHED] lvcan request 14:36:29.622> [2018.03.10 11:36:30]-[MODEM] Modem is Initializing (tmo:256) 14:36:29.934> [2018.03.10 11:36:30]-[MODEM] Modem is Initializing (tmo:256) 14:36:29.934> [2018.03.10 11:36:30]-[DeepSleep] SLEEP MODE DISABLED! 14:36:29.997> [2018.03.10 11:36:31]-[MODEM] Modem is Initializing (tmo:254) 14:36:30.934> [2018.03.10 11:36:31]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:350[1800] ds18:0[1800] lvcan:0[1800] 14:36:30.934> [2018.03.10 11:36:31]-[MODEM] Registered To Home Network 14:36:31.059> [2018.03.10 11:36:31]->> +CREG: 1,"0C81","3521" 14:36:31.059> [2018.03.10 11:36:32]->> OK 14:36:31.059> [2018.03.10 11:36:32]->> +CGEV: NW CLASS B 14:36:31.059> [2018.03.10 11:36:32]-[MODEM] Unknown 14:36:31.153> [2018.03.10 11:36:32]->> +CGREG: 4 14:36:31.153> [2018.03.10 11:36:32]-[AppSend] No sending procedure in 100611378 sec, configured period: 1 sec 14:36:31.153> [2018.03.10 11:36:32]-[BBox] Event 0x38, saved to slot 35, offset 280, crc 0x4B, timestamp 1520681792 14:36:31.622> [2018.03.10 11:36:32]-[MODEM] Modem is Initializing (tmo:253) 14:36:31.919> [2018.03.10 11:36:33]-[MODEM] Modem is Initializing (tmo:252) 14:36:32.919> [2018.03.10 11:36:34]-[MODEM] Modem is Initializing (tmo:251) 14:36:33.919> [2018.03.10 11:36:35]-[MODEM] Modem is Initializing (tmo:250) 14:36:34.919> [2018.03.10 11:36:36]-[MODEM] Modem is Initializing (tmo:249) 14:36:35.919> [2018.03.10 11:36:37]-[MODEM] Modem is Initializing (tmo:248) 14:36:36.919> [2018.03.10 11:36:38]-[AppDatSav] Available 14:36:37.607> [2018.03.10 11:36:38]-[AppDatSav] 50 seconds before periodic record save 14:36:37.669> [2018.03.10 11:36:38]-[OP.SEARCH] Wait for registration confirmation from CREG 14:36:37.794> [2018.03.10 11:36:38]-[MODEM] Modem is Initializing (tmo:247) 14:36:37.919> [2018.03.10 11:36:39]-[MODEM] Modem is Initializing (tmo:246) 14:36:38.919> [2018.03.10 11:36:40]-<< AT+CPAS 14:36:39.294> [2018.03.10 11:36:40]-[MODEM] 0 - Phone Ready 14:36:39.357> [2018.03.10 11:36:40]->> +CPAS: 0 14:36:39.357> [2018.03.10 11:36:40]->> OK 14:36:39.357> [2018.03.10 11:36:40]-<< AT+CPIN? 14:36:39.482> [2018.03.10 11:36:40]-[MODEM] SIM Card Ready 14:36:39.482> [2018.03.10 11:36:40]->> +CPIN: READY 14:36:39.482> [2018.03.10 11:36:40]->> OK 14:36:39.544> [2018.03.10 11:36:40]-<< AT+COPS? 14:36:39.607> [2018.03.10 11:36:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:36:39.607> [2018.03.10 11:36:40]-[MODEM] GSM Op in Num Form 14:36:39.669> [2018.03.10 11:36:40]-[SCHED] lvcan request 14:36:39.669> [2018.03.10 11:36:40]->> +COPS: 0,2,"25001" 14:36:39.669> [2018.03.10 11:36:40]->> OK 14:36:39.669> [2018.03.10 11:36:40]-<< AT+CIMI 14:36:39.747> [2018.03.10 11:36:40]-[AT.PARSER] IMSI Stored:[250015890967995] 14:36:39.794> [2018.03.10 11:36:40]->> 250015890967995 14:36:39.794> [2018.03.10 11:36:40]->> OK 14:36:39.794> [2018.03.10 11:36:40]-<< AT+CREG? 14:36:39.857> [2018.03.10 11:36:40]-[MODEM] Registered To Home Network 14:36:39.919> [2018.03.10 11:36:40]->> +CREG: 2,1,"0C81","3521" 14:36:39.919> [2018.03.10 11:36:40]->> OK 14:36:39.919> [2018.03.10 11:36:40]-[MODEM] Modem is Initializing (tmo:245) 14:36:39.919> [2018.03.10 11:36:40]-[MODEM] Modem is Initializing (tmo:245) 14:36:39.919> [2018.03.10 11:36:40]-<< AT+CIND? 14:36:40.013> [2018.03.10 11:36:41]-[BBox] Event 0x2A, saved to slot 36, offset 288, crc 0x3A, timestamp 1520681801 14:36:40.669> [2018.03.10 11:36:41]-[ModeChange] Home on Stop profile is updated and reloaded 14:36:40.669> [2018.03.10 11:36:41]-[ModeChange] Mode changed 14:36:40.669> [2018.03.10 11:36:41]-[MODEM] Modem is Initializing (tmo:243) 14:36:40.919> [2018.03.10 11:36:42]-[AppSend] No sending procedure in 100611388 sec, configured period: 1 sec 14:36:41.107> [2018.03.10 11:36:42]-[MODEM] Modem is Initializing (tmo:242) 14:36:41.919> [2018.03.10 11:36:42]-[AutoGeo] AutoGeofence is disabled 14:36:42.044> [2018.03.10 11:36:43]-[MODEM] Modem is Initializing (tmo:241) 14:36:42.919> [2018.03.10 11:36:44]-[MODEM.STATUS] 14:36:43.482> 14:36:43.482> Modem Power : 1 14:36:43.482> Modem State : 1 14:36:43.482> Data Link : 0 14:36:43.482> GPRS Status : 0 14:36:43.482> Phone Status: 0 14:36:43.482> SIM PIN : 0 14:36:43.482> PIN Attemts : 3 14:36:43.482> GSM Operator: 25001 14:36:43.482> Bat. Charge : 5 14:36:43.482> GSM Signal : 1 14:36:43.482> Service : 1 14:36:43.482> New Message : 0 14:36:43.482> Roaming : 0 14:36:43.482> SMS Full : 0 14:36:43.482> Voice Call : 0 14:36:43.482> Cell ID : 13601 14:36:43.482> Area Code : 3201 14:36:43.482> MCC : 250 14:36:43.482> MNC : 1 14:36:43.482> 14:36:43.482> [2018.03.10 11:36:44]->> +CIND: 5,1,1,0,0,0,0,0,1,0,0,1 14:36:44.060> [2018.03.10 11:36:44]->> OK 14:36:44.060> [2018.03.10 11:36:44]-<< AT+CSQ 14:36:44.060> [2018.03.10 11:36:44]-[MODEM] Signal rssi 6 14:36:44.060> [2018.03.10 11:36:44]-[BBox] Event 0x5C, saved to slot 37, offset 296, crc 0x79, timestamp 1520681804 14:36:44.060> [2018.03.10 11:36:44]->> +CSQ: 6,99 14:36:44.060> [2018.03.10 11:36:44]->> OK 14:36:44.060> [2018.03.10 11:36:44]-[InitModem] Modem init procedure successful 14:36:44.060> [2018.03.10 11:36:44]-[GPRS] Process mutex taken 14:36:44.060> [2018.03.10 11:36:44]-[GPRS] Opening context 14:36:44.060> [2018.03.10 11:36:44]-<< AT+TIPCALL? 14:36:44.060> [2018.03.10 11:36:44]->> +TIPCALL: 0 14:36:44.060> [2018.03.10 11:36:44]->> OK 14:36:44.060> [2018.03.10 11:36:44]-[MODEM] ok got after 11942 ms cmd event after 11941 ms 14:36:44.060> [2018.03.10 11:36:44]-[MODEM] RetVal: 1 14:36:44.060> [2018.03.10 11:36:44]-<< AT+TIPCALL=1,"internet.mts.ru","","" 14:36:44.060> [2018.03.10 11:36:44]-[OP.SEARCH] Wait for GSM update task 14:36:44.060> [2018.03.10 11:36:44]-[GSM Status] Waiting for Process mutex... 14:36:44.060> [2018.03.10 11:36:44]->> OK 14:36:44.060> [2018.03.10 11:36:48]-[MODEM] Registered To Home Network 14:36:47.295> [2018.03.10 11:36:48]->> +CREG: 1,"0C81","3590" 14:36:47.295> [2018.03.10 11:36:49]-[OP.SEARCH] Waiting for Process mutex... 14:36:48.920> [2018.03.10 11:36:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:36:49.670> [2018.03.10 11:36:50]-[SCHED] lvcan request 14:36:49.670> [2018.03.10 11:36:50]-[LLS.485] Go to SCAN procedure 14:36:49.670> [2018.03.10 11:36:52]-[AppSend] No sending procedure in 100611398 sec, configured period: 1 sec 14:36:51.107> [2018.03.10 11:36:52]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:13[1800] pr:371[1800] ds18:0[1800] lvcan:0[1800] 14:36:51.982> [2018.03.10 11:36:55]-[MODEM] Registered To Home Network 14:36:54.107> [2018.03.10 11:36:55]->> +CREG: 1,"0C81","3521" 14:36:54.107> [2018.03.10 11:37:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:36:59.108> [2018.03.10 11:37:00]-[AppSend] Checking SMS Data Sending 14:36:59.108> [2018.03.10 11:37:00]-[AppSend] SMS Data Sending is Disabled 14:36:59.170> [2018.03.10 11:37:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:36:59.608> [2018.03.10 11:37:00]-[SCHED] lvcan request 14:36:59.670> [2018.03.10 11:37:02]-[AppSend] No sending procedure in 100611408 sec, configured period: 1 sec 14:37:01.108> [2018.03.10 11:37:06]-[DEBUG] 14:37:05.608> 14:37:05.608> UARTTxFullCnt: 0 14:37:05.608> SchTaskRunCnt: 10 14:37:05.608> UARTTskRunCnt: 990 14:37:05.608> AppSaveRunCnt: 10 14:37:05.608> AppSendRunCnt: 10 14:37:05.608> ValidNMEAPkts: 110 14:37:05.608> RS232 procesed: 0 14:37:05.608> RS232 droped : 0 14:37:05.608> RS485 procesed: 0 14:37:05.608> RS485 droped : 0 14:37:05.608> 14:37:05.608> [2018.03.10 11:37:10]-[ModeChange] GPS selected as movement indicator 14:37:09.483> [2018.03.10 11:37:10]-[ModeChange] GPS selected as movement indicator 14:37:09.483> [2018.03.10 11:37:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:37:09.608> [2018.03.10 11:37:10]-[ModeChange] GPS selected as movement indicator 14:37:09.671> [2018.03.10 11:37:10]-[SCHED] lvcan request 14:37:09.671> [2018.03.10 11:37:10]-[ModeChange] Nothing to change 14:37:09.671> [2018.03.10 11:37:12]-[AppSend] No sending procedure in 100611418 sec, configured period: 1 sec 14:37:11.171> [2018.03.10 11:37:13]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:34[1800] pr:392[1800] ds18:0[1800] lvcan:0[1800] 14:37:12.983> [2018.03.10 11:37:14]-[MODEM] Registered To Home Network 14:37:13.671> [2018.03.10 11:37:14]->> +CREG: 1,"0C81","3590" 14:37:13.671> [2018.03.10 11:37:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:37:19.609> [2018.03.10 11:37:20]-[SCHED] DTC request 14:37:19.671> [2018.03.10 11:37:22]-[AppSend] No sending procedure in 100611428 sec, configured period: 1 sec 14:37:21.109> [2018.03.10 11:37:24]-[MODEM] Registered To Home Network 14:37:23.234> [2018.03.10 11:37:24]->> +CREG: 1,"0C81","3521" 14:37:23.234> [2018.03.10 11:37:26]-[FWInfo] 14:37:25.859> 14:37:25.859> FW version: 01.26.10.Rev.00 14:37:25.859> BL version: 01.09 14:37:25.859> Toolchain : 4.0.0524 14:37:25.859> Compiled : Jun 29 2017 @ 11:24:10 14:37:25.859> HW version: FM11YX_MOD3_A2:B2:C0:D0:E2:F0:G0:H0:I0:J0 14:37:25.859> HW voltage: 123 mV 14:37:25.859> 14:37:25.859> [2018.03.10 11:37:26]-[PERIODIC.ONLINE.INFO] 14:37:25.859> 14:37:25.859> Code Ver : 01.26.10.Rev:0 14:37:25.859> BL Version : 01.09 14:37:25.859> Hw Version : FM11YX_MOD3_A2:B2:C0:D0:E2:F0:G0:H0:I0:J0 14:37:25.859> 14:37:25.859> Device IMEI : 356307044112604 14:37:25.859> Device IMSI : 250015890967995 14:37:25.859> Modem AppVer : 1.06.00 14:37:25.859> Modem FullVer : TM11Q_R_01.03.06.00_001 14:37:25.859> 14:37:25.859> Reset Cause : Power On / Power Down 14:37:26.296> Startup Time : 2018/3/10 11:26:16 14:37:26.296> RTC Clock : 2018/3/10 11:37:26 14:37:26.296> GPS Clock : 2018/3/10 11:37:26 14:37:26.296> Device Uptime : 0:11:10 14:37:26.296> 14:37:26.296> Sesion Upload : 0 Bytes 14:37:26.296> Total Upload : 0 Bytes 14:37:26.296> SMS Sent : 0 14:37:26.296> Received : 0 14:37:26.296> 14:37:26.296> Restarts Made : 3 14:37:26.296> Errors Detected: 1 14:37:26.296> Records Sent : 0 14:37:26.296> BadRec Detected: 0 14:37:26.296> ProfCRC Fails : 0 14:37:26.296> Failed GPRS : 0 14:37:26.296> Failed Link : 0 14:37:26.296> UDP Link TMO : 0 14:37:26.296> 14:37:26.296> No GPS Timer : 0:0:54 14:37:26.296> GPS Status : 3 14:37:26.296> Actual Sat : 16 14:37:26.296> Maximum Sat : 17 14:37:26.296> Last fix acqu : 58 sec (acquired) 14:37:26.296> 14:37:26.296> Working Mode : 0 - Home on Stop 14:37:26.296> 14:37:26.296> [2018.03.10 11:37:26]- [Ver]->01.26.10,0,356307044112604,Jun 29 2017,01.09,06,1.06.00,123,GGG303_002,,,,, 14:37:26.296> [2018.03.10 11:37:28]-[AppDatSav] uiMinPeriod (600) record action 14:37:27.672> [2018.03.10 11:37:28]-[AppDatSav] Saving Periodic Record 14:37:27.672> [2018.03.10 11:37:28]-[AVL] Record Saved: 14:37:27.672> 14:37:27.672> Timestamp: 1520681848000 14:37:27.672> Priority : 0 14:37:27.672> Latitude : 545581936 14:37:27.672> Longitude: 362931393 14:37:27.672> Altitude : 245 14:37:27.672> Angle : 0 14:37:27.672> Speed : 0 14:37:27.672> HDOP : 0.7 14:37:27.672> SatInUse : 16 14:37:27.672> 14:37:27.672> Event ID : 0 14:37:27.672> RecSize : 30 Bytes 14:37:27.672> 14:37:27.672> [2018.03.10 11:37:28]-[RecMng] New record address: 0x00077400 len: 30, 116 / 256 14:37:27.672> [2018.03.10 11:37:29]-[AutoGeo] AutoGeofence is disabled 14:37:29.047> [2018.03.10 11:37:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:37:29.609> [2018.03.10 11:37:30]-[SCHED] lvcan request 14:37:29.672> [2018.03.10 11:37:30]-[DeepSleep] SLEEP MODE DISABLED! 14:37:30.047> [2018.03.10 11:37:32]-[AppSend] No sending procedure in 100611438 sec, configured period: 1 sec 14:37:31.109> [2018.03.10 11:37:34]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:55[1800] pr:413[1800] ds18:0[1800] lvcan:0[1800] 14:37:33.984> [2018.03.10 11:37:39]-[AppDatSav] Available 14:37:38.610> [2018.03.10 11:37:39]-[AppDatSav] 589 seconds before periodic record save 14:37:38.672> [2018.03.10 11:37:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:37:39.672> [2018.03.10 11:37:40]-[SCHED] lvcan request 14:37:39.672> [2018.03.10 11:37:42]-[AppSend] No sending procedure in 100611448 sec, configured period: 1 sec 14:37:41.110> [2018.03.10 11:37:44]-[MODEM] Registered To Home Network 14:37:43.797> [2018.03.10 11:37:44]->> +CREG: 1,"0C81","3590" 14:37:43.797> [2018.03.10 11:37:47]-[DEBUG] 14:37:46.172> 14:37:46.172> UARTTxFullCnt: 0 14:37:46.172> SchTaskRunCnt: 10 14:37:46.172> UARTTskRunCnt: 991 14:37:46.172> AppSaveRunCnt: 10 14:37:46.172> AppSendRunCnt: 10 14:37:46.172> ValidNMEAPkts: 110 14:37:46.172> RS232 procesed: 0 14:37:46.172> RS232 droped : 0 14:37:46.172> RS485 procesed: 0 14:37:46.172> RS485 droped : 0 14:37:46.172> 14:37:46.172> [2018.03.10 11:37:49]-[MODEM] Unknown 14:37:48.798> [2018.03.10 11:37:49]->> +CREG: 4 14:37:48.798> [2018.03.10 11:37:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:37:49.610> [2018.03.10 11:37:50]-[SCHED] lvcan request 14:37:49.673> [2018.03.10 11:37:50]-[BBox] Event 0x7A, saved to slot 38, offset 304, crc 0xCB, timestamp 1520681870 14:37:49.673> [2018.03.10 11:37:52]-[AppSend] No sending procedure in 100611458 sec, configured period: 1 sec 14:37:51.110> [2018.03.10 11:37:55]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:76[1800] pr:434[1800] ds18:0[1800] lvcan:0[1800] 14:37:54.985> [2018.03.10 11:37:56]-[ModeChange] GPS selected as movement indicator 14:37:55.485> [2018.03.10 11:37:56]-[ModeChange] GPS selected as movement indicator 14:37:55.485> [2018.03.10 11:37:56]-[ModeChange] GPS selected as movement indicator 14:37:55.610> [2018.03.10 11:37:56]-[ModeChange] Nothing to change 14:37:55.673> [2018.03.10 11:38:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:37:59.126> [2018.03.10 11:38:00]-[AppSend] Checking SMS Data Sending 14:37:59.173> [2018.03.10 11:38:00]-[AppSend] SMS Data Sending is Disabled 14:37:59.173> [2018.03.10 11:38:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:37:59.611> [2018.03.10 11:38:00]-[SCHED] lvcan request 14:37:59.673> [2018.03.10 11:38:00]-[LLS.485] Go to SCAN procedure 14:37:59.673> [2018.03.10 11:38:02]-[AppSend] No sending procedure in 100611468 sec, configured period: 1 sec 14:38:01.173> [2018.03.10 11:38:10]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:38:09.674> [2018.03.10 11:38:10]-[SCHED] lvcan request 14:38:09.674> [2018.03.10 11:38:12]-[AppSend] No sending procedure in 100611478 sec, configured period: 1 sec 14:38:11.174> [2018.03.10 11:38:16]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:97[1800] pr:455[1800] ds18:0[1800] lvcan:0[1800] 14:38:15.986> [2018.03.10 11:38:16]-[AutoGeo] AutoGeofence is disabled 14:38:16.049> [2018.03.10 11:38:20]-[StackCheck] StackInfo: 14:38:19.674> 14:38:19.674> TaskId: 2 Name: TRACE Stack: 0x20001C58 Free: 140/ 576 @ StackInfo 14:38:19.674> TaskId: 3 Name: LVCPAP Stack: 0x2000F1B0 Free: 276/ 664 @ StackInfo 14:38:19.674> TaskId: 4 Name: EVTGEN Stack: 0x200067C0 Free: 592/ 872 @ StackInfo 14:38:19.674> TaskId: 5 Name: SCHEDU Stack: 0x20004DE8 Free: 416/ 768 @ StackInfo 14:38:19.674> TaskId: 6 Name: HEARTB Stack: 0x200050E8 Free: 48/ 128 @ StackInfo 14:38:19.674> TaskId: 7 Name: MODDAT Stack: 0x20005168 Free: 296/ 384 @ StackInfo 14:38:19.799> TaskId: 8 Name: UARTPR Stack: 0x20002A28 Free: 904/1640 @ StackInfo 14:38:19.799> TaskId: 9 Name: DSLEEP Stack: 0x20009B18 Free: 312/ 768 @ StackInfo 14:38:19.799> TaskId: 10 Name: PWRMAN Stack: 0x2000C678 Free: 576/ 768 @ StackInfo 14:38:19.799> TaskId: 11 Name: DATSAV Stack: 0x2000B020 Free: 336/1024 @ StackInfo 14:38:19.799> TaskId: 12 Name: GSMUPD Stack: 0x2000CD00 Free: 856/1504 @ StackInfo 14:38:19.799> TaskId: 13 Name: CHGPRS Stack: 0x2000AC20 Free: 424/1024 @ StackInfo 14:38:19.799> TaskId: 14 Name: OPERS Stack: 0x2000B878 Free: 784/1024 @ StackInfo 14:38:19.799> TaskId: 15 Name: APPSND Stack: 0x2000B420 Free: 864/1096 @ StackInfo 14:38:19.799> TaskId: 16 Name: TCPCMD Stack: 0x2000DD58 Free: 1700/1856 @ StackInfo 14:38:19.799> TaskId: 17 Name: WDOG Stack: 0x20000DB8 Free: 56/ 296 @ StackInfo 14:38:19.799> TaskId: 18 Name: SMSEVT Stack: 0x2000D350 Free: 624/1096 @ StackInfo 14:38:19.799> TaskId: 19 Name: INITM Stack: 0x2000C978 Free: 248/ 904 @ StackInfo 14:38:19.799> 14:38:19.799> [2018.03.10 11:38:20]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:38:19.799> [2018.03.10 11:38:20]-[SCHED] DTC request 14:38:19.799> [2018.03.10 11:38:22]-[AppSend] No sending procedure in 100611488 sec, configured period: 1 sec 14:38:21.174> [2018.03.10 11:38:27]-[DEBUG] 14:38:26.612> 14:38:26.612> UARTTxFullCnt: 0 14:38:26.612> SchTaskRunCnt: 10 14:38:26.612> UARTTskRunCnt: 991 14:38:26.612> AppSaveRunCnt: 10 14:38:26.612> AppSendRunCnt: 10 14:38:26.612> ValidNMEAPkts: 110 14:38:26.612> RS232 procesed: 0 14:38:26.612> RS232 droped : 0 14:38:26.612> RS485 procesed: 0 14:38:26.612> RS485 droped : 0 14:38:26.612> 14:38:26.612> [2018.03.10 11:38:27]-[PERIODIC.GPS.INFO] 14:38:26.612> 14:38:26.612> Status : 3 (gps on) 14:38:26.612> Sat : 15 14:38:26.612> Lat : 54.558194 14:38:26.612> Long : 36.293139 14:38:26.612> Alt : 245 14:38:26.612> Speed : 0 14:38:26.612> Angle : 0 14:38:26.612> 14:38:26.612> GPS TMO : 900 14:38:26.612> GPS Fix : 1 14:38:26.612> NMEA Flag : 1 14:38:26.612> Time Sync : 1 14:38:26.612> Baudrate : 115200 14:38:26.612> Valid Pkts: 110 14:38:26.612> 14:38:26.612> GPS AP : 1 14:38:26.612> GPS_OFF : 1 14:38:26.612> RAM_GPS_ON: 1 14:38:26.612> SN Config : 1 14:38:26.612> SN State : 2 14:38:26.612> Log2Srv : 0 14:38:26.612> 14:38:26.612> [2018.03.10 11:38:30]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:38:29.675> [2018.03.10 11:38:30]-[SCHED] lvcan request 14:38:29.675> [2018.03.10 11:38:31]-[DeepSleep] SLEEP MODE DISABLED! 14:38:30.050> [2018.03.10 11:38:32]-[AppSend] No sending procedure in 100611498 sec, configured period: 1 sec 14:38:31.175> [2018.03.10 11:38:37]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:118[1800] pr:476[1800] ds18:0[1800] lvcan:0[1800] 14:38:37.050> [2018.03.10 11:38:40]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:38:39.675> [2018.03.10 11:38:40]-[AppDatSav] Available 14:38:39.675> [2018.03.10 11:38:40]-[SCHED] lvcan request 14:38:39.675> [2018.03.10 11:38:40]-[AppDatSav] 528 seconds before periodic record save 14:38:39.675> [2018.03.10 11:38:41]-[ModeChange] GPS selected as movement indicator 14:38:40.675> [2018.03.10 11:38:41]-[ModeChange] GPS selected as movement indicator 14:38:40.675> [2018.03.10 11:38:42]-[AppSend] No sending procedure in 100611508 sec, configured period: 1 sec 14:38:41.175> [2018.03.10 11:38:42]-[ModeChange] GPS selected as movement indicator 14:38:41.550> [2018.03.10 11:38:42]-[ModeChange] GPS selected as movement indicator 14:38:41.550> [2018.03.10 11:38:42]-[ModeChange] GPS selected as movement indicator 14:38:41.706> [2018.03.10 11:38:42]-[ModeChange] Nothing to change 14:38:41.706> [2018.03.10 11:38:50]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:38:49.676> [2018.03.10 11:38:50]-[SCHED] lvcan request 14:38:49.676> [2018.03.10 11:38:50]-[Scheduler] GC is scheduled after: 6450 sec 14:38:49.676> [2018.03.10 11:38:52]-[AppSend] No sending procedure in 100611518 sec, configured period: 1 sec 14:38:51.176> [2018.03.10 11:38:58]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:139[1800] pr:497[1800] ds18:0[1800] lvcan:0[1800] 14:38:58.051> [2018.03.10 11:39:00]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 14:38:59.176> [2018.03.10 11:39:00]-[AppSend] Checking SMS Data Sending 14:38:59.176> [2018.03.10 11:39:00]-[AppSend] SMS Data Sending is Disabled 14:38:59.176> [2018.03.10 11:39:00]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 14:38:59.676> [2018.03.10 11:39:00]-[SCHED] lvcan request 14:38:59.676> [2018.03.10 11:39:02]-[AppSend] No sending procedure in 100611528 sec, configured period: 1 sec 14:39:01.176> [2018.03.10 11:39:03]-[AutoGeo] AutoGeofence is disabled 14:39:03.051> ----------------------------------------------- Date: 10.03.2018 - 14:39:05 End log file