Terminal log file Date: 10.03.2018 - 11:28:08 ----------------------------------------------- [2015.01.01 00:00:09]- [MODEM]->Startup Detected!!! 11:28:34.351> [2015.01.01 00:00:09]->> +TDRVI: 9,1,5 11:28:34.351> [2015.01.01 00:00:09]- GNSS-> $GPTXT,01,01,02,ANTSTATUS=OK*3B 11:28:34.910> [2015.01.01 00:00:09]- [GGG.VERSION]-> Not GGG HW.Skip. 11:28:34.910> [2015.01.01 00:00:09]- [MODEM]->SIM STATUS 2 SIM CARD WITHOUT PIN 11:28:34.971> [2015.01.01 00:00:09]->> +TSIM: 2 11:28:34.971> [2015.01.01 00:00:10]->> +TLOCK: "PN",5,0,"PU",5,0,"PP",5,0,"PC",5,0,"PS",5,0 11:28:35.213> [2015.01.01 00:00:10]- [MODEM]->SIM STATUS 7 SIMTK RESET FINISHED 11:28:35.213> [2015.01.01 00:00:10]->> +TSIM: 7 11:28:35.213> [2015.01.01 00:00:16]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:28:41.790> [2015.01.01 00:00:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:28:42.603> [2015.01.01 00:00:17]-[SCHED] lvcan request 11:28:42.603> [2015.01.01 00:00:17]-[AppSend] Successfuly Started 11:28:42.728> [2015.01.01 00:00:17]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:28:42.728> [2015.01.01 00:00:17]-[AppSend] Task Finished 11:28:42.728> [2015.01.01 00:00:17]-[SMS Event] sms_evt_task task started 11:28:42.791> [2015.01.01 00:00:18]- [MODEM]->Modem Phonebook Is Ready Continue 11:28:43.478> [2015.01.01 00:00:18]->> +PBREADY 11:28:43.478> [2015.01.01 00:00:18]-<< ATE0 11:28:43.541> [2015.01.01 00:00:18]->> ATE0 11:28:43.603> 11:28:43.603> [2015.01.01 00:00:18]->> OK 11:28:43.603> [2015.01.01 00:00:18]-<< AT+CMEE=2 11:28:43.603> [2015.01.01 00:00:18]->> OK 11:28:43.666> [2015.01.01 00:00:18]-<< AT+TPSM=2,2000 11:28:43.728> [2015.01.01 00:00:18]->> OK 11:28:43.791> [2015.01.01 00:00:18]-<< AT+CGSN 11:28:43.791> [2015.01.01 00:00:18]- [AT.PARSER]->IMEI Stored:[356307040580804] 11:28:43.853> [2015.01.01 00:00:18]->> 356307040580804 11:28:43.853> [2015.01.01 00:00:18]->> OK 11:28:43.853> [2015.01.01 00:00:18]-<< AT+TSIMPINCNT 11:28:43.916> [2015.01.01 00:00:18]->> +TSIMPINCNT: 3,3,10,10 11:28:43.978> [2015.01.01 00:00:18]->> OK 11:28:43.978> [2015.01.01 00:00:18]-<< AT+TGENVER 11:28:44.041> [2015.01.01 00:00:18]->> +TGENVER: "TM11Q_R_01.03.03" 11:28:44.103> [2015.01.01 00:00:19]->> OK 11:28:44.103> [2015.01.01 00:00:19]-<< AT+TGENVER=1 11:28:44.166> [2015.01.01 00:00:19]->> +TGENVER: "TM11Q_R_01.03.03.03_002" 11:28:44.166> [2015.01.01 00:00:19]->> OK 11:28:44.228> [2015.01.01 00:00:19]-<< AT+CGMR 11:28:44.228> [2015.01.01 00:00:19]->> TM11Q 11:28:44.291> [2015.01.01 00:00:19]->> OK 11:28:44.291> [2015.01.01 00:00:19]-<< AT+CCID? 11:28:44.353> [2015.01.01 00:00:19]- [MODEM]->CCID 89701011958909679956 11:28:44.416> 11:28:44.416> [2015.01.01 00:00:19]->> +CCID: 89701011958909679956 11:28:44.416> [2015.01.01 00:00:19]->> OK 11:28:44.416> [2015.01.01 00:00:19]-<< AT+CPIN? 11:28:44.509> [2015.01.01 00:00:19]- [MODEM]->SIM Card Ready 11:28:44.509> [2015.01.01 00:00:19]->> +CPIN: READY 11:28:44.509> [2015.01.01 00:00:19]->> OK 11:28:44.509> [SIMPIN.STATE]: WORKING, NO PIN REQUIRED 11:28:44.603> [2015.01.01 00:00:19]-<< AT+CREG=2 11:28:44.603> [2015.01.01 00:00:19]->> OK 11:28:44.666> [2015.01.01 00:00:19]-<< AT+CMGF=0 11:28:44.666> [2015.01.01 00:00:19]->> OK 11:28:44.728> [2015.01.01 00:00:19]-<< AT+CNMI=2,1 11:28:44.791> [2015.01.01 00:00:19]->> OK 11:28:44.791> [2015.01.01 00:00:19]-<< AT+CIMI 11:28:44.853> [2015.01.01 00:00:19]- [AT.PARSER]->IMSI Stored:[250015890967995] 11:28:44.916> [2015.01.01 00:00:19]->> 250015890967995 11:28:44.916> [2015.01.01 00:00:19]->> OK 11:28:44.916> [2015.01.01 00:00:19]-<< AT+CGEREP=1,0 11:28:44.978> [2015.01.01 00:00:19]->> OK 11:28:45.041> [2015.01.01 00:00:19]-<< AT+COPS=3,2 11:28:45.041> [2015.01.01 00:00:20]->> OK 11:28:45.103> [2015.01.01 00:00:20]-<< AT+CGREG=2 11:28:45.166> [2015.01.01 00:00:20]->> OK 11:28:45.228> [2015.01.01 00:00:20]-[JammDetect] WARNING @ jamm_detect.c(22): Modem firmware doesn't support jamming detection 11:28:45.228> [2015.01.01 00:00:20]- [MODEM]->Jamming detection not suported. 11:28:45.291> [2015.01.01 00:00:20]-[JammDetect] WARNING @ jamm_detect.c(22): Modem firmware doesn't support jamming detection 11:28:45.291> [2015.01.01 00:00:20]- [MODEM]->Jamming detection not suported. Reason:4 11:28:45.291> [2015.01.01 00:00:20]-<< ATS0=0 11:28:45.291> [2015.01.01 00:00:20]->> OK 11:28:45.369> [2015.01.01 00:00:20]-[InitModem] Modem restart and init OK 11:28:45.478> [2015.01.01 00:00:23]-<< AT 11:28:48.478> [2015.01.01 00:00:23]->> OK 11:28:48.478> [2015.01.01 00:00:23]-[Trip] Time not synchronized, VECHICLE STOP state 11:28:48.603> [2015.01.01 00:00:24]-<< AT+CREG? 11:28:49.478> [2015.01.01 00:00:24]- [MODEM]->Not Registered 11:28:49.541> [2015.01.01 00:00:24]->> +CREG: 2,0 11:28:49.541> [2015.01.01 00:00:24]->> OK 11:28:49.603> [2015.01.01 00:00:24]-<< AT 11:28:49.603> [2015.01.01 00:00:24]->> OK 11:28:49.603> [2015.01.01 00:00:24]-<< AT+COPS=2 11:28:49.603> [2015.01.01 00:00:24]- [MODEM]->Not Registered Home Network 11:28:49.728> [2015.01.01 00:00:24]->> +CGREG: 0 11:28:49.728> [2015.01.01 00:00:24]->> OK 11:28:49.728> [2015.01.01 00:00:26]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:28:51.791> [2015.01.01 00:00:26]-[AppSend] 591 seconds before periodic data sending 11:28:51.853> [2015.01.01 00:00:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:28:52.603> [2015.01.01 00:00:27]-[SCHED] lvcan request 11:28:52.603> [2015.01.01 00:00:27]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:28:52.791> [2015.01.01 00:00:29]-<< AT+COPS=0,2 11:28:54.729> [2015.01.01 00:00:30]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:1[1800] pr:21[1800] ds18:0[1800] lvcan:0[1800] 11:28:55.791> [2015.01.01 00:00:31]-<< AT+CREG? 11:28:56.729> [2015.01.01 00:00:31]->> ABORTED 11:28:56.791> [2015.01.01 00:00:36]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:29:01.791> [2015.01.01 00:00:36]-[AppSend] 581 seconds before periodic data sending 11:29:01.854> [2015.01.01 00:00:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:29:02.604> [2015.01.01 00:00:37]-[AppDatSav] No GNSS fix 870/900 11:29:02.604> [2015.01.01 00:00:37]-[SCHED] lvcan request 11:29:02.604> [2015.01.01 00:00:37]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:29:02.791> [2015.01.01 00:00:38]-[Trip] Time not synchronized, VECHICLE STOP state 11:29:03.604> [2015.01.01 00:00:46]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:29:11.729> [2015.01.01 00:00:46]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:29:11.792> [2015.01.01 00:00:46]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:29:11.854> [2015.01.01 00:00:46]-[AppSend] Checking SMS Data Sending 11:29:11.854> [2015.01.01 00:00:46]-[AppSend] SMS Data Sending is Disabled 11:29:11.854> [2015.01.01 00:00:46]-[AppSend] 571 seconds before periodic data sending 11:29:11.917> [2015.01.01 00:00:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:29:12.604> [2015.01.01 00:00:47]-[SCHED] lvcan request 11:29:12.604> [2015.01.01 00:00:47]-[DEBUG] 11:29:12.729> 11:29:12.729> UARTTxFullCnt: 0 11:29:12.729> SchTaskRunCnt: 10 11:29:12.729> UARTTskRunCnt: 1000 11:29:12.729> AppSaveRunCnt: 10 11:29:12.729> AppSendRunCnt: 10 11:29:12.729> ValidNMEAPkts: 60 11:29:12.729> RS232 procesed: 0 11:29:12.729> RS232 droped : 0 11:29:12.729> RS485 procesed: 0 11:29:12.729> RS485 droped : 0 11:29:12.729> 11:29:12.729> [2015.01.01 00:00:47]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:29:12.979> [2015.01.01 00:00:51]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:42[1800] ds18:0[1800] lvcan:0[1800] 11:29:16.792> [2015.01.01 00:00:52]- [OP.SEARCH.TASK]-> Successfuly Started 11:29:17.730> [2015.01.01 00:00:52]- [OP.CHK]->OP List not Empty. OP Code: 25001 Index: 0 11:29:17.730> [2015.01.01 00:00:52]- [APP.OPSEARCH]->Time left before Optimal Operator Search: 0 minute(s) 11:29:17.730> [2015.01.01 00:00:52]- [APP.OPSEARCH]->Starting Forced OP Search... 11:29:17.792> [2015.01.01 00:00:52]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:29:17.792> [2015.01.01 00:00:53]-[ModeChange] GPS selected as movement indicator 11:29:18.605> [2015.01.01 00:00:53]-[ModeChange] Nothing to change 11:29:18.605> [2015.01.01 00:00:53]-[Trip] Time not synchronized, VECHICLE STOP state 11:29:18.605> [2015.01.01 00:00:53]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:29:18.792> [2015.01.01 00:00:54]- [MODEM.STATUS]->Modem is Initializing (tmo:294) 11:29:19.792> [2015.01.01 00:00:54]-[AutoGeo] AutoGeofence is disabled 11:29:19.980> [2015.01.01 00:00:55]- [MODEM.STATUS]->Modem is Initializing (tmo:293) 11:29:20.792> [2015.01.01 00:00:56]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:29:21.730> [2015.01.01 00:00:56]- [MODEM.STATUS]->Modem is Initializing (tmo:292) 11:29:21.792> [2015.01.01 00:00:56]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:29:21.792> [2015.01.01 00:00:56]-[AppSend] 561 seconds before periodic data sending 11:29:21.917> [2015.01.01 00:00:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:29:22.605> [2015.01.01 00:00:57]-[SCHED] lvcan request 11:29:22.605> [2015.01.01 00:00:57]- [MODEM.STATUS]->Modem is Initializing (tmo:291) 11:29:22.792> [2015.01.01 00:00:57]- [MODEM.STATUS]->Modem is Initializing (tmo:290) 11:29:22.792> [2015.01.01 00:00:58]- [MODEM.STATUS]->Modem is Initializing (tmo:289) 11:29:23.793> [2015.01.01 00:00:59]- [MODEM.STATUS]->Modem is Initializing (tmo:288) 11:29:24.793> [2015.01.01 00:01:00]- [MODEM.STATUS]->Modem is Initializing (tmo:287) 11:29:25.793> [2015.01.01 00:01:01]- [MODEM.STATUS]->Modem is Initializing (tmo:286) 11:29:26.793> [2015.01.01 00:01:02]- [MODEM.STATUS]->Modem is Initializing (tmo:285) 11:29:27.793> [2015.01.01 00:01:03]- [MODEM.STATUS]->Modem is Initializing (tmo:284) 11:29:28.793> [2015.01.01 00:01:04]- [MODEM.STATUS]->Modem is Initializing (tmo:283) 11:29:29.793> [2015.01.01 00:01:05]- [MODEM.STATUS]->Modem is Initializing (tmo:282) 11:29:30.793> [2015.01.01 00:01:06]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:29:31.730> [2015.01.01 00:01:06]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:29:31.793> [2015.01.01 00:01:06]- [MODEM.STATUS]->Modem is Initializing (tmo:281) 11:29:31.793> [2015.01.01 00:01:06]-[AppSend] 551 seconds before periodic data sending 11:29:31.918> [2015.01.01 00:01:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:29:32.605> [2015.01.01 00:01:07]-[AppDatSav] No GNSS fix 840/900 11:29:32.605> [2015.01.01 00:01:07]-[SCHED] lvcan request 11:29:32.605> [2015.01.01 00:01:07]-[LLS.485] Address table reseted 11:29:32.605> [2015.01.01 00:01:07]-[LLS.485] Go to SCAN procedure 11:29:32.605> [2015.01.01 00:01:07]-[FWInfo] 11:29:32.730> 11:29:32.730> FW version: 01.21.10.Rev.00 11:29:32.730> BL version: 01.08 11:29:32.730> Toolchain : 4.0.0524 11:29:32.730> Compiled : Oct 20 2016 @ 09:05:49 11:29:32.730> HW version: FM11YX_MOD1_A0:B0:C0:D0:E2:F0:G0:H0:I0:J0 11:29:32.730> HW voltage: 1241 mV 11:29:32.730> 11:29:32.730> [2015.01.01 00:01:07]-[PERIODIC.ONLINE.INFO] 11:29:32.730> 11:29:32.730> Code Ver : 01.21.10.Rev:0 11:29:32.730> BL Version : 01.08 11:29:32.730> Hw Version : FM11YX_MOD1_A0:B0:C0:D0:E2:F0:G0:H0:I0:J0 11:29:32.730> 11:29:32.730> Device IMEI : 356307040580804 11:29:32.730> Device IMSI : 250015890967995 11:29:32.730> Modem AppVer : 1.03.03 11:29:32.730> Modem FullVer : TM11Q_R_01.03.03.03_002 11:29:32.730> 11:29:32.730> Reset Cause : Power On / Power Down 11:29:33.137> Startup Time : 2015/1/1 0:0:0 11:29:33.137> RTC Clock : 2015/1/1 0:1:7 11:29:33.137> GPS Clock : 2000/1/1 0:0:0 11:29:33.137> Device Uptime : 0:01:07 11:29:33.137> 11:29:33.137> Sesion Upload : 0 Bytes 11:29:33.137> Total Upload : 0 Bytes 11:29:33.137> SMS Sent : 0 11:29:33.137> Received : 0 11:29:33.137> 11:29:33.137> Restarts Made : 1 11:29:33.137> Errors Detected: 0 11:29:33.137> Records Sent : 0 11:29:33.137> BadRec Detected: 7 11:29:33.137> ProfCRC Fails : 0 11:29:33.137> Failed GPRS : 0 11:29:33.137> Failed Link : 0 11:29:33.137> UDP Link TMO : 0 11:29:33.137> 11:29:33.137> No GPS Timer : 0:1:0 11:29:33.137> GPS Status : 2 11:29:33.137> Actual Sat : 0 11:29:33.137> Maximum Sat : 0 11:29:33.137> Last fix acqu : 60 sec (counting) 11:29:33.137> 11:29:33.137> Working Mode : 4 - Unknown on Stop 11:29:33.137> 11:29:33.137> [2015.01.01 00:01:07]- [MODEM.STATUS]->Modem is Initializing (tmo:280) 11:29:33.137> [2015.01.01 00:01:07]- [Ver]->01.21.10,0,356307040580804,Oct 20 2016,01.08,06,0,0,1.03.03,1241,,,,,, 11:29:33.137> [2015.01.01 00:01:07]-[DeepSleep] SLEEP MODE DISABLED! 11:29:33.137> [2015.01.01 00:01:07]- [MODEM.STATUS]->Modem is Initializing (tmo:280) 11:29:33.137> [2015.01.01 00:01:08]-[AppDatSav] No fix 11:29:34.106> [2015.01.01 00:01:08]-[Trip] Time not synchronized, VECHICLE STOP state 11:29:34.106> [2015.01.01 00:01:08]- [MODEM.STATUS]->Modem is Initializing (tmo:278) 11:29:34.106> [2015.01.01 00:01:09]-[GPRS] Time not synchronized 11:29:34.199> [2015.01.01 00:01:09]-[GPRS] Repeating task after delay 11:29:34.293> [2015.01.01 00:01:09]- [MODEM.STATUS]->Modem is Initializing (tmo:277) 11:29:34.856> [2015.01.01 00:01:10]- [MODEM.STATUS]->Modem is Initializing (tmo:276) 11:29:35.856> [2015.01.01 00:01:11]- [MODEM.STATUS]->Modem is Initializing (tmo:275) 11:29:36.856> [2015.01.01 00:01:12]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:63[1800] ds18:0[1800] lvcan:0[1800] 11:29:37.793> [2015.01.01 00:01:12]- [MODEM.STATUS]->Modem is Initializing (tmo:274) 11:29:37.856> [2015.01.01 00:01:13]- [MODEM.STATUS]->Modem is Initializing (tmo:273) 11:29:38.856> [2015.01.01 00:01:14]- [MODEM.STATUS]->Modem is Initializing (tmo:272) 11:29:39.856> [2015.01.01 00:01:15]- [MODEM.STATUS]->Modem is Initializing (tmo:271) 11:29:40.856> [2015.01.01 00:01:16]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:29:41.731> [2015.01.01 00:01:16]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:29:41.793> [2015.01.01 00:01:16]- [MODEM.STATUS]->Modem is Initializing (tmo:270) 11:29:41.856> [2015.01.01 00:01:16]-[AppSend] 541 seconds before periodic data sending 11:29:41.918> [2015.01.01 00:01:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:29:42.606> [2015.01.01 00:01:17]-[SCHED] lvcan request 11:29:42.606> [2015.01.01 00:01:17]- [MODEM.STATUS]->Modem is Initializing (tmo:269) 11:29:42.856> [2015.01.01 00:01:17]- [MODEM.STATUS]->Modem is Initializing (tmo:268) 11:29:42.856> [2015.01.01 00:01:18]- [MODEM.STATUS]->Modem is Initializing (tmo:267) 11:29:43.856> [2015.01.01 00:01:19]- [MODEM.STATUS]->Modem is Initializing (tmo:266) 11:29:44.856> [2015.01.01 00:01:20]- [MODEM.STATUS]->Modem is Initializing (tmo:265) 11:29:45.856> [2015.01.01 00:01:21]- [MODEM.STATUS]->Modem is Initializing (tmo:264) 11:29:46.856> [2015.01.01 00:01:22]- [MODEM.STATUS]->Modem is Initializing (tmo:263) 11:29:47.856> [2015.01.01 00:01:23]-[Trip] Time not synchronized, VECHICLE STOP state 11:29:48.606> [2015.01.01 00:01:23]- [MODEM.STATUS]->Modem is Initializing (tmo:262) 11:29:48.856> [2015.01.01 00:01:24]- [MODEM.STATUS]->Modem is Initializing (tmo:261) 11:29:49.856> [2015.01.01 00:01:25]- [MODEM.STATUS]->Modem is Initializing (tmo:260) 11:29:50.856> [2015.01.01 00:01:26]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:29:51.731> [2015.01.01 00:01:26]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:29:51.794> [2015.01.01 00:01:26]- [MODEM.STATUS]->Modem is Initializing (tmo:259) 11:29:51.856> [2015.01.01 00:01:26]-[AppSend] 531 seconds before periodic data sending 11:29:51.919> [2015.01.01 00:01:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:29:52.606> [2015.01.01 00:01:27]-[SCHED] lvcan request 11:29:52.606> [2015.01.01 00:01:27]-[DEBUG] 11:29:52.794> 11:29:52.794> UARTTxFullCnt: 0 11:29:52.794> SchTaskRunCnt: 10 11:29:52.794> UARTTskRunCnt: 1000 11:29:52.794> AppSaveRunCnt: 10 11:29:52.794> AppSendRunCnt: 10 11:29:52.794> ValidNMEAPkts: 60 11:29:52.794> RS232 procesed: 0 11:29:52.794> RS232 droped : 0 11:29:52.794> RS485 procesed: 0 11:29:52.794> RS485 droped : 0 11:29:52.794> 11:29:52.794> [2015.01.01 00:01:27]- [MODEM.STATUS]->Modem is Initializing (tmo:258) 11:29:53.044> [2015.01.01 00:01:27]- [MODEM.STATUS]->Modem is Initializing (tmo:258) 11:29:53.044> [2015.01.01 00:01:28]- [MODEM.STATUS]->Modem is Initializing (tmo:256) 11:29:53.856> [2015.01.01 00:01:29]- [MODEM.STATUS]->Modem is Initializing (tmo:255) 11:29:54.857> [2015.01.01 00:01:30]- [MODEM.STATUS]->Modem is Initializing (tmo:254) 11:29:55.857> [2015.01.01 00:01:31]- [MODEM.STATUS]->Modem is Initializing (tmo:253) 11:29:56.857> [2015.01.01 00:01:32]- [MODEM.STATUS]->Modem is Initializing (tmo:252) 11:29:57.857> [2015.01.01 00:01:33]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:84[1800] ds18:0[1800] lvcan:0[1800] 11:29:58.794> [2015.01.01 00:01:33]- [MODEM.STATUS]->Modem is Initializing (tmo:251) 11:29:58.857> [2015.01.01 00:01:34]- [MODEM.STATUS]->Modem is Initializing (tmo:250) 11:29:59.857> [2015.01.01 00:01:35]- [MODEM.STATUS]->Modem is Initializing (tmo:249) 11:30:00.857> [2015.01.01 00:01:36]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:30:01.732> [2015.01.01 00:01:36]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:30:01.794> [2015.01.01 00:01:36]- [MODEM.STATUS]->Modem is Initializing (tmo:248) 11:30:01.857> [2015.01.01 00:01:36]-[AppSend] 521 seconds before periodic data sending 11:30:01.919> [2015.01.01 00:01:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:30:02.607> [2015.01.01 00:01:37]-[AppDatSav] No GNSS fix 810/900 11:30:02.607> [2015.01.01 00:01:37]-[SCHED] lvcan request 11:30:02.607> [2015.01.01 00:01:37]- [MODEM.STATUS]->Modem is Initializing (tmo:247) 11:30:02.857> [2015.01.01 00:01:37]- [MODEM.STATUS]->Modem is Initializing (tmo:246) 11:30:02.857> [2015.01.01 00:01:38]-[Trip] Time not synchronized, VECHICLE STOP state 11:30:03.607> [2015.01.01 00:01:38]- [MODEM.STATUS]->Modem is Initializing (tmo:245) 11:30:03.857> [2015.01.01 00:01:39]-[ModeChange] GPS selected as movement indicator 11:30:04.607> [2015.01.01 00:01:39]-[ModeChange] Nothing to change 11:30:04.607> [2015.01.01 00:01:39]- [MODEM.STATUS]->Modem is Initializing (tmo:244) 11:30:04.857> [2015.01.01 00:01:40]- [MODEM.STATUS]->Modem is Initializing (tmo:243) 11:30:05.857> [2015.01.01 00:01:41]- [MODEM.STATUS]->Modem is Initializing (tmo:242) 11:30:06.857> [2015.01.01 00:01:41]-[AutoGeo] AutoGeofence is disabled 11:30:06.982> [2015.01.01 00:01:42]-[Scenarios] Periodic info: 11:30:07.607> DOUT1 scenarios disabled 11:30:07.607> [2015.01.01 00:01:42]-[DOUT2.None] State: DISABLED 11:30:07.607> [2015.01.01 00:01:42]- [MODEM.STATUS]->Modem is Initializing (tmo:241) 11:30:07.857> [2015.01.01 00:01:43]- [MODEM.STATUS]->Modem is Initializing (tmo:240) 11:30:08.857> [2015.01.01 00:01:44]- [MODEM.STATUS]->Modem is Initializing (tmo:239) 11:30:09.857> [2015.01.01 00:01:45]- [MODEM.STATUS]->Modem is Initializing (tmo:238) 11:30:10.857> [2015.01.01 00:01:46]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:30:11.732> [2015.01.01 00:01:46]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:30:11.795> [2015.01.01 00:01:46]- [MODEM.STATUS]->Modem is Initializing (tmo:237) 11:30:11.857> [2015.01.01 00:01:46]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:30:11.920> [2015.01.01 00:01:46]-[AppSend] Checking SMS Data Sending 11:30:11.920> [2015.01.01 00:01:46]-[AppSend] SMS Data Sending is Disabled 11:30:11.920> [2015.01.01 00:01:46]-[AppSend] 511 seconds before periodic data sending 11:30:11.920> [2015.01.01 00:01:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:30:12.607> [2015.01.01 00:01:47]-[SCHED] lvcan request 11:30:12.607> [2015.01.01 00:01:47]- [MODEM.STATUS]->Modem is Initializing (tmo:236) 11:30:12.857> [2015.01.01 00:01:47]- [MODEM.STATUS]->Modem is Initializing (tmo:235) 11:30:12.857> [2015.01.01 00:01:48]- [MODEM.STATUS]->Modem is Initializing (tmo:234) 11:30:13.857> [2015.01.01 00:01:49]- [MODEM.STATUS]->Modem is Initializing (tmo:233) 11:30:14.858> [2015.01.01 00:01:50]- [MODEM.STATUS]->Modem is Initializing (tmo:232) 11:30:15.858> [2015.01.01 00:01:51]- [MODEM.STATUS]->Modem is Initializing (tmo:231) 11:30:16.858> [2015.01.01 00:01:52]- [MODEM.STATUS]->Modem is Initializing (tmo:230) 11:30:17.858> [2015.01.01 00:01:53]-[Trip] Time not synchronized, VECHICLE STOP state 11:30:18.608> [2015.01.01 00:01:53]- [MODEM.STATUS]->Modem is Initializing (tmo:229) 11:30:18.858> [2015.01.01 00:01:54]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:105[1800] ds18:0[1800] lvcan:0[1800] 11:30:19.795> [2015.01.01 00:01:54]- [MODEM.STATUS]->Modem is Initializing (tmo:228) 11:30:19.858> [2015.01.01 00:01:55]- [MODEM.STATUS]->Modem is Initializing (tmo:227) 11:30:20.858> [2015.01.01 00:01:56]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:30:21.733> [2015.01.01 00:01:56]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:30:21.795> [2015.01.01 00:01:56]- [MODEM.STATUS]->Modem is Initializing (tmo:226) 11:30:21.858> [2015.01.01 00:01:56]-[AppSend] 501 seconds before periodic data sending 11:30:21.920> [2015.01.01 00:01:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:30:22.608> [2015.01.01 00:01:57]-[SCHED] lvcan request 11:30:22.608> [2015.01.01 00:01:57]- [MODEM.STATUS]->Modem is Initializing (tmo:225) 11:30:22.858> [2015.01.01 00:01:57]- [MODEM.STATUS]->Modem is Initializing (tmo:224) 11:30:22.858> [2015.01.01 00:01:58]- [MODEM.STATUS]->Modem is Initializing (tmo:223) 11:30:23.858> [2015.01.01 00:01:59]- [MODEM.STATUS]->Modem is Initializing (tmo:222) 11:30:24.858> [2015.01.01 00:02:00]- [MODEM.STATUS]->Modem is Initializing (tmo:221) 11:30:25.858> [2015.01.01 00:02:01]- [MODEM.STATUS]->Modem is Initializing (tmo:220) 11:30:26.858> [2015.01.01 00:02:02]- [MODEM.STATUS]->Modem is Initializing (tmo:219) 11:30:27.858> [2015.01.01 00:02:03]- [MODEM.STATUS]->Modem is Initializing (tmo:218) 11:30:28.858> [2015.01.01 00:02:04]- [MODEM.STATUS]->Modem is Initializing (tmo:217) 11:30:29.858> [2015.01.01 00:02:05]- [MODEM.STATUS]->Modem is Initializing (tmo:216) 11:30:30.858> [2015.01.01 00:02:06]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:30:31.733> [2015.01.01 00:02:06]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:30:31.796> [2015.01.01 00:02:06]- [MODEM.STATUS]->Modem is Initializing (tmo:215) 11:30:31.858> [2015.01.01 00:02:06]-[AppSend] 491 seconds before periodic data sending 11:30:31.921> [2015.01.01 00:02:07]-[StackCheck] StackInfo: 11:30:32.608> 11:30:32.608> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:30:32.608> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:30:32.608> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:30:32.608> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:30:32.608> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:30:32.608> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:30:32.608> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:30:32.608> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:30:32.796> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 752/1024 @ StackInfo 11:30:32.796> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:30:32.796> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 816/1024 @ StackInfo 11:30:32.796> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:30:32.796> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:30:32.796> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:30:32.796> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:30:32.796> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:30:32.796> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 544/ 904 @ StackInfo 11:30:32.796> 11:30:32.796> [2015.01.01 00:02:07]-[AppDatSav] No GNSS fix 780/900 11:30:32.796> [2015.01.01 00:02:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:30:32.796> [2015.01.01 00:02:07]-[SCHED] lvcan request 11:30:32.796> [2015.01.01 00:02:07]-[LLS.485] Address table reseted 11:30:32.796> [2015.01.01 00:02:07]-[LLS.485] Go to SCAN procedure 11:30:32.796> [2015.01.01 00:02:07]-[DEBUG] 11:30:33.233> 11:30:33.233> UARTTxFullCnt: 0 11:30:33.233> SchTaskRunCnt: 10 11:30:33.233> UARTTskRunCnt: 1000 11:30:33.233> AppSaveRunCnt: 10 11:30:33.233> AppSendRunCnt: 10 11:30:33.233> ValidNMEAPkts: 60 11:30:33.233> RS232 procesed: 0 11:30:33.233> RS232 droped : 0 11:30:33.233> RS485 procesed: 0 11:30:33.233> RS485 droped : 0 11:30:33.233> 11:30:33.233> [2015.01.01 00:02:07]-[DeepSleep] SLEEP MODE DISABLED! 11:30:33.233> [2015.01.01 00:02:07]- [MODEM.STATUS]->Modem is Initializing (tmo:214) 11:30:33.233> [2015.01.01 00:02:07]-[PERIODIC.GPS.INFO] 11:30:33.233> 11:30:33.233> Status : 2 (gps on) 11:30:33.233> Sat : 0 11:30:33.233> Lat : 0.000000 11:30:33.233> Long : 0.000000 11:30:33.233> Alt : 0 11:30:33.233> Speed : 0 11:30:33.233> Angle : 0 11:30:33.233> 11:30:33.233> GPS TMO : 780 11:30:33.233> GPS Fix : 0 11:30:33.233> NMEA Flag : 1 11:30:33.233> Time Sync : 0 11:30:33.233> Baudrate : 115200 11:30:33.233> Valid Pkts: 60 11:30:33.233> 11:30:33.233> GPS AP : 1 11:30:33.233> GPS_OFF : 1 11:30:33.233> RAM_GPS_ON: 1 11:30:33.233> SN Config : 0 11:30:33.233> SN State : 0 11:30:33.233> Log2Srv : 0 11:30:33.233> 11:30:33.233> [2015.01.01 00:02:07]- [MODEM.STATUS]->Modem is Initializing (tmo:214) 11:30:33.233> [2015.01.01 00:02:08]-[Trip] Time not synchronized, VECHICLE STOP state 11:30:34.499> [2015.01.01 00:02:08]- [MODEM.STATUS]->Modem is Initializing (tmo:212) 11:30:34.499> [2015.01.01 00:02:09]-[AppDatSav] No fix 11:30:34.562> [2015.01.01 00:02:09]-[GPRS] Time not synchronized 11:30:34.733> [2015.01.01 00:02:09]-[GPRS] Repeating task after delay 11:30:34.796> [2015.01.01 00:02:09]- [MODEM.STATUS]->Modem is Initializing (tmo:211) 11:30:34.858> [2015.01.01 00:02:10]- [MODEM.STATUS]->Modem is Initializing (tmo:210) 11:30:35.859> [2015.01.01 00:02:11]- [MODEM.STATUS]->Modem is Initializing (tmo:209) 11:30:36.859> [2015.01.01 00:02:12]- [MODEM.STATUS]->Modem is Initializing (tmo:208) 11:30:37.859> [2015.01.01 00:02:13]- [MODEM.STATUS]->Modem is Initializing (tmo:207) 11:30:38.859> [2015.01.01 00:02:14]- [MODEM.STATUS]->Modem is Initializing (tmo:206) 11:30:39.859> [2015.01.01 00:02:15]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:126[1800] ds18:0[1800] lvcan:0[1800] 11:30:40.796> [2015.01.01 00:02:15]- [MODEM.STATUS]->Modem is Initializing (tmo:205) 11:30:40.859> [2015.01.01 00:02:16]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:30:41.734> [2015.01.01 00:02:16]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:30:41.796> [2015.01.01 00:02:16]- [MODEM.STATUS]->Modem is Initializing (tmo:204) 11:30:41.859> [2015.01.01 00:02:16]-[AppSend] 481 seconds before periodic data sending 11:30:41.921> [2015.01.01 00:02:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:30:42.609> [2015.01.01 00:02:17]-[SCHED] lvcan request 11:30:42.609> [2015.01.01 00:02:17]- [MODEM.STATUS]->Modem is Initializing (tmo:203) 11:30:42.859> [2015.01.01 00:02:17]- [MODEM.STATUS]->Modem is Initializing (tmo:202) 11:30:42.921> [2015.01.01 00:02:18]- [MODEM.STATUS]->Modem is Initializing (tmo:201) 11:30:43.859> [2015.01.01 00:02:19]- [MODEM.STATUS]->Modem is Initializing (tmo:200) 11:30:44.859> [2015.01.01 00:02:20]- [MODEM.STATUS]->Modem is Initializing (tmo:199) 11:30:45.859> [2015.01.01 00:02:21]- [MODEM.STATUS]->Modem is Initializing (tmo:198) 11:30:46.859> [2015.01.01 00:02:22]- [MODEM.STATUS]->Modem is Initializing (tmo:197) 11:30:47.859> [2015.01.01 00:02:23]-[Trip] Time not synchronized, VECHICLE STOP state 11:30:48.609> [2015.01.01 00:02:23]- [MODEM.STATUS]->Modem is Initializing (tmo:196) 11:30:48.859> [2015.01.01 00:02:24]- [MODEM.STATUS]->Modem is Initializing (tmo:195) 11:30:49.859> [2015.01.01 00:02:25]-[ModeChange] GPS selected as movement indicator 11:30:50.609> [2015.01.01 00:02:25]-[ModeChange] Nothing to change 11:30:50.609> [2015.01.01 00:02:25]- [MODEM.STATUS]->Modem is Initializing (tmo:194) 11:30:50.859> [2015.01.01 00:02:26]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:30:51.734> [2015.01.01 00:02:26]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:30:51.797> [2015.01.01 00:02:26]- [MODEM.STATUS]->Modem is Initializing (tmo:193) 11:30:51.859> [2015.01.01 00:02:26]-[AppSend] 471 seconds before periodic data sending 11:30:51.922> [2015.01.01 00:02:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:30:52.609> [2015.01.01 00:02:27]-[SCHED] lvcan request 11:30:52.609> [2015.01.01 00:02:27]- [MODEM.STATUS]->Modem is Initializing (tmo:192) 11:30:52.859> [2015.01.01 00:02:27]- [MODEM.STATUS]->Modem is Initializing (tmo:191) 11:30:52.922> [2015.01.01 00:02:28]- [MODEM.STATUS]->Modem is Initializing (tmo:190) 11:30:53.859> [2015.01.01 00:02:28]-[AutoGeo] AutoGeofence is disabled 11:30:53.984> [2015.01.01 00:02:29]- [MODEM.STATUS]->Modem is Initializing (tmo:189) 11:30:54.859> [2015.01.01 00:02:30]- [MODEM.STATUS]->Modem is Initializing (tmo:188) 11:30:55.860> [2015.01.01 00:02:31]- [MODEM.STATUS]->Modem is Initializing (tmo:187) 11:30:56.860> [2015.01.01 00:02:32]- [MODEM.STATUS]->Modem is Initializing (tmo:186) 11:30:57.860> [2015.01.01 00:02:33]- [MODEM.STATUS]->Modem is Initializing (tmo:185) 11:30:58.860> [2015.01.01 00:02:34]- [MODEM.STATUS]->Modem is Initializing (tmo:184) 11:30:59.860> [2015.01.01 00:02:35]- [MODEM.STATUS]->Modem is Initializing (tmo:183) 11:31:00.860> [2015.01.01 00:02:36]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:31:01.735> [2015.01.01 00:02:36]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:147[1800] ds18:0[1800] lvcan:0[1800] 11:31:01.797> [2015.01.01 00:02:36]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:31:01.797> [2015.01.01 00:02:36]- [MODEM.STATUS]->Modem is Initializing (tmo:182) 11:31:01.860> [2015.01.01 00:02:36]-[AppSend] 461 seconds before periodic data sending 11:31:01.922> [2015.01.01 00:02:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:31:02.610> [2015.01.01 00:02:37]-[AppDatSav] No GNSS fix 750/900 11:31:02.610> [2015.01.01 00:02:37]-[SCHED] lvcan request 11:31:02.610> [2015.01.01 00:02:37]-[Scheduler] GC is scheduled after: 7050 sec 11:31:02.610> [2015.01.01 00:02:37]- [MODEM.STATUS]->Modem is Initializing (tmo:181) 11:31:02.860> [2015.01.01 00:02:37]- [MODEM.STATUS]->Modem is Initializing (tmo:180) 11:31:02.922> [2015.01.01 00:02:38]-[Trip] Time not synchronized, VECHICLE STOP state 11:31:03.610> [2015.01.01 00:02:38]- [MODEM.STATUS]->Modem is Initializing (tmo:179) 11:31:03.860> [2015.01.01 00:02:39]- [MODEM.STATUS]->Modem is Initializing (tmo:178) 11:31:04.860> [2015.01.01 00:02:40]- [MODEM.STATUS]->Modem is Initializing (tmo:177) 11:31:05.860> [2015.01.01 00:02:41]- [MODEM.STATUS]->Modem is Initializing (tmo:176) 11:31:06.860> [2015.01.01 00:02:42]- [MODEM.STATUS]->Modem is Initializing (tmo:175) 11:31:07.860> [2015.01.01 00:02:43]- [MODEM.STATUS]->Modem is Initializing (tmo:174) 11:31:08.860> [2015.01.01 00:02:44]- [MODEM.STATUS]->Modem is Initializing (tmo:173) 11:31:09.860> [2015.01.01 00:02:45]- [MODEM.STATUS]->Modem is Initializing (tmo:172) 11:31:10.860> [2015.01.01 00:02:46]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:31:11.735> [2015.01.01 00:02:46]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:31:11.798> [2015.01.01 00:02:46]- [MODEM.STATUS]->Modem is Initializing (tmo:171) 11:31:11.860> [2015.01.01 00:02:46]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:31:11.923> [2015.01.01 00:02:46]-[AppSend] Checking SMS Data Sending 11:31:11.923> [2015.01.01 00:02:46]-[AppSend] SMS Data Sending is Disabled 11:31:11.923> [2015.01.01 00:02:46]-[AppSend] 451 seconds before periodic data sending 11:31:11.923> [2015.01.01 00:02:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:31:12.610> [2015.01.01 00:02:47]-[SCHED] lvcan request 11:31:12.610> [2015.01.01 00:02:47]- [MODEM.STATUS]->Modem is Initializing (tmo:170) 11:31:12.860> [2015.01.01 00:02:47]-[DEBUG] 11:31:12.923> 11:31:12.923> UARTTxFullCnt: 0 11:31:12.923> SchTaskRunCnt: 10 11:31:12.923> UARTTskRunCnt: 1000 11:31:12.923> AppSaveRunCnt: 10 11:31:12.923> AppSendRunCnt: 10 11:31:12.923> ValidNMEAPkts: 60 11:31:12.923> RS232 procesed: 0 11:31:12.923> RS232 droped : 0 11:31:12.923> RS485 procesed: 0 11:31:12.923> RS485 droped : 0 11:31:12.923> 11:31:12.923> [2015.01.01 00:02:47]- [MODEM.STATUS]->Modem is Initializing (tmo:169) 11:31:12.923> [2015.01.01 00:02:48]- [MODEM.STATUS]->Modem is Initializing (tmo:168) 11:31:13.860> [2015.01.01 00:02:49]- [MODEM.STATUS]->Modem is Initializing (tmo:167) 11:31:14.860> [2015.01.01 00:02:50]- [MODEM.STATUS]->Modem is Initializing (tmo:166) 11:31:15.860> [2015.01.01 00:02:51]- [MODEM.STATUS]->Modem is Initializing (tmo:165) 11:31:16.861> [2015.01.01 00:02:52]- [MODEM.STATUS]->Modem is Initializing (tmo:164) 11:31:17.861> [2015.01.01 00:02:53]-[Trip] Time not synchronized, VECHICLE STOP state 11:31:18.611> [2015.01.01 00:02:53]- [MODEM.STATUS]->Modem is Initializing (tmo:163) 11:31:18.861> [2015.01.01 00:02:54]- [MODEM.STATUS]->Modem is Initializing (tmo:162) 11:31:19.861> [2015.01.01 00:02:55]- [MODEM.STATUS]->Modem is Initializing (tmo:161) 11:31:20.861> [2015.01.01 00:02:56]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:31:21.736> [2015.01.01 00:02:56]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:31:21.798> [2015.01.01 00:02:56]- [MODEM.STATUS]->Modem is Initializing (tmo:160) 11:31:21.861> [2015.01.01 00:02:56]-[AppSend] 441 seconds before periodic data sending 11:31:21.923> [2015.01.01 00:02:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:31:22.611> [2015.01.01 00:02:57]-[SCHED] lvcan request 11:31:22.611> [2015.01.01 00:02:57]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:168[1800] ds18:0[1800] lvcan:0[1800] 11:31:22.798> [2015.01.01 00:02:57]- [MODEM.STATUS]->Modem is Initializing (tmo:159) 11:31:22.861> [2015.01.01 00:02:57]- [MODEM.STATUS]->Modem is Initializing (tmo:158) 11:31:22.923> [2015.01.01 00:02:58]- [MODEM.STATUS]->Modem is Initializing (tmo:157) 11:31:23.861> [2015.01.01 00:02:59]- [MODEM.STATUS]->Modem is Initializing (tmo:156) 11:31:24.861> [2015.01.01 00:03:00]- [MODEM.STATUS]->Modem is Initializing (tmo:155) 11:31:25.861> [2015.01.01 00:03:01]- [MODEM.STATUS]->Modem is Initializing (tmo:154) 11:31:26.861> [2015.01.01 00:03:02]- [MODEM.STATUS]->Modem is Initializing (tmo:153) 11:31:27.861> [2015.01.01 00:03:03]- [MODEM.STATUS]->Modem is Initializing (tmo:152) 11:31:28.861> [2015.01.01 00:03:04]- [MODEM.STATUS]->Modem is Initializing (tmo:151) 11:31:29.861> [2015.01.01 00:03:05]- [MODEM.STATUS]->Modem is Initializing (tmo:150) 11:31:30.861> [2015.01.01 00:03:06]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:31:31.736> [2015.01.01 00:03:06]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:31:31.799> [2015.01.01 00:03:06]- [MODEM.STATUS]->Modem is Initializing (tmo:149) 11:31:31.861> [2015.01.01 00:03:06]-[AppSend] 431 seconds before periodic data sending 11:31:31.924> [2015.01.01 00:03:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:31:32.611> [2015.01.01 00:03:07]-[AppDatSav] No GNSS fix 720/900 11:31:32.611> [2015.01.01 00:03:07]-[SCHED] lvcan request 11:31:32.611> [2015.01.01 00:03:07]-[LLS.485] Address table reseted 11:31:32.611> [2015.01.01 00:03:07]-[LLS.485] Go to SCAN procedure 11:31:32.611> [2015.01.01 00:03:07]-[DeepSleep] SLEEP MODE DISABLED! 11:31:32.861> [2015.01.01 00:03:07]- [MODEM.STATUS]->Modem is Initializing (tmo:148) 11:31:32.861> [2015.01.01 00:03:07]-[PowerMan] State:NORMAL ExtPG:1 BatPG:0 Ext:12352 mV; BatV:0 mV; BatI:0 mA; GPS:3286 mV; Modem:3880 mV; 11:31:32.924> [2015.01.01 00:03:07]- [MODEM.STATUS]->Modem is Initializing (tmo:147) 11:31:32.924> [2015.01.01 00:03:08]-[Trip] Time not synchronized, VECHICLE STOP state 11:31:33.611> [2015.01.01 00:03:08]- [MODEM.STATUS]->Modem is Initializing (tmo:146) 11:31:33.861> [2015.01.01 00:03:09]- [MODEM.STATUS]->Modem is Initializing (tmo:145) 11:31:34.861> [2015.01.01 00:03:10]-[GPRS] Time not synchronized 11:31:35.236> [2015.01.01 00:03:10]-[GPRS] Repeating task after delay 11:31:35.299> [2015.01.01 00:03:10]-[AppDatSav] No fix 11:31:35.611> [2015.01.01 00:03:10]- [MODEM.STATUS]->Modem is Initializing (tmo:144) 11:31:35.861> [2015.01.01 00:03:11]-[ModeChange] GPS selected as movement indicator 11:31:36.612> [2015.01.01 00:03:11]-[ModeChange] Nothing to change 11:31:36.612> [2015.01.01 00:03:11]- [MODEM.STATUS]->Modem is Initializing (tmo:143) 11:31:36.862> [2015.01.01 00:03:12]- [MODEM.STATUS]->Modem is Initializing (tmo:142) 11:31:37.862> [2015.01.01 00:03:13]- [MODEM.STATUS]->Modem is Initializing (tmo:141) 11:31:38.862> [2015.01.01 00:03:14]- [MODEM.STATUS]->Modem is Initializing (tmo:140) 11:31:39.862> [2015.01.01 00:03:15]- [MODEM.STATUS]->Modem is Initializing (tmo:139) 11:31:40.862> [2015.01.01 00:03:15]-[AutoGeo] AutoGeofence is disabled 11:31:40.987> [2015.01.01 00:03:16]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:31:41.737> [2015.01.01 00:03:16]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:31:41.799> [2015.01.01 00:03:16]- [MODEM.STATUS]->Modem is Initializing (tmo:138) 11:31:41.862> [2015.01.01 00:03:16]-[AppSend] 421 seconds before periodic data sending 11:31:41.924> [2015.01.01 00:03:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:31:42.612> [2015.01.01 00:03:17]-[Scenarios] Periodic info: 11:31:42.612> DOUT1 scenarios disabled 11:31:42.612> [2015.01.01 00:03:17]-[DOUT2.None] State: DISABLED 11:31:42.612> [2015.01.01 00:03:17]-[SCHED] lvcan request 11:31:42.612> [2015.01.01 00:03:17]- [MODEM.STATUS]->Modem is Initializing (tmo:137) 11:31:42.862> [2015.01.01 00:03:17]- [MODEM.STATUS]->Modem is Initializing (tmo:136) 11:31:42.924> [2015.01.01 00:03:18]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:189[1800] ds18:0[1800] lvcan:0[1800] 11:31:43.799> [2015.01.01 00:03:18]- [MODEM.STATUS]->Modem is Initializing (tmo:135) 11:31:43.862> [2015.01.01 00:03:19]- [MODEM.STATUS]->Modem is Initializing (tmo:134) 11:31:44.862> [2015.01.01 00:03:20]- [MODEM.STATUS]->Modem is Initializing (tmo:133) 11:31:45.862> [2015.01.01 00:03:21]- [MODEM.STATUS]->Modem is Initializing (tmo:132) 11:31:46.862> [2015.01.01 00:03:22]- [MODEM.STATUS]->Modem is Initializing (tmo:131) 11:31:47.862> [2015.01.01 00:03:23]-[Trip] Time not synchronized, VECHICLE STOP state 11:31:48.612> [2015.01.01 00:03:23]- [MODEM.STATUS]->Modem is Initializing (tmo:130) 11:31:48.862> [2015.01.01 00:03:24]- [MODEM.STATUS]->Modem is Initializing (tmo:129) 11:31:49.862> [2015.01.01 00:03:25]- [MODEM.STATUS]->Modem is Initializing (tmo:128) 11:31:50.862> [2015.01.01 00:03:26]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:31:51.737> [2015.01.01 00:03:26]-[NMEA Filter] PDOP:99.99, HDOP:99.99, Valid:0 Q: 0, Sats: 0 Retry: 0 11:31:51.800> [2015.01.01 00:03:26]- [MODEM.STATUS]->Modem is Initializing (tmo:127) 11:31:51.862> [2015.01.01 00:03:26]-[AppSend] 411 seconds before periodic data sending 11:31:51.925> [2015.01.01 00:03:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:31:52.612> [2015.01.01 00:03:27]-[SCHED] lvcan request 11:31:52.612> [2015.01.01 00:03:27]- [MODEM.STATUS]->Modem is Initializing (tmo:126) 11:31:52.862> [2015.01.01 00:03:27]-[DEBUG] 11:31:52.925> 11:31:52.925> UARTTxFullCnt: 0 11:31:52.925> SchTaskRunCnt: 10 11:31:52.925> UARTTskRunCnt: 1000 11:31:52.925> AppSaveRunCnt: 10 11:31:52.925> AppSendRunCnt: 10 11:31:52.925> ValidNMEAPkts: 60 11:31:52.925> RS232 procesed: 0 11:31:52.925> RS232 droped : 0 11:31:52.925> RS485 procesed: 0 11:31:52.925> RS485 droped : 0 11:31:52.925> 11:31:52.925> [2015.01.01 00:03:27]- [MODEM.STATUS]->Modem is Initializing (tmo:125) 11:31:52.925> [2015.01.01 00:03:28]- [MODEM.STATUS]->Modem is Initializing (tmo:124) 11:31:53.862> [2015.01.01 00:03:29]- [MODEM.STATUS]->Modem is Initializing (tmo:123) 11:31:54.862> [2015.01.01 00:03:30]- [MODEM.STATUS]->Modem is Initializing (tmo:122) 11:31:55.862> [2015.01.01 00:03:31]- [MODEM.STATUS]->Modem is Initializing (tmo:121) 11:31:56.862> [2015.01.01 00:03:32]- [MODEM.STATUS]->Modem is Initializing (tmo:120) 11:31:57.863> [2015.01.01 00:03:33]- [MODEM.STATUS]->Modem is Initializing (tmo:119) 11:31:58.863> [2015.01.01 00:03:34]- [MODEM.STATUS]->Modem is Initializing (tmo:118) 11:31:59.863> [2015.01.01 00:03:35]- [MODEM.STATUS]->Modem is Initializing (tmo:117) 11:32:00.863> [2015.01.01 00:03:36]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:32:01.738> [2015.01.01 00:03:36]-[NMEA Filter] PDOP:8.01, HDOP:7.94, Valid:0 Q: 0, Sats: 3 Retry: 0 11:32:01.800> [2015.01.01 00:03:36]- [MODEM.STATUS]->Modem is Initializing (tmo:116) 11:32:01.863> [2015.01.01 00:03:36]-[AppSend] 401 seconds before periodic data sending 11:32:01.925> [2015.01.01 00:03:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:32:02.613> [2015.01.01 00:03:37]-[AppDatSav] No GNSS fix 690/900 11:32:02.613> [2015.01.01 00:03:37]-[SCHED] lvcan request 11:32:02.613> [2015.01.01 00:03:37]- [MODEM.STATUS]->Modem is Initializing (tmo:115) 11:32:02.863> [2015.01.01 00:03:37]- [MODEM.STATUS]->Modem is Initializing (tmo:114) 11:32:02.925> [2015.01.01 00:03:38]-[Trip] Time not synchronized, VECHICLE STOP state 11:32:03.613> [2015.01.01 00:03:38]- [MODEM.STATUS]->Modem is Initializing (tmo:113) 11:32:03.863> [2015.01.01 00:03:39]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:210[1800] ds18:0[1800] lvcan:0[1800] 11:32:04.800> [2015.01.01 00:03:39]- [MODEM.STATUS]->Modem is Initializing (tmo:112) 11:32:04.863> [2015.01.01 00:03:40]- [MODEM.STATUS]->Modem is Initializing (tmo:111) 11:32:05.863> [2015.01.01 00:03:41]- [MODEM.STATUS]->Modem is Initializing (tmo:110) 11:32:06.863> [2015.01.01 00:03:42]- [MODEM.STATUS]->Modem is Initializing (tmo:109) 11:32:07.863> [2015.01.01 00:03:43]- [MODEM.STATUS]->Modem is Initializing (tmo:108) 11:32:08.863> [2015.01.01 00:03:44]- [MODEM.STATUS]->Modem is Initializing (tmo:107) 11:32:09.863> [2015.01.01 00:03:45]- [MODEM.STATUS]->Modem is Initializing (tmo:106) 11:32:10.863> [2015.01.01 00:03:46]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:32:11.738> [2015.01.01 00:03:46]-[NMEA Filter] PDOP:7.99, HDOP:7.92, Valid:0 Q: 0, Sats: 3 Retry: 0 11:32:11.801> [2015.01.01 00:03:46]- [MODEM.STATUS]->Modem is Initializing (tmo:105) 11:32:11.863> [2015.01.01 00:03:46]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:32:11.926> [2015.01.01 00:03:46]-[AppSend] Checking SMS Data Sending 11:32:11.926> [2015.01.01 00:03:46]-[AppSend] SMS Data Sending is Disabled 11:32:11.988> [2015.01.01 00:03:46]-[AppSend] 391 seconds before periodic data sending 11:32:11.988> [2015.01.01 00:03:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:32:12.613> [2015.01.01 00:03:47]-[SCHED] lvcan request 11:32:12.613> [2015.01.01 00:03:47]- [MODEM.STATUS]->Modem is Initializing (tmo:104) 11:32:12.863> [2015.01.01 00:03:47]- [MODEM.STATUS]->Modem is Initializing (tmo:103) 11:32:12.926> [2015.01.01 00:03:48]- [MODEM.STATUS]->Modem is Initializing (tmo:102) 11:32:13.863> [2015.01.01 00:03:49]- [MODEM.STATUS]->Modem is Initializing (tmo:101) 11:32:14.863> [2015.01.01 00:03:50]- [MODEM.STATUS]->Modem is Initializing (tmo:100) 11:32:15.863> [2015.01.01 00:03:51]- [MODEM.STATUS]->Modem is Initializing (tmo:99) 11:32:16.863> [2015.01.01 00:03:52]- [MODEM.STATUS]->Modem is Initializing (tmo:98) 11:32:17.864> [2015.01.01 00:03:53]-[Trip] Time not synchronized, VECHICLE STOP state 11:32:18.614> [2015.01.01 00:03:53]- [MODEM.STATUS]->Modem is Initializing (tmo:97) 11:32:18.864> [2015.01.01 00:03:54]- [MODEM.STATUS]->Modem is Initializing (tmo:96) 11:32:19.864> [2015.01.01 00:03:55]- [MODEM.STATUS]->Modem is Initializing (tmo:95) 11:32:20.864> [2015.01.01 00:03:56]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:32:21.739> [2015.01.01 00:03:56]- [MODEM.STATUS]->Modem is Initializing (tmo:94) 11:32:21.864> [2015.01.01 00:03:56]-[NMEA Filter] PDOP:7.96, HDOP:7.90, Valid:1 Q: 1, Sats: 3 Retry: 0 11:32:21.926> [2015.01.01 00:03:56]-[AppSend] 381 seconds before periodic data sending 11:32:21.989> [2015.01.01 00:03:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:32:22.614> [2015.01.01 00:03:57]-[ModeChange] GPS selected as movement indicator 11:32:22.614> [2015.01.01 00:03:57]-[SCHED] lvcan request 11:32:22.614> [2015.01.01 00:03:57]-[ModeChange] Nothing to change 11:32:22.614> [2015.01.01 00:03:57]- [MODEM.STATUS]->Modem is Initializing (tmo:93) 11:32:22.864> [2015.01.01 00:03:57]- [MODEM.STATUS]->Modem is Initializing (tmo:92) 11:32:22.926> [2015.01.01 00:03:58]- [MODEM.STATUS]->Modem is Initializing (tmo:91) 11:32:23.864> [2015.01.01 00:03:59]- [MODEM.STATUS]->Modem is Initializing (tmo:90) 11:32:24.864> [2015.01.01 00:04:00]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:231[1800] ds18:0[1800] lvcan:0[1800] 11:32:25.801> [2015.01.01 00:04:00]- [MODEM.STATUS]->Modem is Initializing (tmo:89) 11:32:25.864> [2015.01.01 00:04:01]- [MODEM.STATUS]->Modem is Initializing (tmo:88) 11:32:26.864> [2015.01.01 00:04:02]- [MODEM.STATUS]->Modem is Initializing (tmo:87) 11:32:27.864> [2015.01.01 00:04:02]-[AutoGeo] AutoGeofence is disabled 11:32:27.989> [2015.01.01 00:04:03]- [MODEM.STATUS]->Modem is Initializing (tmo:86) 11:32:28.864> [2015.01.01 00:04:04]- [MODEM.STATUS]->Modem is Initializing (tmo:85) 11:32:29.864> [2015.01.01 00:04:05]- [MODEM.STATUS]->Modem is Initializing (tmo:84) 11:32:30.864> [2015.01.01 00:04:06]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:32:31.739> [2015.01.01 00:04:06]-[MovDetect] state changed 0 -> 1 (mov: 1, speed: -1, tmr: 1) 11:32:31.864> [2015.01.01 00:04:06]- [MODEM.STATUS]->Modem is Initializing (tmo:83) 11:32:31.864> [2015.01.01 00:04:06]-[AppSend] 371 seconds before periodic data sending 11:32:31.989> [2015.01.01 00:04:07]-[StackCheck] StackInfo: 11:32:32.614> 11:32:32.614> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:32:32.614> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:32:32.614> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:32:32.614> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:32:32.614> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:32:32.614> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:32:32.614> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:32:32.614> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:32:32.802> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 752/1024 @ StackInfo 11:32:32.802> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:32:32.802> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 816/1024 @ StackInfo 11:32:32.802> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:32:32.802> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:32:32.802> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:32:32.802> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:32:32.802> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:32:32.802> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 544/ 904 @ StackInfo 11:32:32.802> 11:32:32.802> [2015.01.01 00:04:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:32:32.802> [2015.01.01 00:04:07]-[SCHED] lvcan request 11:32:32.802> [2015.01.01 00:04:07]-[LLS.485] Address table reseted 11:32:32.802> [2015.01.01 00:04:07]-[LLS.485] Go to SCAN procedure 11:32:32.802> [2015.01.01 00:04:07]-[DeepSleep] SLEEP MODE DISABLED! 11:32:33.208> [2015.01.01 00:04:07]- [MODEM.STATUS]->Modem is Initializing (tmo:82) 11:32:33.208> [2015.01.01 00:04:07]-[DEBUG] 11:32:33.208> 11:32:33.208> UARTTxFullCnt: 0 11:32:33.208> SchTaskRunCnt: 10 11:32:33.208> UARTTskRunCnt: 1000 11:32:33.208> AppSaveRunCnt: 10 11:32:33.208> AppSendRunCnt: 10 11:32:33.208> ValidNMEAPkts: 69 11:32:33.208> RS232 procesed: 0 11:32:33.208> RS232 droped : 0 11:32:33.208> RS485 procesed: 0 11:32:33.208> RS485 droped : 0 11:32:33.208> 11:32:33.208> [2015.01.01 00:04:07]- [MODEM.STATUS]->Modem is Initializing (tmo:81) 11:32:33.208> [2015.01.01 00:04:07]-[PERIODIC.NMEA.INFO] 11:32:33.208> 11:32:33.208> Total Pkts : 1483 11:32:33.208> Bad Chksum : 0 11:32:33.208> No Chksum : 0 11:32:33.208> 11:32:33.208> HDOP Cycles: 3 11:32:33.208> HDOP Limit : 4.0 11:32:33.208> HDOP Actual: 2.9 11:32:33.208> HDOP Mean : 2.9 11:32:33.208> HDOP Min : 2.9 11:32:33.208> HDOP Max : 2.9 11:32:33.208> PDOP Actual: 4.8 11:32:33.208> HDOP Rejct : 0 11:32:33.208> HDOP Taken : 60 11:32:33.208> HDOP Timer : 60 11:32:33.208> HDOP Ready : 0 11:32:33.208> 11:32:33.208> Total sats GPS : 8 Tracked: 16 5 7 27 30 11:32:33.208> Total sats GLONASS: 0 Tracked: 11:32:33.208> 11:32:33.208> Bad Time: 0 11:32:33.208> Bad Lat : 0 11:32:33.208> Bad Lon : 0 11:32:33.208> Bad Spd : 0 11:32:33.208> Bad Angl: 0 11:32:33.208> Bad HDOP: 0 11:32:33.208> 11:32:33.208> GPS jmps: 0 11:32:33.208> Filtered: 241 11:32:33.208> Rejected: 0 11:32:33.208> 11:32:33.208> [2015.01.01 00:04:08]-[Trip] Time not synchronized, VECHICLE STOP state 11:32:34.552> [2015.01.01 00:04:08]- [MODEM.STATUS]->Modem is Initializing (tmo:80) 11:32:34.552> [2015.01.01 00:04:09]- [SCENARIOS]-> Stabilizing input data after GPS FIX lost! 11:32:34.630> [2015.01.01 00:04:09]- [MODEM.STATUS]->Modem is Initializing (tmo:79) 11:32:34.864> [2018.03.10 08:32:39]-[TIME] RTC SYNC (RTC: 1420070649, GNSS: 1520670759, Delta: -100600110.000000) 11:32:34.927> [2018.03.10 08:32:40]-[BBox] Event 0x54, saved to slot 81, offset 648, crc 0x12, timestamp 1520670760 11:32:35.614> [2018.03.10 08:32:40]-[Trip] Time synchronized, going to the WAITING state. 11:32:35.614> [2018.03.10 08:32:40]-[Trip] IO odometer disabled. Driven distance won't be counted! 11:32:35.614> [2018.03.10 08:32:40]-[AppDatSav] No record has been saved in 1520670759 second(s), MinPeriod: 600 second(s) 11:32:35.614> [2018.03.10 08:32:40]-[AppDatSav] uiMinPeriod (1520670759) record action 11:32:35.739> [2018.03.10 08:32:40]- [APP.GPS]-> Saving Periodic Record 11:32:35.739> [2018.03.10 08:32:40]-[AVL] Record Saved: 11:32:35.739> 11:32:35.739> Timestamp: 1520670760000 11:32:35.739> Priority : 0 11:32:35.739> Latitude : 545581675 11:32:35.739> Longitude: 362932178 11:32:35.739> Altitude : 231 11:32:35.739> Angle : 0 11:32:35.739> Speed : 1 11:32:35.739> HDOP : 2.9 11:32:35.739> SatInUse : 5 11:32:35.739> 11:32:35.739> Event ID : 0 11:32:35.739> 11:32:35.739> RecSize : 30 Bytes 11:32:35.739> 11:32:35.739> [2018.03.10 08:32:40]- [FLASH]->New Record Address: 0x00043A00 Length: 30, 058 / 256 11:32:35.739> [2018.03.10 08:32:40]- [MODEM.STATUS]->Modem is Initializing (tmo:78) 11:32:36.208> [2018.03.10 08:32:40]- [MODEM.STATUS]->Modem is Initializing (tmo:77) 11:32:36.208> [2018.03.10 08:32:40]-[GPRS] Repeating task after delay 11:32:36.208> [2018.03.10 08:32:41]-[AppDatSav] Available 11:32:36.614> [2018.03.10 08:32:41]-[AppDatSav] 599 seconds before periodic record save 11:32:36.614> [2018.03.10 08:32:41]- [MODEM.STATUS]->Modem is Initializing (tmo:76) 11:32:36.864> [2018.03.10 08:32:42]- [MODEM.STATUS]->Modem is Initializing (tmo:75) 11:32:37.864> [2018.03.10 08:32:43]- [SCENARIOS]-> Stabilizing input data after GPS FIX lost! 11:32:38.615> [2018.03.10 08:32:43]- [MODEM.STATUS]->Modem is Initializing (tmo:74) 11:32:38.865> [2018.03.10 08:32:44]-[MovDetect] state changed 1 -> 0 (mov: 0, speed: 0, tmr: 0) 11:32:39.865> [2018.03.10 08:32:44]- [MODEM.STATUS]->Modem is Initializing (tmo:73) 11:32:39.865> [2018.03.10 08:32:45]- [SCENARIOS]-> GPS Data Stable 11:32:40.615> [2018.03.10 08:32:45]- [MODEM.STATUS]->Modem is Initializing (tmo:72) 11:32:40.865> [2018.03.10 08:32:46]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:32:41.740> [2018.03.10 08:32:46]- [MODEM.STATUS]->Modem is Initializing (tmo:71) 11:32:41.865> [2018.03.10 08:32:46]-[AppSend] No sending procedure in 100600349 sec, configured period: 600 sec 11:32:41.990> [2018.03.10 08:32:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:32:42.615> [2018.03.10 08:32:47]-[SCHED] lvcan request 11:32:42.615> [2018.03.10 08:32:47]- [MODEM.STATUS]->Modem is Initializing (tmo:70) 11:32:42.865> [2018.03.10 08:32:47]- [MODEM.STATUS]->Modem is Initializing (tmo:69) 11:32:42.990> [2018.03.10 08:32:48]- [MODEM.STATUS]->Modem is Initializing (tmo:68) 11:32:43.865> [2018.03.10 08:32:49]- [MODEM.STATUS]->Modem is Initializing (tmo:67) 11:32:44.865> [2018.03.10 08:32:50]- [MODEM.STATUS]->Modem is Initializing (tmo:66) 11:32:45.865> [2018.03.10 08:32:51]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:252[1800] ds18:0[1800] lvcan:0[1800] 11:32:46.802> [2018.03.10 08:32:51]- [MODEM.STATUS]->Modem is Initializing (tmo:65) 11:32:46.865> [2018.03.10 08:32:52]- [MODEM.STATUS]->Modem is Initializing (tmo:64) 11:32:47.865> [2018.03.10 08:32:53]- [MODEM.STATUS]->Modem is Initializing (tmo:63) 11:32:48.865> [2018.03.10 08:32:54]- [MODEM.STATUS]->Modem is Initializing (tmo:62) 11:32:49.865> [2018.03.10 08:32:55]- [MODEM.STATUS]->Modem is Initializing (tmo:61) 11:32:50.865> [2018.03.10 08:32:56]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:32:51.740> [2018.03.10 08:32:56]- [MODEM.STATUS]->Modem is Initializing (tmo:60) 11:32:51.865> [2018.03.10 08:32:56]-[AppSend] No sending procedure in 100600359 sec, configured period: 600 sec 11:32:51.990> [2018.03.10 08:32:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:32:52.615> [2018.03.10 08:32:57]-[SCHED] lvcan request 11:32:52.615> [2018.03.10 08:32:57]- [MODEM.STATUS]->Modem is Initializing (tmo:59) 11:32:52.865> [2018.03.10 08:32:57]- [MODEM.STATUS]->Modem is Initializing (tmo:58) 11:32:52.990> [2018.03.10 08:32:58]- [MODEM.STATUS]->Modem is Initializing (tmo:57) 11:32:53.865> [2018.03.10 08:32:59]- [MODEM.STATUS]->Modem is Initializing (tmo:56) 11:32:54.865> [2018.03.10 08:33:00]- [MODEM.STATUS]->Modem is Initializing (tmo:55) 11:32:55.865> [2018.03.10 08:33:01]- [MODEM.STATUS]->Modem is Initializing (tmo:54) 11:32:56.865> [2018.03.10 08:33:02]- [MODEM.STATUS]->Modem is Initializing (tmo:53) 11:32:57.865> [2018.03.10 08:33:02]-[AppSend] No sending procedure in 100600365 sec, configured period: 600 sec 11:32:57.990> [2018.03.10 08:33:03]- [MODEM.STATUS]->Modem is Initializing (tmo:52) 11:32:58.866> [2018.03.10 08:33:04]- [MODEM.STATUS]->Modem is Initializing (tmo:51) 11:32:59.866> [2018.03.10 08:33:05]- [MODEM.STATUS]->Modem is Initializing (tmo:50) 11:33:00.866> [2018.03.10 08:33:06]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:33:01.741> [2018.03.10 08:33:06]- [MODEM.STATUS]->Modem is Initializing (tmo:49) 11:33:01.866> [2018.03.10 08:33:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:33:02.616> [2018.03.10 08:33:07]-[SCHED] lvcan request 11:33:02.616> [2018.03.10 08:33:07]- [MODEM.STATUS]->Modem is Initializing (tmo:48) 11:33:02.866> [2018.03.10 08:33:07]- [MODEM.STATUS]->Modem is Initializing (tmo:47) 11:33:02.991> [2018.03.10 08:33:08]- [MODEM.STATUS]->Modem is Initializing (tmo:46) 11:33:03.866> [2018.03.10 08:33:09]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:33:04.616> [2018.03.10 08:33:09]- [MODEM.STATUS]->Modem is Initializing (tmo:45) 11:33:04.866> [2018.03.10 08:33:10]- [MODEM.STATUS]->Modem is Initializing (tmo:44) 11:33:05.866> [2018.03.10 08:33:11]- [MODEM.STATUS]->Modem is Initializing (tmo:43) 11:33:06.866> [2018.03.10 08:33:12]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:273[1800] ds18:0[1800] lvcan:0[1800] 11:33:07.803> [2018.03.10 08:33:12]- [MODEM.STATUS]->Modem is Initializing (tmo:42) 11:33:07.866> [2018.03.10 08:33:12]-[ModeChange] GPS selected as movement indicator 11:33:07.928> [2018.03.10 08:33:12]-[AppSend] No sending procedure in 100600375 sec, configured period: 600 sec 11:33:07.991> [2018.03.10 08:33:13]-[ModeChange] GPS selected as movement indicator 11:33:08.616> [2018.03.10 08:33:13]-[ModeChange] Nothing to change 11:33:08.616> [2018.03.10 08:33:13]- [MODEM.STATUS]->Modem is Initializing (tmo:41) 11:33:08.866> [2018.03.10 08:33:14]- [MODEM.STATUS]->Modem is Initializing (tmo:40) 11:33:09.866> [2018.03.10 08:33:15]- [MODEM.STATUS]->Modem is Initializing (tmo:39) 11:33:10.866> [2018.03.10 08:33:16]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:33:12.147> [2018.03.10 08:33:16]- [MODEM.STATUS]->Modem is Initializing (tmo:38) 11:33:12.147> [2018.03.10 08:33:16]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:33:12.147> [2018.03.10 08:33:16]-[AppSend] Checking SMS Data Sending 11:33:12.147> [2018.03.10 08:33:16]-[AppSend] SMS Data Sending is Disabled 11:33:12.147> [2018.03.10 08:33:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:33:12.616> [2018.03.10 08:33:17]-[SCHED] lvcan request 11:33:12.616> [2018.03.10 08:33:17]- [MODEM.STATUS]->Modem is Initializing (tmo:37) 11:33:12.866> [2018.03.10 08:33:17]- [MODEM.STATUS]->Modem is Initializing (tmo:36) 11:33:12.991> [2018.03.10 08:33:17]-[DEBUG] 11:33:12.991> 11:33:12.991> UARTTxFullCnt: 0 11:33:12.991> SchTaskRunCnt: 10 11:33:12.991> UARTTskRunCnt: 1000 11:33:12.991> AppSaveRunCnt: 10 11:33:12.991> AppSendRunCnt: 10 11:33:12.991> ValidNMEAPkts: 70 11:33:12.991> RS232 procesed: 0 11:33:12.991> RS232 droped : 0 11:33:12.991> RS485 procesed: 0 11:33:12.991> RS485 droped : 0 11:33:12.991> 11:33:12.991> [2018.03.10 08:33:18]- [MODEM.STATUS]->Modem is Initializing (tmo:35) 11:33:13.866> [2018.03.10 08:33:19]- [MODEM.STATUS]->Modem is Initializing (tmo:34) 11:33:14.866> [2018.03.10 08:33:19]-[AutoGeo] AutoGeofence is disabled 11:33:14.991> [2018.03.10 08:33:20]- [MODEM.STATUS]->Modem is Initializing (tmo:33) 11:33:15.866> [2018.03.10 08:33:21]- [MODEM.STATUS]->Modem is Initializing (tmo:32) 11:33:16.866> [2018.03.10 08:33:22]-[Scenarios] Periodic info: 11:33:17.616> DOUT1 scenarios disabled 11:33:17.616> [2018.03.10 08:33:22]-[DOUT2.None] State: DISABLED 11:33:17.616> [2018.03.10 08:33:22]- [MODEM.STATUS]->Modem is Initializing (tmo:31) 11:33:17.866> [2018.03.10 08:33:22]-[AppSend] No sending procedure in 100600385 sec, configured period: 600 sec 11:33:17.991> [2018.03.10 08:33:23]- [MODEM.STATUS]->Modem is Initializing (tmo:30) 11:33:18.867> [2018.03.10 08:33:24]- [MODEM.STATUS]->Modem is Initializing (tmo:29) 11:33:19.867> [2018.03.10 08:33:25]- [MODEM.STATUS]->Modem is Initializing (tmo:28) 11:33:20.867> [2018.03.10 08:33:26]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:33:21.742> [2018.03.10 08:33:26]- [MODEM.STATUS]->Modem is Initializing (tmo:27) 11:33:21.867> [2018.03.10 08:33:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:33:22.617> [2018.03.10 08:33:27]-[SCHED] lvcan request 11:33:22.617> [2018.03.10 08:33:27]- [MODEM.STATUS]->Modem is Initializing (tmo:26) 11:33:22.867> [2018.03.10 08:33:27]- [MODEM.STATUS]->Modem is Initializing (tmo:25) 11:33:22.992> [2018.03.10 08:33:28]- [MODEM.STATUS]->Modem is Initializing (tmo:24) 11:33:23.867> [2018.03.10 08:33:29]- [MODEM.STATUS]->Modem is Initializing (tmo:23) 11:33:24.867> [2018.03.10 08:33:30]- [MODEM.STATUS]->Modem is Initializing (tmo:22) 11:33:25.867> [2018.03.10 08:33:31]- [MODEM.STATUS]->Modem is Initializing (tmo:21) 11:33:26.867> [2018.03.10 08:33:32]- [MODEM.STATUS]->Modem is Initializing (tmo:20) 11:33:27.867> [2018.03.10 08:33:32]-[AppSend] No sending procedure in 100600395 sec, configured period: 600 sec 11:33:27.992> [2018.03.10 08:33:33]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:294[1800] ds18:0[1800] lvcan:0[1800] 11:33:28.804> [2018.03.10 08:33:33]- [MODEM.STATUS]->Modem is Initializing (tmo:19) 11:33:28.867> [2018.03.10 08:33:34]- [MODEM.STATUS]->Modem is Initializing (tmo:18) 11:33:29.867> [2018.03.10 08:33:35]- [MODEM.STATUS]->Modem is Initializing (tmo:17) 11:33:30.867> [2018.03.10 08:33:36]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:33:31.742> [2018.03.10 08:33:36]- [MODEM.STATUS]->Modem is Initializing (tmo:16) 11:33:31.867> [2018.03.10 08:33:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:33:32.617> [2018.03.10 08:33:37]-[SCHED] lvcan request 11:33:32.617> [2018.03.10 08:33:37]-[Scheduler] GC is scheduled after: 6900 sec 11:33:32.617> [2018.03.10 08:33:37]-[LLS.485] Address table reseted 11:33:32.617> [2018.03.10 08:33:37]-[LLS.485] Go to SCAN procedure 11:33:32.617> [2018.03.10 08:33:37]-[DeepSleep] SLEEP MODE DISABLED! 11:33:32.867> [2018.03.10 08:33:37]- [MODEM.STATUS]->Modem is Initializing (tmo:15) 11:33:32.867> [2018.03.10 08:33:37]- [MODEM.STATUS]->Modem is Initializing (tmo:14) 11:33:32.992> [2018.03.10 08:33:37]-[PERIODIC.BATRAM.INFO] 11:33:33.055> 11:33:33.055> BootSign : 0x0000 11:33:33.055> BootSign Inv: 0x0000 11:33:33.055> 11:33:33.055> Date : 2018.03.10 11:33:33.055> TimeSync : 1 11:33:33.055> Uptime : 307 11:33:33.055> 11:33:33.055> Restart Cnt : 0 11:33:33.055> WDG Restarts: 0 11:33:33.055> Bad Records : 7 11:33:33.055> AuthState : 0 11:33:33.055> GC Timer : 300 11:33:33.055> 11:33:33.055> [2018.03.10 08:33:38]- [MODEM.STATUS]->Modem is Initializing (tmo:13) 11:33:33.867> [2018.03.10 08:33:39]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:33:34.617> [2018.03.10 08:33:39]- [MODEM.STATUS]->Modem is Initializing (tmo:12) 11:33:34.867> [2018.03.10 08:33:40]- [MODEM.STATUS]->Modem is Initializing (tmo:11) 11:33:35.867> [2018.03.10 08:33:41]- [MODEM.STATUS]->Modem is Initializing (tmo:10) 11:33:36.430> [2018.03.10 08:33:41]-[GPRS] Repeating task after delay 11:33:36.555> [2018.03.10 08:33:41]- [MODEM.STATUS]->Modem is Initializing (tmo:9) 11:33:36.867> [2018.03.10 08:33:42]-[AppDatSav] Available 11:33:37.617> [2018.03.10 08:33:42]-[AppDatSav] 538 seconds before periodic record save 11:33:37.617> [2018.03.10 08:33:42]- [MODEM.STATUS]->Modem is Initializing (tmo:8) 11:33:37.867> [2018.03.10 08:33:42]-[AppSend] No sending procedure in 100600405 sec, configured period: 600 sec 11:33:37.992> [2018.03.10 08:33:43]- [MODEM.STATUS]->Modem is Initializing (tmo:7) 11:33:38.867> [2018.03.10 08:33:44]- [MODEM.STATUS]->Modem is Initializing (tmo:6) 11:33:39.868> [2018.03.10 08:33:45]- [MODEM.STATUS]->Modem is Initializing (tmo:5) 11:33:40.868> [2018.03.10 08:33:46]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:33:41.743> [2018.03.10 08:33:46]- [MODEM.STATUS]->Modem is Initializing (tmo:4) 11:33:41.868> [2018.03.10 08:33:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:33:42.618> [2018.03.10 08:33:47]-[SCHED] lvcan request 11:33:42.618> [2018.03.10 08:33:47]- [MODEM.STATUS]->Modem is Initializing (tmo:3) 11:33:42.868> [2018.03.10 08:33:47]- [MODEM.STATUS]->Modem is Initializing (tmo:2) 11:33:42.993> [2018.03.10 08:33:48]- [MODEM.STATUS]->Modem is Initializing (tmo:1) 11:33:43.868> [2018.03.10 08:33:49]- [MODEM.STATUS]->Modem is Initializing (tmo:0) 11:33:44.868> [2018.03.10 08:33:50]- [MODEM.STATUS]->Initiating Modem Restart Procedure 11:33:45.868> [2018.03.10 08:33:51]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:33:46.868> [2018.03.10 08:33:52]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:33:47.868> [2018.03.10 08:33:52]-[AppSend] No sending procedure in 100600415 sec, configured period: 600 sec 11:33:47.993> [2018.03.10 08:33:53]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:33:48.868> [2018.03.10 08:33:54]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:315[1800] ds18:0[1800] lvcan:0[1800] 11:33:49.806> [2018.03.10 08:33:54]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:33:49.868> [2018.03.10 08:33:55]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:33:50.868> [2018.03.10 08:33:56]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:33:51.743> [2018.03.10 08:33:56]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:33:51.868> [2018.03.10 08:33:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:33:52.618> [2018.03.10 08:33:57]-[SCHED] lvcan request 11:33:52.618> [2018.03.10 08:33:57]- [MODEM.STATUS]->Modem is Initializing (tmo:294) 11:33:52.868> [2018.03.10 08:33:57]- [MODEM.STATUS]->Modem is Initializing (tmo:293) 11:33:52.993> [2018.03.10 08:33:57]-[DEBUG] 11:33:53.056> 11:33:53.056> UARTTxFullCnt: 0 11:33:53.056> SchTaskRunCnt: 10 11:33:53.056> UARTTskRunCnt: 1000 11:33:53.056> AppSaveRunCnt: 10 11:33:53.056> AppSendRunCnt: 10 11:33:53.056> ValidNMEAPkts: 74 11:33:53.056> RS232 procesed: 0 11:33:53.056> RS232 droped : 0 11:33:53.056> RS485 procesed: 0 11:33:53.056> RS485 droped : 0 11:33:53.056> 11:33:53.056> [2018.03.10 08:33:58]- [MODEM.STATUS]->Modem is Initializing (tmo:292) 11:33:53.868> [2018.03.10 08:33:58]-[ModeChange] GPS selected as movement indicator 11:33:53.931> [2018.03.10 08:33:59]-[ModeChange] GPS selected as movement indicator 11:33:54.618> [2018.03.10 08:33:59]-[ModeChange] Nothing to change 11:33:54.618> [2018.03.10 08:33:59]- [MODEM.STATUS]->Modem is Initializing (tmo:291) 11:33:54.868> [2018.03.10 08:34:00]- [MODEM.STATUS]->Modem is Initializing (tmo:290) 11:33:55.868> [2018.03.10 08:34:01]- [MODEM.STATUS]->Modem is Initializing (tmo:289) 11:33:56.868> [2018.03.10 08:34:02]- [MODEM.STATUS]->Modem is Initializing (tmo:288) 11:33:57.868> [2018.03.10 08:34:02]-[AppSend] No sending procedure in 100600425 sec, configured period: 600 sec 11:33:57.993> [2018.03.10 08:34:03]- [MODEM.STATUS]->Modem is Initializing (tmo:287) 11:33:58.868> [2018.03.10 08:34:04]- [MODEM.STATUS]->Modem is Initializing (tmo:286) 11:33:59.869> [2018.03.10 08:34:05]- [MODEM.STATUS]->Modem is Initializing (tmo:285) 11:34:00.869> [2018.03.10 08:34:06]- [OPER.WAIT]->Wait For Registration Confirmation from CREG 11:34:01.744> [2018.03.10 08:34:06]-<< AT+CREG? 11:34:01.744> [2018.03.10 08:34:06]- [MODEM]->Not Registered 11:34:01.806> [2018.03.10 08:34:06]->> +CREG: 2,0 11:34:01.806> [2018.03.10 08:34:06]->> OK 11:34:01.806> [2018.03.10 08:34:06]-[InitModem] ERROR @ modem_init.c(316): [0] Numerical operator request failed 11:34:01.869> [2018.03.10 08:34:06]-<< AT 11:34:01.869> [2018.03.10 08:34:06]- [MODEM.STATUS]->Modem is Initializing (tmo:284) 11:34:01.869> [2018.03.10 08:34:06]->> OK 11:34:01.869> [2018.03.10 08:34:06]-<< AT 11:34:01.869> [2018.03.10 08:34:06]->> OK 11:34:01.931> [2018.03.10 08:34:06]-[InitModem] Op List Not Empty Try connect Manually 11:34:01.931> [2018.03.10 08:34:06]-[InitModem] Starting OP Search Procedure 11:34:01.931> [2018.03.10 08:34:06]-<< AT+COPS=? 11:34:01.931> [2018.03.10 08:34:06]->> +CME ERROR: operation not allowed 11:34:01.994> [2018.03.10 08:34:06]-[AutoGeo] AutoGeofence is disabled 11:34:01.994> [2018.03.10 08:34:06]-[InitModem] Get Available Ops Failed 11:34:01.994> [2018.03.10 08:34:06]-[BBox] Event 0x26, saved to slot 82, offset 656, crc 0xAA, timestamp 1520670846 11:34:02.056> [2018.03.10 08:34:06]-[InitModem] Modem state set to UNAVAILABLE! 11:34:02.056> [2018.03.10 08:34:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:34:02.619> [2018.03.10 08:34:07]-[SCHED] lvcan request 11:34:02.619> [2018.03.10 08:34:07]- [MODEM.STATUS]->Initiating Modem Restart Procedure 11:34:02.869> [2018.03.10 08:34:07]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:34:02.994> [2018.03.10 08:34:08]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:34:03.869> [2018.03.10 08:34:09]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:34:04.619> [2018.03.10 08:34:09]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:34:04.869> [2018.03.10 08:34:10]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:34:05.869> [2018.03.10 08:34:11]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:34:06.869> [2018.03.10 08:34:11]-[InitModem] Waiting for process mutex... 11:34:07.056> [2018.03.10 08:34:11]-[InitModem] Process mutex taken 11:34:07.056> [2018.03.10 08:34:11]-[BBox] Event 0x31, saved to slot 83, offset 664, crc 0xD3, timestamp 1520670851 11:34:07.056> [2018.03.10 08:34:12]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:34:07.869> [2018.03.10 08:34:12]-[AppSend] No sending procedure in 100600435 sec, configured period: 600 sec 11:34:07.994> [2018.03.10 08:34:12]-[InitModem] Cycle: 1 of 10 11:34:08.056> [2018.03.10 08:34:12]-[InitModem] Restarting modem... 11:34:08.056> [2018.03.10 08:34:13]- [MODEM.STATUS]->Modem is OFF. 11:34:08.869> [2018.03.10 08:34:14]- [MODEM.STATUS]->Modem is OFF. 11:34:09.869> [2018.03.10 08:34:15]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:3[1800] ds18:0[1800] lvcan:0[1800] 11:34:10.807> [2018.03.10 08:34:15]- [MODEM.STATUS]->Modem is OFF. 11:34:10.869> [2018.03.10 08:34:16]- [MODEM.STATUS]->Modem is OFF. 11:34:11.869> [2018.03.10 08:34:16]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:34:11.994> [2018.03.10 08:34:16]-[AppSend] Checking SMS Data Sending 11:34:11.994> [2018.03.10 08:34:16]-[AppSend] SMS Data Sending is Disabled 11:34:11.994> [2018.03.10 08:34:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:34:12.619> [2018.03.10 08:34:17]-[SCHED] lvcan request 11:34:12.619> [2018.03.10 08:34:17]- [MODEM.STATUS]->Modem is OFF. 11:34:12.869> [2018.03.10 08:34:17]- [MODEM.STATUS]->Modem is OFF. 11:34:12.994> [2018.03.10 08:34:18]- [MODEM.STATUS]->Modem is OFF. 11:34:13.869> [2018.03.10 08:34:19]- [MODEM.STATUS]->Modem is OFF. 11:34:14.869> [2018.03.10 08:34:20]- [MODEM.STATUS]->Modem is OFF. 11:34:15.869> [2018.03.10 08:34:20]-[InitModem] Wait For Ready 11:34:16.119> [2018.03.10 08:34:21]- RX:0, TX:0, CTS:1, RTS:1, ON:0, PWR:1, RESET:1, DTR:0 11:34:16.119> [2018.03.10 08:34:21]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:34:16.869> [2018.03.10 08:34:22]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:34:17.869> [2018.03.10 08:34:22]-[AppSend] No sending procedure in 100600445 sec, configured period: 600 sec 11:34:17.994> [2018.03.10 08:34:23]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:34:18.869> [2018.03.10 08:34:24]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:34:19.869> [2018.03.10 08:34:25]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:34:20.870> [2018.03.10 08:34:26]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:34:21.870> [2018.03.10 08:34:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:34:22.620> [2018.03.10 08:34:27]-[SCHED] lvcan request 11:34:22.620> [2018.03.10 08:34:27]- [MODEM.STATUS]->Modem is Initializing (tmo:294) 11:34:22.870> [2018.03.10 08:34:27]- [MODEM.STATUS]->Modem is Initializing (tmo:293) 11:34:22.995> [2018.03.10 08:34:28]- [MODEM.STATUS]->Modem is Initializing (tmo:292) 11:34:23.870> [2018.03.10 08:34:29]- [MODEM.STATUS]->Modem is Initializing (tmo:291) 11:34:24.870> [2018.03.10 08:34:30]- [MODEM.STATUS]->Modem is Initializing (tmo:290) 11:34:25.870> [2018.03.10 08:34:31]- [MODEM.STATUS]->Modem is Initializing (tmo:289) 11:34:26.870> [2018.03.10 08:34:32]- [MODEM.STATUS]->Modem is Initializing (tmo:288) 11:34:27.886> [2018.03.10 08:34:32]-[AppSend] No sending procedure in 100600455 sec, configured period: 600 sec 11:34:28.136> [2018.03.10 08:34:33]- [MODEM.STATUS]->Modem is Initializing (tmo:287) 11:34:28.870> [2018.03.10 08:34:34]- [MODEM.STATUS]->Modem is Initializing (tmo:286) 11:34:29.870> [2018.03.10 08:34:35]- [MODEM.STATUS]->Modem is Initializing (tmo:285) 11:34:30.870> [2018.03.10 08:34:36]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:24[1800] ds18:0[1800] lvcan:0[1800] 11:34:31.808> [2018.03.10 08:34:36]- [MODEM.STATUS]->Modem is Initializing (tmo:284) 11:34:31.870> [2018.03.10 08:34:37]-[StackCheck] StackInfo: 11:34:32.620> 11:34:32.620> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:34:32.620> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:34:32.620> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:34:32.620> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:34:32.620> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:34:32.620> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:34:32.620> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:34:33.433> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:34:33.433> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 368/1024 @ StackInfo 11:34:33.433> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:34:33.433> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 800/1024 @ StackInfo 11:34:33.433> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:34:33.433> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:34:33.433> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:34:33.433> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:34:33.433> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:34:33.433> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 264/ 904 @ StackInfo 11:34:33.433> 11:34:33.433> [2018.03.10 08:34:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:34:33.433> [2018.03.10 08:34:37]-[SCHED] lvcan request 11:34:33.433> [2018.03.10 08:34:37]-[LLS.485] Address table reseted 11:34:33.433> [2018.03.10 08:34:37]-[LLS.485] Go to SCAN procedure 11:34:33.433> [2018.03.10 08:34:37]-[DeepSleep] SLEEP MODE DISABLED! 11:34:33.433> [2018.03.10 08:34:37]- [MODEM.STATUS]->Modem is Initializing (tmo:283) 11:34:33.433> [2018.03.10 08:34:37]- [MODEM.STATUS]->Modem is Initializing (tmo:282) 11:34:33.433> [2018.03.10 08:34:37]-[DEBUG] 11:34:33.433> 11:34:33.433> UARTTxFullCnt: 0 11:34:33.433> SchTaskRunCnt: 10 11:34:33.433> UARTTskRunCnt: 1000 11:34:33.433> AppSaveRunCnt: 10 11:34:33.433> AppSendRunCnt: 10 11:34:33.433> ValidNMEAPkts: 80 11:34:33.433> RS232 procesed: 0 11:34:33.433> RS232 droped : 0 11:34:33.433> RS485 procesed: 0 11:34:33.433> RS485 droped : 0 11:34:33.433> 11:34:33.433> [2018.03.10 08:34:37]-[FWInfo] 11:34:33.433> 11:34:33.433> FW version: 01.21.10.Rev.00 11:34:33.433> BL version: 01.08 11:34:33.433> Toolchain : 4.0.0524 11:34:33.433> Compiled : Oct 20 2016 @ 09:05:49 11:34:33.433> HW version: FM11YX_MOD1_A0:B0:C0:D0:E2:F0:G0:H0:I0:J0 11:34:33.433> HW voltage: 1241 mV 11:34:33.433> 11:34:33.433> [2018.03.10 08:34:37]-[PERIODIC.ONLINE.INFO] 11:34:33.433> 11:34:33.433> Code Ver : 01.21.10.Rev:0 11:34:33.433> BL Version : 01.08 11:34:33.433> Hw Version : FM11YX_MOD1_A0:B0:C0:D0:E2:F0:G0:H0:I0:J0 11:34:33.433> 11:34:33.433> Device IMEI : 356307040580804 11:34:33.433> Device IMSI : 250015890967995 11:34:33.433> Modem AppVer : 1.03.03 11:34:33.433> Modem FullVer : TM11Q_R_01.03.03.03_002 11:34:33.433> 11:34:33.433> Reset Cause : Power On / Power Down 11:34:33.433> Startup Time : 2018/3/10 8:28:30 11:34:33.433> RTC Clock : 2018/3/10 8:34:38 11:34:33.433> GPS Clock : 2018/3/10 8:34:37 11:34:33.433> Device Uptime : 0:06:08 11:34:33.433> 11:34:33.433> Sesion Upload : 0 Bytes 11:34:33.433> Total Upload : 0 Bytes 11:34:33.433> SMS Sent : 0 11:34:33.433> Received : 0 11:34:33.433> 11:34:33.433> Restarts Made : 4 11:34:33.433> Errors Detected: 1 11:34:33.433> Records Sent : 0 11:34:33.433> BadRec Detected: 7 11:34:33.433> ProfCRC Fails : 0 11:34:33.433> Failed GPRS : 0 11:34:33.433> Failed Link : 0 11:34:33.433> UDP Link TMO : 0 11:34:33.433> 11:34:33.433> No GPS Timer : 0:4:2 11:34:33.433> GPS Status : 3 11:34:33.433> Actual Sat : 5 11:34:33.433> Maximum Sat : 6 11:34:33.433> Last fix acqu : 238 sec (acquired) 11:34:33.433> 11:34:33.433> Working Mode : 4 - Unknown on Stop 11:34:33.433> 11:34:33.433> [2018.03.10 08:34:38]- [Ver]->01.21.10,0,356307040580804,Oct 20 2016,01.08,06,0,0,1.03.03,1241,,,,,, 11:34:33.433> [2018.03.10 08:34:38]- [MODEM.STATUS]->Modem is Initializing (tmo:281) 11:34:37.308> [2018.03.10 08:34:39]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:34:37.308> [2018.03.10 08:34:39]- [MODEM.STATUS]->Modem is Initializing (tmo:280) 11:34:37.308> [2018.03.10 08:34:40]- [MODEM.STATUS]->Modem is Initializing (tmo:279) 11:34:37.308> [2018.03.10 08:34:41]- [MODEM.STATUS]->Modem is Initializing (tmo:278) 11:34:37.308> [2018.03.10 08:34:41]- [MODEM.STATUS]->Modem is Initializing (tmo:277) 11:34:37.308> [2018.03.10 08:34:42]-[GPRS] Repeating task after delay 11:34:37.308> [2018.03.10 08:34:42]- [MODEM.STATUS]->Modem is Initializing (tmo:276) 11:34:37.870> [2018.03.10 08:34:42]-[AppSend] No sending procedure in 100600465 sec, configured period: 600 sec 11:34:37.995> [2018.03.10 08:34:43]-[AppDatSav] Available 11:34:38.620> [2018.03.10 08:34:43]-[AppDatSav] 477 seconds before periodic record save 11:34:38.620> [2018.03.10 08:34:43]- [MODEM.STATUS]->Modem is Initializing (tmo:275) 11:34:38.870> [2018.03.10 08:34:44]- [MODEM.STATUS]->Modem is Initializing (tmo:274) 11:34:39.870> [2018.03.10 08:34:44]-[ModeChange] GPS selected as movement indicator 11:34:39.933> [2018.03.10 08:34:45]-[ModeChange] GPS selected as movement indicator 11:34:40.621> [2018.03.10 08:34:45]-[ModeChange] Nothing to change 11:34:40.621> [2018.03.10 08:34:45]- [MODEM.STATUS]->Modem is Initializing (tmo:273) 11:34:40.871> [2018.03.10 08:34:46]- [MODEM.STATUS]->Modem is Initializing (tmo:272) 11:34:41.871> [2018.03.10 08:34:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:34:42.621> [2018.03.10 08:34:47]-[SCHED] lvcan request 11:34:42.621> [2018.03.10 08:34:47]- [MODEM.STATUS]->Modem is Initializing (tmo:271) 11:34:42.871> [2018.03.10 08:34:47]- [MODEM.STATUS]->Modem is Initializing (tmo:270) 11:34:42.996> [2018.03.10 08:34:48]- [MODEM.STATUS]->Modem is Initializing (tmo:269) 11:34:43.871> [2018.03.10 08:34:49]- [MODEM.STATUS]->Modem is Initializing (tmo:268) 11:34:44.871> [2018.03.10 08:34:50]- [MODEM.STATUS]->Modem is Initializing (tmo:267) 11:34:45.871> [2018.03.10 08:34:51]- [MODEM.STATUS]->Modem is Initializing (tmo:266) 11:34:46.871> [2018.03.10 08:34:52]- [MODEM.STATUS]->Modem is Initializing (tmo:265) 11:34:47.871> [2018.03.10 08:34:52]-[AppSend] No sending procedure in 100600475 sec, configured period: 600 sec 11:34:47.996> [2018.03.10 08:34:53]- [MODEM.STATUS]->Modem is Initializing (tmo:264) 11:34:48.871> [2018.03.10 08:34:53]-[AutoGeo] AutoGeofence is disabled 11:34:48.996> [2018.03.10 08:34:54]- [MODEM.STATUS]->Modem is Initializing (tmo:263) 11:34:49.871> [2018.03.10 08:34:55]- [MODEM.STATUS]->Modem is Initializing (tmo:262) 11:34:50.871> [2018.03.10 08:34:56]- [MODEM.STATUS]->Modem is Initializing (tmo:261) 11:34:51.871> [2018.03.10 08:34:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:34:52.621> [2018.03.10 08:34:57]-[Scenarios] Periodic info: 11:34:52.621> DOUT1 scenarios disabled 11:34:52.621> [2018.03.10 08:34:57]-[DOUT2.None] State: DISABLED 11:34:52.621> [2018.03.10 08:34:57]-[SCHED] lvcan request 11:34:52.621> [2018.03.10 08:34:57]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:45[1800] ds18:0[1800] lvcan:0[1800] 11:34:52.809> [2018.03.10 08:34:57]- [MODEM.STATUS]->Modem is Initializing (tmo:260) 11:34:52.871> [2018.03.10 08:34:57]- [MODEM.STATUS]->Modem is Initializing (tmo:259) 11:34:52.996> [2018.03.10 08:34:58]- [MODEM.STATUS]->Modem is Initializing (tmo:258) 11:34:53.871> [2018.03.10 08:34:59]- [MODEM.STATUS]->Modem is Initializing (tmo:257) 11:34:54.871> [2018.03.10 08:35:00]- [MODEM.STATUS]->Modem is Initializing (tmo:256) 11:34:55.871> [2018.03.10 08:35:01]- [MODEM.STATUS]->Modem is Initializing (tmo:255) 11:34:56.887> [2018.03.10 08:35:02]- [MODEM.STATUS]->Modem is Initializing (tmo:254) 11:34:57.903> [2018.03.10 08:35:02]-[AppSend] No sending procedure in 100600485 sec, configured period: 600 sec 11:34:57.996> [2018.03.10 08:35:03]- [MODEM.STATUS]->Modem is Initializing (tmo:253) 11:34:58.871> [2018.03.10 08:35:04]- [MODEM.STATUS]->Modem is Initializing (tmo:252) 11:34:59.871> [2018.03.10 08:35:05]- [MODEM.STATUS]->Modem is Initializing (tmo:251) 11:35:00.871> [2018.03.10 08:35:06]- [MODEM.STATUS]->Modem is Initializing (tmo:250) 11:35:01.872> [2018.03.10 08:35:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:35:02.622> [2018.03.10 08:35:07]-[SCHED] lvcan request 11:35:02.622> [2018.03.10 08:35:07]- [MODEM.STATUS]->Modem is Initializing (tmo:249) 11:35:02.872> [2018.03.10 08:35:07]- [MODEM.STATUS]->Modem is Initializing (tmo:248) 11:35:02.997> [2018.03.10 08:35:08]- [MODEM.STATUS]->Modem is Initializing (tmo:247) 11:35:03.872> [2018.03.10 08:35:09]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:35:04.622> [2018.03.10 08:35:09]- [MODEM.STATUS]->Modem is Initializing (tmo:246) 11:35:04.887> [2018.03.10 08:35:10]- [MODEM.STATUS]->Modem is Initializing (tmo:245) 11:35:05.887> [2018.03.10 08:35:11]- [MODEM.STATUS]->Modem is Initializing (tmo:244) 11:35:06.887> [2018.03.10 08:35:12]- [MODEM.STATUS]->Modem is Initializing (tmo:243) 11:35:07.887> [2018.03.10 08:35:12]-[AppSend] No sending procedure in 100600495 sec, configured period: 600 sec 11:35:08.012> [2018.03.10 08:35:13]- [MODEM.STATUS]->Modem is Initializing (tmo:242) 11:35:08.903> [2018.03.10 08:35:14]- [MODEM.STATUS]->Modem is Initializing (tmo:241) 11:35:09.888> [2018.03.10 08:35:15]- [MODEM.STATUS]->Modem is Initializing (tmo:240) 11:35:10.888> [2018.03.10 08:35:16]- [MODEM.STATUS]->Modem is Initializing (tmo:239) 11:35:11.888> [2018.03.10 08:35:16]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:35:12.013> [2018.03.10 08:35:16]-[AppSend] Checking SMS Data Sending 11:35:12.013> [2018.03.10 08:35:16]-[AppSend] SMS Data Sending is Disabled 11:35:12.013> [2018.03.10 08:35:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:35:12.638> [2018.03.10 08:35:17]-[SCHED] lvcan request 11:35:12.638> [2018.03.10 08:35:17]- [MODEM.STATUS]->Modem is Initializing (tmo:238) 11:35:12.888> [2018.03.10 08:35:17]- [MODEM.STATUS]->Modem is Initializing (tmo:237) 11:35:12.950> [2018.03.10 08:35:18]-[DEBUG] 11:35:13.200> 11:35:13.200> UARTTxFullCnt: 0 11:35:13.200> SchTaskRunCnt: 10 11:35:13.200> UARTTskRunCnt: 1000 11:35:13.200> AppSaveRunCnt: 10 11:35:13.200> AppSendRunCnt: 10 11:35:13.200> ValidNMEAPkts: 80 11:35:13.200> RS232 procesed: 0 11:35:13.200> RS232 droped : 0 11:35:13.200> RS485 procesed: 0 11:35:13.200> RS485 droped : 0 11:35:13.200> 11:35:13.200> [2018.03.10 08:35:18]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:66[1800] ds18:0[1800] lvcan:0[1800] 11:35:13.825> [2018.03.10 08:35:18]- [MODEM.STATUS]->Modem is Initializing (tmo:236) 11:35:13.888> [2018.03.10 08:35:19]- [MODEM.STATUS]->Modem is Initializing (tmo:235) 11:35:14.888> [2018.03.10 08:35:20]- [MODEM.STATUS]->Modem is Initializing (tmo:234) 11:35:15.888> [2018.03.10 08:35:21]- [MODEM.STATUS]->Modem is Initializing (tmo:233) 11:35:16.888> [2018.03.10 08:35:22]- [MODEM.STATUS]->Modem is Initializing (tmo:232) 11:35:17.888> [2018.03.10 08:35:22]-[AppSend] No sending procedure in 100600505 sec, configured period: 600 sec 11:35:18.013> [2018.03.10 08:35:23]- [MODEM.STATUS]->Modem is Initializing (tmo:231) 11:35:18.888> [2018.03.10 08:35:24]- [MODEM.STATUS]->Modem is Initializing (tmo:230) 11:35:19.888> [2018.03.10 08:35:25]- [MODEM.STATUS]->Modem is Initializing (tmo:229) 11:35:20.888> [2018.03.10 08:35:26]- [MODEM.STATUS]->Modem is Initializing (tmo:228) 11:35:21.888> [2018.03.10 08:35:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:35:22.638> [2018.03.10 08:35:27]-[SCHED] lvcan request 11:35:22.638> [2018.03.10 08:35:27]- [MODEM.STATUS]->Modem is Initializing (tmo:227) 11:35:22.888> [2018.03.10 08:35:27]- [MODEM.STATUS]->Modem is Initializing (tmo:226) 11:35:22.951> [2018.03.10 08:35:28]- [MODEM.STATUS]->Modem is Initializing (tmo:225) 11:35:23.888> [2018.03.10 08:35:29]- [MODEM.STATUS]->Modem is Initializing (tmo:224) 11:35:24.888> [2018.03.10 08:35:30]- [MODEM.STATUS]->Modem is Initializing (tmo:223) 11:35:25.888> [2018.03.10 08:35:30]-[ModeChange] GPS selected as movement indicator 11:35:25.951> [2018.03.10 08:35:31]-[ModeChange] GPS selected as movement indicator 11:35:26.638> [2018.03.10 08:35:31]-[ModeChange] Nothing to change 11:35:26.638> [2018.03.10 08:35:31]- [MODEM.STATUS]->Modem is Initializing (tmo:222) 11:35:26.888> [2018.03.10 08:35:32]- [MODEM.STATUS]->Modem is Initializing (tmo:221) 11:35:27.888> [2018.03.10 08:35:32]-[AppSend] No sending procedure in 100600515 sec, configured period: 600 sec 11:35:28.013> [2018.03.10 08:35:33]- [MODEM.STATUS]->Modem is Initializing (tmo:220) 11:35:28.888> [2018.03.10 08:35:34]- [MODEM.STATUS]->Modem is Initializing (tmo:219) 11:35:29.889> [2018.03.10 08:35:35]- [MODEM.STATUS]->Modem is Initializing (tmo:218) 11:35:30.889> [2018.03.10 08:35:36]- [MODEM.STATUS]->Modem is Initializing (tmo:217) 11:35:31.889> [2018.03.10 08:35:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:35:32.639> [2018.03.10 08:35:37]-[SCHED] lvcan request 11:35:32.639> [2018.03.10 08:35:37]-[LLS.485] Address table reseted 11:35:32.639> [2018.03.10 08:35:37]-[LLS.485] Go to SCAN procedure 11:35:32.639> [2018.03.10 08:35:37]-[DeepSleep] SLEEP MODE DISABLED! 11:35:32.889> [2018.03.10 08:35:37]- [MODEM.STATUS]->Modem is Initializing (tmo:216) 11:35:32.889> [2018.03.10 08:35:37]- [MODEM.STATUS]->Modem is Initializing (tmo:215) 11:35:32.951> [2018.03.10 08:35:38]-[PERIODIC.GPS.INFO] 11:35:33.201> 11:35:33.201> Status : 3 (gps on) 11:35:33.201> Sat : 6 11:35:33.201> Lat : 54.558101 11:35:33.201> Long : 36.293159 11:35:33.201> Alt : 223 11:35:33.201> Speed : 0 11:35:33.201> Angle : 0 11:35:33.201> 11:35:33.201> GPS TMO : 900 11:35:33.201> GPS Fix : 1 11:35:33.201> NMEA Flag : 1 11:35:33.201> Time Sync : 1 11:35:33.201> Baudrate : 115200 11:35:33.201> Valid Pkts: 80 11:35:33.201> 11:35:33.201> GPS AP : 1 11:35:33.201> GPS_OFF : 1 11:35:33.201> RAM_GPS_ON: 1 11:35:33.201> SN Config : 0 11:35:33.201> SN State : 0 11:35:33.201> Log2Srv : 0 11:35:33.201> 11:35:33.201> [2018.03.10 08:35:38]- [MODEM.STATUS]->Modem is Initializing (tmo:214) 11:35:33.889> [2018.03.10 08:35:39]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:35:34.639> [2018.03.10 08:35:39]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:87[1800] ds18:0[1800] lvcan:0[1800] 11:35:34.826> [2018.03.10 08:35:39]- [MODEM.STATUS]->Modem is Initializing (tmo:213) 11:35:34.889> [2018.03.10 08:35:40]- [MODEM.STATUS]->Modem is Initializing (tmo:212) 11:35:35.889> [2018.03.10 08:35:40]-[AutoGeo] AutoGeofence is disabled 11:35:36.014> [2018.03.10 08:35:41]- [MODEM.STATUS]->Modem is Initializing (tmo:211) 11:35:36.889> [2018.03.10 08:35:42]- [MODEM.STATUS]->Modem is Initializing (tmo:210) 11:35:37.639> [2018.03.10 08:35:42]-[GPRS] Repeating task after delay 11:35:37.701> [2018.03.10 08:35:42]- [MODEM.STATUS]->Modem is Initializing (tmo:209) 11:35:37.889> [2018.03.10 08:35:42]-[AppSend] No sending procedure in 100600525 sec, configured period: 600 sec 11:35:38.014> [2018.03.10 08:35:43]- [MODEM.STATUS]->Modem is Initializing (tmo:208) 11:35:38.889> [2018.03.10 08:35:44]-[AppDatSav] Available 11:35:39.639> [2018.03.10 08:35:44]-[AppDatSav] 416 seconds before periodic record save 11:35:39.639> [2018.03.10 08:35:44]- [MODEM.STATUS]->Modem is Initializing (tmo:207) 11:35:39.889> [2018.03.10 08:35:45]- [MODEM.STATUS]->Modem is Initializing (tmo:206) 11:35:40.889> [2018.03.10 08:35:46]- [MODEM.STATUS]->Modem is Initializing (tmo:205) 11:35:41.889> [2018.03.10 08:35:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:35:42.639> [2018.03.10 08:35:47]-[SCHED] lvcan request 11:35:42.639> [2018.03.10 08:35:47]- [MODEM.STATUS]->Modem is Initializing (tmo:204) 11:35:42.889> [2018.03.10 08:35:47]- [MODEM.STATUS]->Modem is Initializing (tmo:203) 11:35:42.952> [2018.03.10 08:35:48]- [MODEM.STATUS]->Modem is Initializing (tmo:202) 11:35:43.889> [2018.03.10 08:35:49]- [MODEM.STATUS]->Modem is Initializing (tmo:201) 11:35:44.889> [2018.03.10 08:35:50]- [MODEM.STATUS]->Modem is Initializing (tmo:200) 11:35:45.889> [2018.03.10 08:35:51]- [MODEM.STATUS]->Modem is Initializing (tmo:199) 11:35:46.889> [2018.03.10 08:35:52]- [MODEM.STATUS]->Modem is Initializing (tmo:198) 11:35:47.889> [2018.03.10 08:35:52]-[AppSend] No sending procedure in 100600535 sec, configured period: 600 sec 11:35:48.014> [2018.03.10 08:35:53]- [MODEM.STATUS]->Modem is Initializing (tmo:197) 11:35:48.889> [2018.03.10 08:35:54]- [MODEM.STATUS]->Modem is Initializing (tmo:196) 11:35:49.890> [2018.03.10 08:35:55]- [MODEM.STATUS]->Modem is Initializing (tmo:195) 11:35:50.890> [2018.03.10 08:35:56]- [MODEM.STATUS]->Modem is Initializing (tmo:194) 11:35:51.890> [2018.03.10 08:35:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:35:52.640> [2018.03.10 08:35:57]-[SCHED] lvcan request 11:35:52.640> [2018.03.10 08:35:57]- [MODEM.STATUS]->Modem is Initializing (tmo:193) 11:35:52.890> [2018.03.10 08:35:57]- [MODEM.STATUS]->Modem is Initializing (tmo:192) 11:35:52.952> [2018.03.10 08:35:58]-[DEBUG] 11:35:53.202> 11:35:53.202> UARTTxFullCnt: 0 11:35:53.202> SchTaskRunCnt: 10 11:35:53.202> UARTTskRunCnt: 1000 11:35:53.202> AppSaveRunCnt: 10 11:35:53.202> AppSendRunCnt: 10 11:35:53.202> ValidNMEAPkts: 80 11:35:53.202> RS232 procesed: 0 11:35:53.202> RS232 droped : 0 11:35:53.202> RS485 procesed: 0 11:35:53.202> RS485 droped : 0 11:35:53.202> 11:35:53.202> [2018.03.10 08:35:58]- [MODEM.STATUS]->Modem is Initializing (tmo:191) 11:35:53.890> [2018.03.10 08:35:59]- [MODEM.STATUS]->Modem is Initializing (tmo:190) 11:35:54.890> [2018.03.10 08:36:00]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:108[1800] ds18:0[1800] lvcan:0[1800] 11:35:55.827> [2018.03.10 08:36:00]- [MODEM.STATUS]->Modem is Initializing (tmo:189) 11:35:55.890> [2018.03.10 08:36:01]-[InitModem] ERROR @ modem_init.c(177): Modem seems to be dead 11:35:56.077> [2018.03.10 08:36:01]- RX:1, TX:1, CTS:0, RTS:1, ON:0, PWR:1, RESET:1, DTR:0 11:35:56.140> [2018.03.10 08:36:01]-[InitModem] Cycle: 2 of 10 11:35:56.140> [2018.03.10 08:36:01]-[InitModem] Restarting modem... 11:35:56.140> [2018.03.10 08:36:01]- [MODEM.STATUS]->Modem is OFF. 11:35:56.890> [2018.03.10 08:36:02]- [MODEM.STATUS]->Modem is OFF. 11:35:57.890> [2018.03.10 08:36:02]-[AppSend] No sending procedure in 100600545 sec, configured period: 600 sec 11:35:58.015> [2018.03.10 08:36:03]- [MODEM.STATUS]->Modem is OFF. 11:35:58.890> [2018.03.10 08:36:04]- [MODEM.STATUS]->Modem is OFF. 11:35:59.890> [2018.03.10 08:36:05]- [MODEM.STATUS]->Modem is OFF. 11:36:00.890> [2018.03.10 08:36:06]- [MODEM.STATUS]->Modem is OFF. 11:36:01.890> [2018.03.10 08:36:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:36:02.640> [2018.03.10 08:36:07]-[SCHED] lvcan request 11:36:02.640> [2018.03.10 08:36:07]-[Scheduler] GC is scheduled after: 6750 sec 11:36:02.640> [2018.03.10 08:36:07]- [MODEM.STATUS]->Modem is OFF. 11:36:02.890> [2018.03.10 08:36:07]- [MODEM.STATUS]->Modem is OFF. 11:36:02.953> [2018.03.10 08:36:08]- [MODEM.STATUS]->Modem is OFF. 11:36:03.890> [2018.03.10 08:36:09]-[InitModem] Wait For Ready 11:36:04.140> [2018.03.10 08:36:09]- RX:0, TX:0, CTS:1, RTS:1, ON:0, PWR:1, RESET:1, DTR:0 11:36:04.140> [2018.03.10 08:36:09]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:36:04.640> [2018.03.10 08:36:09]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:36:04.890> [2018.03.10 08:36:10]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:36:05.890> [2018.03.10 08:36:11]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:36:06.890> [2018.03.10 08:36:12]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:36:07.890> [2018.03.10 08:36:12]-[AppSend] No sending procedure in 100600555 sec, configured period: 600 sec 11:36:08.015> [2018.03.10 08:36:13]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:36:08.890> [2018.03.10 08:36:14]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:36:09.890> [2018.03.10 08:36:15]- [MODEM.STATUS]->Modem is Initializing (tmo:294) 11:36:10.891> [2018.03.10 08:36:16]- [MODEM.STATUS]->Modem is Initializing (tmo:293) 11:36:11.891> [2018.03.10 08:36:16]-[ModeChange] GPS selected as movement indicator 11:36:11.953> [2018.03.10 08:36:16]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:36:12.016> [2018.03.10 08:36:16]-[AppSend] Checking SMS Data Sending 11:36:12.016> [2018.03.10 08:36:16]-[AppSend] SMS Data Sending is Disabled 11:36:12.016> [2018.03.10 08:36:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:36:12.641> [2018.03.10 08:36:17]-[ModeChange] GPS selected as movement indicator 11:36:12.641> [2018.03.10 08:36:17]-[SCHED] lvcan request 11:36:12.641> [2018.03.10 08:36:17]-[ModeChange] Nothing to change 11:36:12.641> [2018.03.10 08:36:17]- [MODEM.STATUS]->Modem is Initializing (tmo:292) 11:36:12.891> [2018.03.10 08:36:17]- [MODEM.STATUS]->Modem is Initializing (tmo:291) 11:36:12.953> [2018.03.10 08:36:18]- [MODEM.STATUS]->Modem is Initializing (tmo:290) 11:36:13.891> [2018.03.10 08:36:19]- [MODEM.STATUS]->Modem is Initializing (tmo:289) 11:36:14.891> [2018.03.10 08:36:20]- [MODEM.STATUS]->Modem is Initializing (tmo:288) 11:36:15.891> [2018.03.10 08:36:21]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:129[1800] ds18:0[1800] lvcan:0[1800] 11:36:16.828> [2018.03.10 08:36:21]- [MODEM.STATUS]->Modem is Initializing (tmo:287) 11:36:16.891> [2018.03.10 08:36:22]- [MODEM.STATUS]->Modem is Initializing (tmo:286) 11:36:17.891> [2018.03.10 08:36:22]-[AppSend] No sending procedure in 100600565 sec, configured period: 600 sec 11:36:18.016> [2018.03.10 08:36:23]- [MODEM.STATUS]->Modem is Initializing (tmo:285) 11:36:18.891> [2018.03.10 08:36:24]- [MODEM.STATUS]->Modem is Initializing (tmo:284) 11:36:19.891> [2018.03.10 08:36:25]- [MODEM.STATUS]->Modem is Initializing (tmo:283) 11:36:20.891> [2018.03.10 08:36:26]- [MODEM.STATUS]->Modem is Initializing (tmo:282) 11:36:21.891> [2018.03.10 08:36:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:36:22.641> [2018.03.10 08:36:27]-[SCHED] lvcan request 11:36:22.641> [2018.03.10 08:36:27]- [MODEM.STATUS]->Modem is Initializing (tmo:281) 11:36:22.891> [2018.03.10 08:36:27]- [MODEM.STATUS]->Modem is Initializing (tmo:280) 11:36:22.954> [2018.03.10 08:36:27]-[AutoGeo] AutoGeofence is disabled 11:36:23.016> [2018.03.10 08:36:28]- [MODEM.STATUS]->Modem is Initializing (tmo:279) 11:36:23.891> [2018.03.10 08:36:29]- [MODEM.STATUS]->Modem is Initializing (tmo:278) 11:36:24.891> [2018.03.10 08:36:30]- [MODEM.STATUS]->Modem is Initializing (tmo:277) 11:36:25.891> [2018.03.10 08:36:31]- [MODEM.STATUS]->Modem is Initializing (tmo:276) 11:36:26.891> [2018.03.10 08:36:32]-[Scenarios] Periodic info: 11:36:27.641> DOUT1 scenarios disabled 11:36:27.641> [2018.03.10 08:36:32]-[DOUT2.None] State: DISABLED 11:36:27.641> [2018.03.10 08:36:32]- [MODEM.STATUS]->Modem is Initializing (tmo:275) 11:36:27.891> [2018.03.10 08:36:32]-[AppSend] No sending procedure in 100600575 sec, configured period: 600 sec 11:36:28.016> [2018.03.10 08:36:33]- [MODEM.STATUS]->Modem is Initializing (tmo:274) 11:36:28.891> [2018.03.10 08:36:34]- [MODEM.STATUS]->Modem is Initializing (tmo:273) 11:36:29.891> [2018.03.10 08:36:35]- [MODEM.STATUS]->Modem is Initializing (tmo:272) 11:36:30.892> [2018.03.10 08:36:36]- [MODEM.STATUS]->Modem is Initializing (tmo:271) 11:36:31.892> [2018.03.10 08:36:37]-[StackCheck] StackInfo: 11:36:32.642> 11:36:32.642> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:36:32.642> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:36:32.642> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:36:32.642> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:36:32.642> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:36:32.642> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:36:32.642> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:36:32.642> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:36:32.642> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 368/1024 @ StackInfo 11:36:32.642> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:36:32.704> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 800/1024 @ StackInfo 11:36:32.704> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:36:32.704> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:36:32.704> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:36:32.704> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:36:32.704> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:36:32.704> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 264/ 904 @ StackInfo 11:36:32.704> 11:36:32.704> [2018.03.10 08:36:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:36:32.704> [2018.03.10 08:36:37]-[SCHED] lvcan request 11:36:32.767> [2018.03.10 08:36:37]-[LLS.485] Address table reseted 11:36:32.767> [2018.03.10 08:36:37]-[LLS.485] Go to SCAN procedure 11:36:32.767> [2018.03.10 08:36:37]-[DeepSleep] SLEEP MODE DISABLED! 11:36:32.892> [2018.03.10 08:36:37]- [MODEM.STATUS]->Modem is Initializing (tmo:270) 11:36:32.892> [2018.03.10 08:36:37]- [MODEM.STATUS]->Modem is Initializing (tmo:269) 11:36:32.954> [2018.03.10 08:36:38]-[DEBUG] 11:36:33.267> 11:36:33.267> UARTTxFullCnt: 0 11:36:33.267> SchTaskRunCnt: 10 11:36:33.267> UARTTskRunCnt: 1001 11:36:33.267> AppSaveRunCnt: 10 11:36:33.267> AppSendRunCnt: 10 11:36:33.267> ValidNMEAPkts: 80 11:36:33.267> RS232 procesed: 0 11:36:33.267> RS232 droped : 0 11:36:33.267> RS485 procesed: 0 11:36:33.267> RS485 droped : 0 11:36:33.267> 11:36:33.267> [2018.03.10 08:36:38]-[PowerMan] State:NORMAL ExtPG:1 BatPG:0 Ext:12352 mV; BatV:0 mV; BatI:0 mA; GPS:3286 mV; Modem:3877 mV; 11:36:33.267> [2018.03.10 08:36:38]- [MODEM.STATUS]->Modem is Initializing (tmo:268) 11:36:33.892> [2018.03.10 08:36:39]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:36:34.642> [2018.03.10 08:36:39]- [MODEM.STATUS]->Modem is Initializing (tmo:267) 11:36:34.892> [2018.03.10 08:36:40]- [MODEM.STATUS]->Modem is Initializing (tmo:266) 11:36:35.892> [2018.03.10 08:36:41]- [MODEM.STATUS]->Modem is Initializing (tmo:265) 11:36:36.892> [2018.03.10 08:36:42]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:150[1800] ds18:0[1800] lvcan:0[1800] 11:36:37.829> [2018.03.10 08:36:42]- [MODEM.STATUS]->Modem is Initializing (tmo:264) 11:36:37.892> [2018.03.10 08:36:42]-[AppSend] No sending procedure in 100600585 sec, configured period: 600 sec 11:36:38.017> [2018.03.10 08:36:43]- [MODEM.STATUS]->Modem is Initializing (tmo:263) 11:36:38.204> [2018.03.10 08:36:43]-[GPRS] Repeating task after delay 11:36:38.329> [2018.03.10 08:36:43]- [MODEM.STATUS]->Modem is Initializing (tmo:262) 11:36:38.892> [2018.03.10 08:36:44]- [MODEM.STATUS]->Modem is Initializing (tmo:261) 11:36:39.892> [2018.03.10 08:36:45]-[AppDatSav] Available 11:36:40.642> [2018.03.10 08:36:45]-[AppDatSav] 355 seconds before periodic record save 11:36:40.642> [2018.03.10 08:36:45]- [MODEM.STATUS]->Modem is Initializing (tmo:260) 11:36:40.892> [2018.03.10 08:36:46]- [MODEM.STATUS]->Modem is Initializing (tmo:259) 11:36:41.892> [2018.03.10 08:36:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:36:42.642> [2018.03.10 08:36:47]-[SCHED] lvcan request 11:36:42.642> [2018.03.10 08:36:47]- [MODEM.STATUS]->Modem is Initializing (tmo:258) 11:36:42.892> [2018.03.10 08:36:47]- [MODEM.STATUS]->Modem is Initializing (tmo:257) 11:36:42.955> [2018.03.10 08:36:48]- [MODEM.STATUS]->Modem is Initializing (tmo:256) 11:36:43.892> [2018.03.10 08:36:49]- [MODEM.STATUS]->Modem is Initializing (tmo:255) 11:36:44.892> [2018.03.10 08:36:50]- [MODEM.STATUS]->Modem is Initializing (tmo:254) 11:36:45.892> [2018.03.10 08:36:51]- [MODEM.STATUS]->Modem is Initializing (tmo:253) 11:36:46.892> [2018.03.10 08:36:52]- [MODEM.STATUS]->Modem is Initializing (tmo:252) 11:36:47.892> [2018.03.10 08:36:52]-[AppSend] No sending procedure in 100600595 sec, configured period: 600 sec 11:36:48.017> [2018.03.10 08:36:53]- [MODEM.STATUS]->Modem is Initializing (tmo:251) 11:36:48.892> [2018.03.10 08:36:54]- [MODEM.STATUS]->Modem is Initializing (tmo:250) 11:36:49.892> [2018.03.10 08:36:55]- [MODEM.STATUS]->Modem is Initializing (tmo:249) 11:36:50.892> [2018.03.10 08:36:56]- [MODEM.STATUS]->Modem is Initializing (tmo:248) 11:36:51.893> [2018.03.10 08:36:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:36:52.643> [2018.03.10 08:36:57]-[SCHED] lvcan request 11:36:52.643> [2018.03.10 08:36:57]- [MODEM.STATUS]->Modem is Initializing (tmo:247) 11:36:52.893> [2018.03.10 08:36:57]- [MODEM.STATUS]->Modem is Initializing (tmo:246) 11:36:52.955> [2018.03.10 08:36:58]- [MODEM.STATUS]->Modem is Initializing (tmo:245) 11:36:53.893> [2018.03.10 08:36:59]- [MODEM.STATUS]->Modem is Initializing (tmo:244) 11:36:54.893> [2018.03.10 08:37:00]- [MODEM.STATUS]->Modem is Initializing (tmo:243) 11:36:55.893> [2018.03.10 08:37:01]- [MODEM.STATUS]->Modem is Initializing (tmo:242) 11:36:56.893> [2018.03.10 08:37:02]- [MODEM.STATUS]->Modem is Initializing (tmo:241) 11:36:57.893> [2018.03.10 08:37:02]-[ModeChange] GPS selected as movement indicator 11:36:57.893> [2018.03.10 08:37:02]-[AppSend] No sending procedure in 100600605 sec, configured period: 600 sec 11:36:58.018> [2018.03.10 08:37:03]-[ModeChange] GPS selected as movement indicator 11:36:58.643> [2018.03.10 08:37:03]-[ModeChange] Nothing to change 11:36:58.643> [2018.03.10 08:37:03]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:171[1800] ds18:0[1800] lvcan:0[1800] 11:36:58.830> [2018.03.10 08:37:03]- [MODEM.STATUS]->Modem is Initializing (tmo:240) 11:36:58.893> [2018.03.10 08:37:04]- [MODEM.STATUS]->Modem is Initializing (tmo:239) 11:36:59.893> [2018.03.10 08:37:05]- [MODEM.STATUS]->Modem is Initializing (tmo:238) 11:37:00.893> [2018.03.10 08:37:06]- [MODEM.STATUS]->Modem is Initializing (tmo:237) 11:37:01.893> [2018.03.10 08:37:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:37:02.643> [2018.03.10 08:37:07]-[SCHED] lvcan request 11:37:02.643> [2018.03.10 08:37:07]- [MODEM.STATUS]->Modem is Initializing (tmo:236) 11:37:02.893> [2018.03.10 08:37:07]- [MODEM.STATUS]->Modem is Initializing (tmo:235) 11:37:02.956> [2018.03.10 08:37:08]- [MODEM.STATUS]->Modem is Initializing (tmo:234) 11:37:03.893> [2018.03.10 08:37:09]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:37:04.643> [2018.03.10 08:37:09]- [MODEM.STATUS]->Modem is Initializing (tmo:233) 11:37:04.893> [2018.03.10 08:37:10]- [MODEM.STATUS]->Modem is Initializing (tmo:232) 11:37:05.893> [2018.03.10 08:37:11]- [MODEM.STATUS]->Modem is Initializing (tmo:231) 11:37:06.893> [2018.03.10 08:37:12]- [MODEM.STATUS]->Modem is Initializing (tmo:230) 11:37:07.893> [2018.03.10 08:37:12]-[AppSend] No sending procedure in 100600615 sec, configured period: 600 sec 11:37:08.018> [2018.03.10 08:37:13]- [MODEM.STATUS]->Modem is Initializing (tmo:229) 11:37:08.893> [2018.03.10 08:37:14]- [MODEM.STATUS]->Modem is Initializing (tmo:228) 11:37:09.893> [2018.03.10 08:37:14]-[AutoGeo] AutoGeofence is disabled 11:37:10.018> [2018.03.10 08:37:15]- [MODEM.STATUS]->Modem is Initializing (tmo:227) 11:37:10.893> [2018.03.10 08:37:16]- [MODEM.STATUS]->Modem is Initializing (tmo:226) 11:37:11.894> [2018.03.10 08:37:16]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:37:12.019> [2018.03.10 08:37:16]-[AppSend] Checking SMS Data Sending 11:37:12.019> [2018.03.10 08:37:16]-[AppSend] SMS Data Sending is Disabled 11:37:12.019> [2018.03.10 08:37:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:37:12.644> [2018.03.10 08:37:17]-[SCHED] lvcan request 11:37:12.644> [2018.03.10 08:37:17]- [MODEM.STATUS]->Modem is Initializing (tmo:225) 11:37:12.894> [2018.03.10 08:37:17]- [MODEM.STATUS]->Modem is Initializing (tmo:224) 11:37:12.956> [2018.03.10 08:37:18]-[DEBUG] 11:37:13.269> 11:37:13.269> UARTTxFullCnt: 0 11:37:13.269> SchTaskRunCnt: 10 11:37:13.269> UARTTskRunCnt: 1000 11:37:13.269> AppSaveRunCnt: 10 11:37:13.269> AppSendRunCnt: 10 11:37:13.269> ValidNMEAPkts: 80 11:37:13.269> RS232 procesed: 0 11:37:13.269> RS232 droped : 0 11:37:13.269> RS485 procesed: 0 11:37:13.269> RS485 droped : 0 11:37:13.269> 11:37:13.269> [2018.03.10 08:37:18]- [MODEM.STATUS]->Modem is Initializing (tmo:223) 11:37:13.894> [2018.03.10 08:37:18]-[AppSend] No sending procedure in 100600621 sec, configured period: 600 sec 11:37:14.019> [2018.03.10 08:37:19]- [MODEM.STATUS]->Modem is Initializing (tmo:222) 11:37:14.894> [2018.03.10 08:37:20]- [MODEM.STATUS]->Modem is Initializing (tmo:221) 11:37:15.894> [2018.03.10 08:37:21]- [MODEM.STATUS]->Modem is Initializing (tmo:220) 11:37:16.894> [2018.03.10 08:37:22]- [MODEM.STATUS]->Modem is Initializing (tmo:219) 11:37:17.894> [2018.03.10 08:37:23]- [MODEM.STATUS]->Modem is Initializing (tmo:218) 11:37:18.894> [2018.03.10 08:37:24]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:192[1800] ds18:0[1800] lvcan:0[1800] 11:37:19.831> [2018.03.10 08:37:24]- [MODEM.STATUS]->Modem is Initializing (tmo:217) 11:37:19.894> [2018.03.10 08:37:25]- [MODEM.STATUS]->Modem is Initializing (tmo:216) 11:37:20.894> [2018.03.10 08:37:26]- [MODEM.STATUS]->Modem is Initializing (tmo:215) 11:37:21.894> [2018.03.10 08:37:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:37:22.644> [2018.03.10 08:37:27]-[SCHED] lvcan request 11:37:22.644> [2018.03.10 08:37:27]- [MODEM.STATUS]->Modem is Initializing (tmo:214) 11:37:22.894> [2018.03.10 08:37:27]- [MODEM.STATUS]->Modem is Initializing (tmo:213) 11:37:22.957> [2018.03.10 08:37:28]- [MODEM.STATUS]->Modem is Initializing (tmo:212) 11:37:23.894> [2018.03.10 08:37:28]-[AppSend] No sending procedure in 100600631 sec, configured period: 600 sec 11:37:24.019> [2018.03.10 08:37:29]- [MODEM.STATUS]->Modem is Initializing (tmo:211) 11:37:24.894> [2018.03.10 08:37:30]- [MODEM.STATUS]->Modem is Initializing (tmo:210) 11:37:25.894> [2018.03.10 08:37:31]- [MODEM.STATUS]->Modem is Initializing (tmo:209) 11:37:26.894> [2018.03.10 08:37:32]- [MODEM.STATUS]->Modem is Initializing (tmo:208) 11:37:27.894> [2018.03.10 08:37:33]- [MODEM.STATUS]->Modem is Initializing (tmo:207) 11:37:28.894> [2018.03.10 08:37:34]- [MODEM.STATUS]->Modem is Initializing (tmo:206) 11:37:29.894> [2018.03.10 08:37:35]- [MODEM.STATUS]->Modem is Initializing (tmo:205) 11:37:30.894> [2018.03.10 08:37:36]- [MODEM.STATUS]->Modem is Initializing (tmo:204) 11:37:31.894> [2018.03.10 08:37:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:37:32.645> [2018.03.10 08:37:37]-[SCHED] lvcan request 11:37:32.645> [2018.03.10 08:37:37]-[LLS.485] Address table reseted 11:37:32.645> [2018.03.10 08:37:37]-[LLS.485] Go to SCAN procedure 11:37:32.645> [2018.03.10 08:37:37]-[DeepSleep] SLEEP MODE DISABLED! 11:37:32.895> [2018.03.10 08:37:37]- [MODEM.STATUS]->Modem is Initializing (tmo:203) 11:37:32.895> [2018.03.10 08:37:37]- [MODEM.STATUS]->Modem is Initializing (tmo:202) 11:37:32.957> [2018.03.10 08:37:38]-[PERIODIC.NMEA.INFO] 11:37:33.270> 11:37:33.270> Total Pkts : 3819 11:37:33.270> Bad Chksum : 0 11:37:33.270> No Chksum : 0 11:37:33.270> 11:37:33.270> HDOP Cycles: 303 11:37:33.270> HDOP Limit : 4.0 11:37:33.270> HDOP Actual: 2.4 11:37:33.270> HDOP Mean : 2.5 11:37:33.270> HDOP Min : 2.4 11:37:33.270> HDOP Max : 3.1 11:37:33.270> PDOP Actual: 4.3 11:37:33.270> HDOP Rejct : 0 11:37:33.270> HDOP Taken : 60 11:37:33.270> HDOP Timer : 60 11:37:33.270> HDOP Ready : 0 11:37:33.270> 11:37:33.270> Total sats GPS : 10 Tracked: 16 5 9 7 27 30 11:37:33.332> Total sats GLONASS: 0 Tracked: 11:37:33.332> 11:37:33.332> Bad Time: 0 11:37:33.332> Bad Lat : 0 11:37:33.332> Bad Lon : 0 11:37:33.332> Bad Spd : 0 11:37:33.332> Bad Angl: 0 11:37:33.332> Bad HDOP: 0 11:37:33.332> 11:37:33.332> GPS jmps: 0 11:37:33.332> Filtered: 541 11:37:33.332> Rejected: 0 11:37:33.332> 11:37:33.332> [2018.03.10 08:37:38]- [MODEM.STATUS]->Modem is Initializing (tmo:201) 11:37:33.895> [2018.03.10 08:37:38]-[AppSend] No sending procedure in 100600641 sec, configured period: 600 sec 11:37:34.020> [2018.03.10 08:37:39]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:37:34.645> [2018.03.10 08:37:39]- [MODEM.STATUS]->Modem is Initializing (tmo:200) 11:37:34.895> [2018.03.10 08:37:40]- [MODEM.STATUS]->Modem is Initializing (tmo:199) 11:37:35.895> [2018.03.10 08:37:41]- [MODEM.STATUS]->Modem is Initializing (tmo:198) 11:37:36.895> [2018.03.10 08:37:42]- [MODEM.STATUS]->Modem is Initializing (tmo:197) 11:37:37.895> [2018.03.10 08:37:43]- [MODEM.STATUS]->Modem is Initializing (tmo:196) 11:37:38.848> [2018.03.10 08:37:43]- [MODEM.STATUS]->Modem is Initializing (tmo:195) 11:37:38.895> [2018.03.10 08:37:43]-[GPRS] Repeating task after delay 11:37:38.957> [2018.03.10 08:37:44]- [MODEM.STATUS]->Modem is Initializing (tmo:194) 11:37:39.895> [2018.03.10 08:37:45]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:213[1800] ds18:0[1800] lvcan:0[1800] 11:37:40.832> [2018.03.10 08:37:45]- [MODEM.STATUS]->Modem is Initializing (tmo:193) 11:37:40.895> [2018.03.10 08:37:46]-[AppDatSav] Available 11:37:41.645> [2018.03.10 08:37:46]-[AppDatSav] 294 seconds before periodic record save 11:37:41.645> [2018.03.10 08:37:46]- [MODEM.STATUS]->Modem is Initializing (tmo:192) 11:37:41.895> [2018.03.10 08:37:47]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:37:42.645> [2018.03.10 08:37:47]-[SCHED] lvcan request 11:37:42.645> [2018.03.10 08:37:47]- [MODEM.STATUS]->Modem is Initializing (tmo:191) 11:37:42.895> [2018.03.10 08:37:47]- [MODEM.STATUS]->Modem is Initializing (tmo:190) 11:37:42.958> [2018.03.10 08:37:48]- [MODEM.STATUS]->Modem is Initializing (tmo:189) 11:37:43.895> [2018.03.10 08:37:48]-[ModeChange] GPS selected as movement indicator 11:37:43.895> [2018.03.10 08:37:48]-[AppSend] No sending procedure in 100600651 sec, configured period: 600 sec 11:37:44.020> [2018.03.10 08:37:49]-[ModeChange] GPS selected as movement indicator 11:37:44.645> [2018.03.10 08:37:49]-[ModeChange] Nothing to change 11:37:44.645> [2018.03.10 08:37:49]- [MODEM.STATUS]->Modem is Initializing (tmo:188) 11:37:44.895> [2018.03.10 08:37:50]- [MODEM.STATUS]->Modem is Initializing (tmo:187) 11:37:45.895> [2018.03.10 08:37:51]- [MODEM.STATUS]->Modem is Initializing (tmo:186) 11:37:46.895> [2018.03.10 08:37:52]- [MODEM.STATUS]->Modem is Initializing (tmo:185) 11:37:47.895> [2018.03.10 08:37:53]- [MODEM.STATUS]->Modem is Initializing (tmo:184) 11:37:48.895> [2018.03.10 08:37:54]- [MODEM.STATUS]->Modem is Initializing (tmo:183) 11:37:49.895> [2018.03.10 08:37:55]- [MODEM.STATUS]->Modem is Initializing (tmo:182) 11:37:50.895> [2018.03.10 08:37:56]- [MODEM.STATUS]->Modem is Initializing (tmo:181) 11:37:51.895> [2018.03.10 08:37:57]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:37:52.645> [2018.03.10 08:37:57]-[SCHED] lvcan request 11:37:52.645> [2018.03.10 08:37:57]- [MODEM.STATUS]->Modem is Initializing (tmo:180) 11:37:52.896> [2018.03.10 08:37:57]- [MODEM.STATUS]->Modem is Initializing (tmo:179) 11:37:52.958> [2018.03.10 08:37:58]-[DEBUG] 11:37:53.333> 11:37:53.333> UARTTxFullCnt: 0 11:37:53.333> SchTaskRunCnt: 10 11:37:53.333> UARTTskRunCnt: 1000 11:37:53.333> AppSaveRunCnt: 10 11:37:53.333> AppSendRunCnt: 10 11:37:53.333> ValidNMEAPkts: 80 11:37:53.333> RS232 procesed: 0 11:37:53.333> RS232 droped : 0 11:37:53.333> RS485 procesed: 0 11:37:53.333> RS485 droped : 0 11:37:53.333> 11:37:53.333> [2018.03.10 08:37:58]- [MODEM.STATUS]->Modem is Initializing (tmo:178) 11:37:53.896> [2018.03.10 08:37:58]-[AppSend] No sending procedure in 100600661 sec, configured period: 600 sec 11:37:54.021> [2018.03.10 08:37:59]- [MODEM.STATUS]->Modem is Initializing (tmo:177) 11:37:54.896> [2018.03.10 08:38:00]- [MODEM.STATUS]->Modem is Initializing (tmo:176) 11:37:55.896> [2018.03.10 08:38:01]- [MODEM.STATUS]->Modem is Initializing (tmo:175) 11:37:56.896> [2018.03.10 08:38:01]-[AutoGeo] AutoGeofence is disabled 11:37:57.021> [2018.03.10 08:38:02]- [MODEM.STATUS]->Modem is Initializing (tmo:174) 11:37:57.896> [2018.03.10 08:38:03]- [MODEM.STATUS]->Modem is Initializing (tmo:173) 11:37:58.896> [2018.03.10 08:38:04]- [MODEM.STATUS]->Modem is Initializing (tmo:172) 11:37:59.896> [2018.03.10 08:38:05]- [MODEM.STATUS]->Modem is Initializing (tmo:171) 11:38:00.896> [2018.03.10 08:38:06]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:234[1800] ds18:0[1800] lvcan:0[1800] 11:38:01.833> [2018.03.10 08:38:06]- [MODEM.STATUS]->Modem is Initializing (tmo:170) 11:38:01.896> [2018.03.10 08:38:07]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:38:02.646> [2018.03.10 08:38:07]-[Scenarios] Periodic info: 11:38:02.646> DOUT1 scenarios disabled 11:38:02.646> [2018.03.10 08:38:07]-[DOUT2.None] State: DISABLED 11:38:02.646> [2018.03.10 08:38:07]-[SCHED] lvcan request 11:38:02.646> [2018.03.10 08:38:07]- [MODEM.STATUS]->Modem is Initializing (tmo:169) 11:38:02.896> [2018.03.10 08:38:07]- [MODEM.STATUS]->Modem is Initializing (tmo:168) 11:38:02.958> [2018.03.10 08:38:08]- [MODEM.STATUS]->Modem is Initializing (tmo:167) 11:38:03.896> [2018.03.10 08:38:08]-[AppSend] No sending procedure in 100600671 sec, configured period: 600 sec 11:38:04.021> [2018.03.10 08:38:09]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:38:04.646> [2018.03.10 08:38:09]- [MODEM.STATUS]->Modem is Initializing (tmo:166) 11:38:04.896> [2018.03.10 08:38:10]- [MODEM.STATUS]->Modem is Initializing (tmo:165) 11:38:05.896> [2018.03.10 08:38:11]- [MODEM.STATUS]->Modem is Initializing (tmo:164) 11:38:06.896> [2018.03.10 08:38:12]- [MODEM.STATUS]->Modem is Initializing (tmo:163) 11:38:07.896> [2018.03.10 08:38:13]- [MODEM.STATUS]->Modem is Initializing (tmo:162) 11:38:08.896> [2018.03.10 08:38:14]- [MODEM.STATUS]->Modem is Initializing (tmo:161) 11:38:09.896> [2018.03.10 08:38:15]- [MODEM.STATUS]->Modem is Initializing (tmo:160) 11:38:10.896> [2018.03.10 08:38:16]- [MODEM.STATUS]->Modem is Initializing (tmo:159) 11:38:11.896> [2018.03.10 08:38:16]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:38:12.021> [2018.03.10 08:38:16]-[AppSend] Checking SMS Data Sending 11:38:12.021> [2018.03.10 08:38:16]-[AppSend] SMS Data Sending is Disabled 11:38:12.084> [2018.03.10 08:38:17]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:38:12.646> [2018.03.10 08:38:17]-[SCHED] lvcan request 11:38:12.646> [2018.03.10 08:38:17]- [MODEM.STATUS]->Modem is Initializing (tmo:158) 11:38:12.896> [2018.03.10 08:38:17]- [MODEM.STATUS]->Modem is Initializing (tmo:157) 11:38:12.959> [2018.03.10 08:38:18]- [MODEM.STATUS]->Modem is Initializing (tmo:156) 11:38:13.897> [2018.03.10 08:38:18]-[AppSend] No sending procedure in 100600681 sec, configured period: 600 sec 11:38:14.084> [2018.03.10 08:38:19]- [MODEM.STATUS]->Modem is Initializing (tmo:155) 11:38:14.897> [2018.03.10 08:38:20]- [MODEM.STATUS]->Modem is Initializing (tmo:154) 11:38:15.897> [2018.03.10 08:38:21]- [MODEM.STATUS]->Modem is Initializing (tmo:153) 11:38:16.912> [2018.03.10 08:38:22]- [MODEM.STATUS]->Modem is Initializing (tmo:152) 11:38:17.897> [2018.03.10 08:38:23]- [MODEM.STATUS]->Modem is Initializing (tmo:151) 11:38:18.897> [2018.03.10 08:38:24]- [MODEM.STATUS]->Modem is Initializing (tmo:150) 11:38:19.897> [2018.03.10 08:38:25]- [MODEM.STATUS]->Modem is Initializing (tmo:149) 11:38:20.897> [2018.03.10 08:38:26]- [MODEM.STATUS]->Modem is Initializing (tmo:148) 11:38:21.897> [2018.03.10 08:38:27]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:38:22.647> [2018.03.10 08:38:27]-[SCHED] lvcan request 11:38:22.647> [2018.03.10 08:38:27]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:255[1800] ds18:0[1800] lvcan:0[1800] 11:38:22.959> [2018.03.10 08:38:27]- [MODEM.STATUS]->Modem is Initializing (tmo:147) 11:38:22.959> [2018.03.10 08:38:27]- [MODEM.STATUS]->Modem is Initializing (tmo:146) 11:38:22.959> [2018.03.10 08:38:28]- [MODEM.STATUS]->Modem is Initializing (tmo:145) 11:38:23.897> [2018.03.10 08:38:28]-[AppSend] No sending procedure in 100600691 sec, configured period: 600 sec 11:38:24.085> [2018.03.10 08:38:29]- [MODEM.STATUS]->Modem is Initializing (tmo:144) 11:38:24.897> [2018.03.10 08:38:30]- [MODEM.STATUS]->Modem is Initializing (tmo:143) 11:38:25.897> [2018.03.10 08:38:31]- [MODEM.STATUS]->Modem is Initializing (tmo:142) 11:38:26.897> [2018.03.10 08:38:32]- [MODEM.STATUS]->Modem is Initializing (tmo:141) 11:38:27.897> [2018.03.10 08:38:33]- [MODEM.STATUS]->Modem is Initializing (tmo:140) 11:38:28.897> [2018.03.10 08:38:34]- [MODEM.STATUS]->Modem is Initializing (tmo:139) 11:38:29.897> [2018.03.10 08:38:34]-[ModeChange] GPS selected as movement indicator 11:38:29.897> [2018.03.10 08:38:35]-[ModeChange] GPS selected as movement indicator 11:38:30.647> [2018.03.10 08:38:35]-[ModeChange] Nothing to change 11:38:30.647> [2018.03.10 08:38:35]- [MODEM.STATUS]->Modem is Initializing (tmo:138) 11:38:30.897> [2018.03.10 08:38:36]- [MODEM.STATUS]->Modem is Initializing (tmo:137) 11:38:31.897> [2018.03.10 08:38:37]-[StackCheck] StackInfo: 11:38:32.647> 11:38:32.647> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:38:32.647> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:38:32.647> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:38:32.647> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:38:32.647> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:38:32.647> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:38:32.647> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:38:32.647> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:38:32.647> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 368/1024 @ StackInfo 11:38:32.647> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:38:32.897> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 800/1024 @ StackInfo 11:38:32.897> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:38:32.897> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:38:32.897> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:38:32.897> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:38:32.897> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:38:32.897> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 264/ 904 @ StackInfo 11:38:32.897> 11:38:32.897> [2018.03.10 08:38:37]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:38:32.897> [2018.03.10 08:38:37]-[SCHED] lvcan request 11:38:32.897> [2018.03.10 08:38:37]-[Scheduler] GC is scheduled after: 6600 sec 11:38:32.897> [2018.03.10 08:38:37]-[LLS.485] Address table reseted 11:38:32.897> [2018.03.10 08:38:37]-[LLS.485] Go to SCAN procedure 11:38:32.897> [2018.03.10 08:38:37]-[DeepSleep] SLEEP MODE DISABLED! 11:38:32.897> [2018.03.10 08:38:37]- [MODEM.STATUS]->Modem is Initializing (tmo:136) 11:38:32.897> [2018.03.10 08:38:37]- [MODEM.STATUS]->Modem is Initializing (tmo:135) 11:38:33.397> [2018.03.10 08:38:38]-[DEBUG] 11:38:33.397> 11:38:33.397> UARTTxFullCnt: 0 11:38:33.397> SchTaskRunCnt: 10 11:38:33.397> UARTTskRunCnt: 1000 11:38:33.397> AppSaveRunCnt: 10 11:38:33.397> AppSendRunCnt: 10 11:38:33.397> ValidNMEAPkts: 80 11:38:33.397> RS232 procesed: 0 11:38:33.397> RS232 droped : 0 11:38:33.397> RS485 procesed: 0 11:38:33.397> RS485 droped : 0 11:38:33.397> 11:38:33.397> [2018.03.10 08:38:38]-[PERIODIC.BATRAM.INFO] 11:38:33.397> 11:38:33.397> BootSign : 0x0000 11:38:33.397> BootSign Inv: 0x0000 11:38:33.397> 11:38:33.397> Date : 2018.03.10 11:38:33.397> TimeSync : 1 11:38:33.397> Uptime : 608 11:38:33.397> 11:38:33.397> Restart Cnt : 0 11:38:33.397> WDG Restarts: 0 11:38:33.397> Bad Records : 7 11:38:33.397> AuthState : 0 11:38:33.397> GC Timer : 600 11:38:33.397> 11:38:33.397> [2018.03.10 08:38:38]- [MODEM.STATUS]->Modem is Initializing (tmo:134) 11:38:34.163> [2018.03.10 08:38:38]-[AppSend] No sending procedure in 100600701 sec, configured period: 600 sec 11:38:34.163> [2018.03.10 08:38:39]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:38:34.648> [2018.03.10 08:38:39]- [MODEM.STATUS]->Modem is Initializing (tmo:133) 11:38:34.898> [2018.03.10 08:38:40]- [MODEM.STATUS]->Modem is Initializing (tmo:132) 11:38:35.898> [2018.03.10 08:38:41]- [MODEM.STATUS]->Modem is Initializing (tmo:131) 11:38:36.898> [2018.03.10 08:38:42]- [MODEM.STATUS]->Modem is Initializing (tmo:130) 11:38:37.898> [2018.03.10 08:38:39]-[TIME] RTC SYNC (RTC: 1520671122, GNSS: 1520671119, Delta: 0.560889) 11:38:37.898> [2018.03.10 08:38:40]- [MODEM.STATUS]->Modem is Initializing (tmo:129) 11:38:38.898> [2018.03.10 08:38:41]- [MODEM.STATUS]->Modem is Initializing (tmo:128) 11:38:39.460> [2018.03.10 08:38:41]-[GPRS] Repeating task after delay 11:38:39.523> [2018.03.10 08:38:41]- [MODEM.STATUS]->Modem is Initializing (tmo:127) 11:38:39.898> [2018.03.10 08:38:42]- [MODEM.STATUS]->Modem is Initializing (tmo:126) 11:38:40.898> [2018.03.10 08:38:43]- [MODEM.STATUS]->Modem is Initializing (tmo:125) 11:38:41.898> [2018.03.10 08:38:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:38:42.648> [2018.03.10 08:38:44]-[AppDatSav] Available 11:38:42.648> [2018.03.10 08:38:44]-[SCHED] lvcan request 11:38:42.648> [2018.03.10 08:38:44]-[AppDatSav] 236 seconds before periodic record save 11:38:42.648> [2018.03.10 08:38:44]- [MODEM.STATUS]->Modem is Initializing (tmo:124) 11:38:42.898> [2018.03.10 08:38:44]- [MODEM.STATUS]->Modem is Initializing (tmo:123) 11:38:42.960> [2018.03.10 08:38:45]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:276[1800] ds18:0[1800] lvcan:0[1800] 11:38:43.835> [2018.03.10 08:38:45]- [MODEM.STATUS]->Modem is Initializing (tmo:122) 11:38:43.898> [2018.03.10 08:38:45]-[AutoGeo] AutoGeofence is disabled 11:38:44.023> [2018.03.10 08:38:45]-[AppSend] No sending procedure in 100600708 sec, configured period: 600 sec 11:38:44.085> [2018.03.10 08:38:46]- [MODEM.STATUS]->Modem is Initializing (tmo:121) 11:38:44.898> [2018.03.10 08:38:47]- [MODEM.STATUS]->Modem is Initializing (tmo:120) 11:38:45.898> [2018.03.10 08:38:48]- [MODEM.STATUS]->Modem is Initializing (tmo:119) 11:38:46.898> [2018.03.10 08:38:49]- [MODEM.STATUS]->Modem is Initializing (tmo:118) 11:38:47.898> [2018.03.10 08:38:50]- [MODEM.STATUS]->Modem is Initializing (tmo:117) 11:38:48.898> [2018.03.10 08:38:51]- [MODEM.STATUS]->Modem is Initializing (tmo:116) 11:38:49.898> [2018.03.10 08:38:52]- [MODEM.STATUS]->Modem is Initializing (tmo:115) 11:38:50.898> [2018.03.10 08:38:53]- [MODEM.STATUS]->Modem is Initializing (tmo:114) 11:38:51.898> [2018.03.10 08:38:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:38:52.648> [2018.03.10 08:38:54]-[SCHED] lvcan request 11:38:52.648> [2018.03.10 08:38:54]- [MODEM.STATUS]->Modem is Initializing (tmo:113) 11:38:52.898> [2018.03.10 08:38:54]- [MODEM.STATUS]->Modem is Initializing (tmo:112) 11:38:52.961> [2018.03.10 08:38:55]- [MODEM.STATUS]->Modem is Initializing (tmo:111) 11:38:53.898> [2018.03.10 08:38:55]-[AppSend] No sending procedure in 100600718 sec, configured period: 600 sec 11:38:54.086> [2018.03.10 08:38:56]- [MODEM.STATUS]->Modem is Initializing (tmo:110) 11:38:54.899> [2018.03.10 08:38:57]- [MODEM.STATUS]->Modem is Initializing (tmo:109) 11:38:55.899> [2018.03.10 08:38:58]- [MODEM.STATUS]->Modem is Initializing (tmo:108) 11:38:56.899> [2018.03.10 08:38:59]- [MODEM.STATUS]->Modem is Initializing (tmo:107) 11:38:57.899> [2018.03.10 08:39:00]- [MODEM.STATUS]->Modem is Initializing (tmo:106) 11:38:58.899> [2018.03.10 08:39:01]- [MODEM.STATUS]->Modem is Initializing (tmo:105) 11:38:59.899> [2018.03.10 08:39:02]- [MODEM.STATUS]->Modem is Initializing (tmo:104) 11:39:00.899> [2018.03.10 08:39:03]- [MODEM.STATUS]->Modem is Initializing (tmo:103) 11:39:01.899> [2018.03.10 08:39:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:39:02.649> [2018.03.10 08:39:04]-[SCHED] lvcan request 11:39:02.649> [2018.03.10 08:39:04]- [MODEM.STATUS]->Modem is Initializing (tmo:102) 11:39:02.899> [2018.03.10 08:39:04]- [MODEM.STATUS]->Modem is Initializing (tmo:101) 11:39:02.961> [2018.03.10 08:39:05]- [MODEM.STATUS]->Modem is Initializing (tmo:100) 11:39:03.899> [2018.03.10 08:39:05]-[AppSend] No sending procedure in 100600728 sec, configured period: 600 sec 11:39:04.086> [2018.03.10 08:39:06]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:39:04.649> [2018.03.10 08:39:06]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:297[1800] ds18:0[1800] lvcan:0[1800] 11:39:04.837> [2018.03.10 08:39:06]- [MODEM.STATUS]->Modem is Initializing (tmo:99) 11:39:04.899> [2018.03.10 08:39:07]- [MODEM.STATUS]->Modem is Initializing (tmo:98) 11:39:05.899> [2018.03.10 08:39:08]- [MODEM.STATUS]->Modem is Initializing (tmo:97) 11:39:06.899> [2018.03.10 08:39:09]- [MODEM.STATUS]->Modem is Initializing (tmo:96) 11:39:07.899> [2018.03.10 08:39:10]- [MODEM.STATUS]->Modem is Initializing (tmo:95) 11:39:08.899> [2018.03.10 08:39:11]- [MODEM.STATUS]->Modem is Initializing (tmo:94) 11:39:09.899> [2018.03.10 08:39:12]- [MODEM.STATUS]->Modem is Initializing (tmo:93) 11:39:10.899> [2018.03.10 08:39:13]- [MODEM.STATUS]->Modem is Initializing (tmo:92) 11:39:11.899> [2018.03.10 08:39:13]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:39:12.087> [2018.03.10 08:39:13]-[AppSend] Checking SMS Data Sending 11:39:12.087> [2018.03.10 08:39:13]-[AppSend] SMS Data Sending is Disabled 11:39:12.087> [2018.03.10 08:39:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:39:12.649> [2018.03.10 08:39:14]-[SCHED] lvcan request 11:39:12.649> [2018.03.10 08:39:14]- [MODEM.STATUS]->Modem is Initializing (tmo:91) 11:39:12.899> [2018.03.10 08:39:14]- [MODEM.STATUS]->Modem is Initializing (tmo:90) 11:39:12.962> [2018.03.10 08:39:15]-[DEBUG] 11:39:13.399> 11:39:13.399> UARTTxFullCnt: 0 11:39:13.399> SchTaskRunCnt: 10 11:39:13.399> UARTTskRunCnt: 1000 11:39:13.399> AppSaveRunCnt: 10 11:39:13.399> AppSendRunCnt: 10 11:39:13.399> ValidNMEAPkts: 80 11:39:13.399> RS232 procesed: 0 11:39:13.399> RS232 droped : 0 11:39:13.399> RS485 procesed: 0 11:39:13.399> RS485 droped : 0 11:39:13.399> 11:39:13.399> [2018.03.10 08:39:15]- [MODEM.STATUS]->Modem is Initializing (tmo:89) 11:39:13.899> [2018.03.10 08:39:15]-[AppSend] No sending procedure in 100600738 sec, configured period: 600 sec 11:39:14.087> [2018.03.10 08:39:16]- [MODEM.STATUS]->Modem is Initializing (tmo:88) 11:39:14.899> [2018.03.10 08:39:17]- [MODEM.STATUS]->Modem is Initializing (tmo:87) 11:39:15.900> [2018.03.10 08:39:17]-[ModeChange] GPS selected as movement indicator 11:39:15.900> [2018.03.10 08:39:18]-[ModeChange] GPS selected as movement indicator 11:39:16.650> [2018.03.10 08:39:18]-[ModeChange] Nothing to change 11:39:16.650> [2018.03.10 08:39:18]- [MODEM.STATUS]->Modem is Initializing (tmo:86) 11:39:16.900> [2018.03.10 08:39:19]- [MODEM.STATUS]->Modem is Initializing (tmo:85) 11:39:17.900> [2018.03.10 08:39:20]- [MODEM.STATUS]->Modem is Initializing (tmo:84) 11:39:18.900> [2018.03.10 08:39:21]- [MODEM.STATUS]->Modem is Initializing (tmo:83) 11:39:19.900> [2018.03.10 08:39:22]- [MODEM.STATUS]->Modem is Initializing (tmo:82) 11:39:20.900> [2018.03.10 08:39:23]- [MODEM.STATUS]->Modem is Initializing (tmo:81) 11:39:21.900> [2018.03.10 08:39:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:39:22.650> [2018.03.10 08:39:24]-[SCHED] lvcan request 11:39:22.650> [2018.03.10 08:39:24]- [MODEM.STATUS]->Modem is Initializing (tmo:80) 11:39:23.212> [2018.03.10 08:39:24]- [MODEM.STATUS]->Modem is Initializing (tmo:79) 11:39:23.212> [2018.03.10 08:39:25]- [MODEM.STATUS]->Modem is Initializing (tmo:78) 11:39:23.900> [2018.03.10 08:39:25]-[AppSend] No sending procedure in 100600748 sec, configured period: 600 sec 11:39:24.087> [2018.03.10 08:39:26]-[InitModem] ERROR @ modem_init.c(177): Modem seems to be dead 11:39:24.150> [2018.03.10 08:39:26]- RX:1, TX:1, CTS:1, RTS:1, ON:0, PWR:1, RESET:1, DTR:0 11:39:24.150> [2018.03.10 08:39:26]-[InitModem] Cycle: 3 of 10 11:39:24.150> [2018.03.10 08:39:26]-[InitModem] Restarting modem... 11:39:24.150> [2018.03.10 08:39:26]- [MODEM.STATUS]->Modem is OFF. 11:39:24.900> [2018.03.10 08:39:27]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:318[1800] ds18:0[1800] lvcan:0[1800] 11:39:25.838> [2018.03.10 08:39:27]- [MODEM.STATUS]->Modem is OFF. 11:39:25.900> [2018.03.10 08:39:28]- [MODEM.STATUS]->Modem is OFF. 11:39:26.900> [2018.03.10 08:39:29]- [MODEM.STATUS]->Modem is OFF. 11:39:27.900> [2018.03.10 08:39:30]- [MODEM.STATUS]->Modem is OFF. 11:39:28.900> [2018.03.10 08:39:31]- [MODEM.STATUS]->Modem is OFF. 11:39:29.900> [2018.03.10 08:39:32]- [MODEM.STATUS]->Modem is OFF. 11:39:30.900> [2018.03.10 08:39:32]-[AutoGeo] AutoGeofence is disabled 11:39:31.119> [2018.03.10 08:39:33]- [MODEM.STATUS]->Modem is OFF. 11:39:31.900> [2018.03.10 08:39:34]-[InitModem] Wait For Ready 11:39:32.213> [2018.03.10 08:39:34]- RX:0, TX:0, CTS:1, RTS:1, ON:0, PWR:1, RESET:1, DTR:0 11:39:32.213> [2018.03.10 08:39:34]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:39:32.650> [2018.03.10 08:39:34]-[SCHED] lvcan request 11:39:32.650> [2018.03.10 08:39:34]-[LLS.485] Address table reseted 11:39:32.650> [2018.03.10 08:39:34]-[LLS.485] Go to SCAN procedure 11:39:32.650> [2018.03.10 08:39:34]-[DeepSleep] SLEEP MODE DISABLED! 11:39:32.900> [2018.03.10 08:39:34]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:39:32.900> [2018.03.10 08:39:34]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:39:32.963> [2018.03.10 08:39:35]-[FWInfo] 11:39:33.400> 11:39:33.400> FW version: 01.21.10.Rev.00 11:39:33.400> BL version: 01.08 11:39:33.400> Toolchain : 4.0.0524 11:39:33.400> Compiled : Oct 20 2016 @ 09:05:49 11:39:33.400> HW version: FM11YX_MOD1_A0:B0:C0:D0:E2:F0:G0:H0:I0:J0 11:39:33.400> HW voltage: 1241 mV 11:39:33.400> 11:39:33.400> [2018.03.10 08:39:35]-[PERIODIC.ONLINE.INFO] 11:39:33.400> 11:39:33.400> Code Ver : 01.21.10.Rev:0 11:39:33.400> BL Version : 01.08 11:39:33.400> Hw Version : FM11YX_MOD1_A0:B0:C0:D0:E2:F0:G0:H0:I0:J0 11:39:33.400> 11:39:33.400> Device IMEI : 356307040580804 11:39:33.400> Device IMSI : 250015890967995 11:39:33.400> Modem AppVer : 1.03.03 11:39:33.400> Modem FullVer : TM11Q_R_01.03.03.03_002 11:39:33.400> 11:39:33.400> Reset Cause : Power On / Power Down 11:39:33.994> Startup Time : 2018/3/10 8:28:27 11:39:33.994> RTC Clock : 2018/3/10 8:39:35 11:39:33.994> GPS Clock : 2018/3/10 8:39:34 11:39:33.994> Device Uptime : 0:11:08 11:39:33.994> 11:39:33.994> Sesion Upload : 0 Bytes 11:39:33.994> Total Upload : 0 Bytes 11:39:33.994> SMS Sent : 0 11:39:33.994> Received : 0 11:39:33.994> 11:39:33.994> Restarts Made : 6 11:39:33.994> Errors Detected: 1 11:39:33.994> Records Sent : 0 11:39:33.994> BadRec Detected: 7 11:39:33.994> ProfCRC Fails : 0 11:39:33.994> Failed GPRS : 0 11:39:33.994> Failed Link : 0 11:39:33.994> UDP Link TMO : 0 11:39:33.994> 11:39:33.994> No GPS Timer : 0:4:2 11:39:33.994> GPS Status : 3 11:39:33.994> Actual Sat : 7 11:39:33.994> Maximum Sat : 7 11:39:33.994> Last fix acqu : 238 sec (acquired) 11:39:33.994> 11:39:33.994> Working Mode : 4 - Unknown on Stop 11:39:33.994> 11:39:33.994> [2018.03.10 08:39:35]- [Ver]->01.21.10,0,356307040580804,Oct 20 2016,01.08,06,0,0,1.03.03,1241,,,,,, 11:39:33.994> [2018.03.10 08:39:35]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:39:33.994> [2018.03.10 08:39:35]-[AppSend] No sending procedure in 100600758 sec, configured period: 600 sec 11:39:34.963> [2018.03.10 08:39:36]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:39:34.963> [2018.03.10 08:39:36]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:39:34.963> [2018.03.10 08:39:37]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:39:35.901> [2018.03.10 08:39:38]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:39:36.901> [2018.03.10 08:39:39]-[Scenarios] Periodic info: 11:39:37.651> DOUT1 scenarios disabled 11:39:37.651> [2018.03.10 08:39:39]-[DOUT2.None] State: DISABLED 11:39:37.651> [2018.03.10 08:39:39]- [MODEM.STATUS]->Modem is Initializing (tmo:294) 11:39:37.901> [2018.03.10 08:39:40]- [MODEM.STATUS]->Modem is Initializing (tmo:293) 11:39:38.901> [2018.03.10 08:39:41]- [MODEM.STATUS]->Modem is Initializing (tmo:292) 11:39:39.901> [2018.03.10 08:39:41]- [MODEM.STATUS]->Modem is Initializing (tmo:291) 11:39:40.026> [2018.03.10 08:39:42]-[GPRS] Repeating task after delay 11:39:40.151> [2018.03.10 08:39:42]- [MODEM.STATUS]->Modem is Initializing (tmo:290) 11:39:40.901> [2018.03.10 08:39:43]- [MODEM.STATUS]->Modem is Initializing (tmo:289) 11:39:41.901> [2018.03.10 08:39:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:39:42.651> [2018.03.10 08:39:44]-[SCHED] lvcan request 11:39:42.651> [2018.03.10 08:39:44]- [MODEM.STATUS]->Modem is Initializing (tmo:288) 11:39:42.901> [2018.03.10 08:39:44]- [MODEM.STATUS]->Modem is Initializing (tmo:287) 11:39:42.963> [2018.03.10 08:39:45]-[AppDatSav] Available 11:39:43.651> [2018.03.10 08:39:45]-[AppDatSav] 175 seconds before periodic record save 11:39:43.651> [2018.03.10 08:39:45]- [MODEM.STATUS]->Modem is Initializing (tmo:286) 11:39:43.901> [2018.03.10 08:39:45]-[AppSend] No sending procedure in 100600768 sec, configured period: 600 sec 11:39:44.088> [2018.03.10 08:39:46]- [MODEM.STATUS]->Modem is Initializing (tmo:285) 11:39:44.901> [2018.03.10 08:39:47]- [MODEM.STATUS]->Modem is Initializing (tmo:284) 11:39:45.901> [2018.03.10 08:39:48]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:339[1800] ds18:0[1800] lvcan:0[1800] 11:39:46.839> [2018.03.10 08:39:48]- [MODEM.STATUS]->Modem is Initializing (tmo:283) 11:39:46.901> [2018.03.10 08:39:49]- [MODEM.STATUS]->Modem is Initializing (tmo:282) 11:39:47.901> [2018.03.10 08:39:50]- [MODEM.STATUS]->Modem is Initializing (tmo:281) 11:39:48.901> [2018.03.10 08:39:51]- [MODEM.STATUS]->Modem is Initializing (tmo:280) 11:39:49.901> [2018.03.10 08:39:52]- [MODEM.STATUS]->Modem is Initializing (tmo:279) 11:39:50.901> [2018.03.10 08:39:53]- [MODEM.STATUS]->Modem is Initializing (tmo:278) 11:39:51.901> [2018.03.10 08:39:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:39:52.651> [2018.03.10 08:39:54]-[SCHED] lvcan request 11:39:52.651> [2018.03.10 08:39:54]- [MODEM.STATUS]->Modem is Initializing (tmo:277) 11:39:52.901> [2018.03.10 08:39:54]- [MODEM.STATUS]->Modem is Initializing (tmo:276) 11:39:52.964> [2018.03.10 08:39:55]-[DEBUG] 11:39:53.526> 11:39:53.526> UARTTxFullCnt: 0 11:39:53.526> SchTaskRunCnt: 10 11:39:53.526> UARTTskRunCnt: 1000 11:39:53.526> AppSaveRunCnt: 10 11:39:53.526> AppSendRunCnt: 10 11:39:53.526> ValidNMEAPkts: 80 11:39:53.526> RS232 procesed: 0 11:39:53.526> RS232 droped : 0 11:39:53.526> RS485 procesed: 0 11:39:53.526> RS485 droped : 0 11:39:53.526> 11:39:53.526> [2018.03.10 08:39:55]- [MODEM.STATUS]->Modem is Initializing (tmo:275) 11:39:53.901> [2018.03.10 08:39:55]-[AppSend] No sending procedure in 100600778 sec, configured period: 600 sec 11:39:54.089> [2018.03.10 08:39:56]- [MODEM.STATUS]->Modem is Initializing (tmo:274) 11:39:54.901> [2018.03.10 08:39:57]- [MODEM.STATUS]->Modem is Initializing (tmo:273) 11:39:55.901> [2018.03.10 08:39:58]- [MODEM.STATUS]->Modem is Initializing (tmo:272) 11:39:56.902> [2018.03.10 08:39:59]- [MODEM.STATUS]->Modem is Initializing (tmo:271) 11:39:57.902> [2018.03.10 08:40:00]- [MODEM.STATUS]->Modem is Initializing (tmo:270) 11:39:58.902> [2018.03.10 08:40:01]- [MODEM.STATUS]->Modem is Initializing (tmo:269) 11:39:59.902> [2018.03.10 08:40:02]- [MODEM.STATUS]->Modem is Initializing (tmo:268) 11:40:00.902> [2018.03.10 08:40:03]- [MODEM.STATUS]->Modem is Initializing (tmo:267) 11:40:01.902> [2018.03.10 08:40:03]-[ModeChange] GPS selected as movement indicator 11:40:01.902> [2018.03.10 08:40:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:40:02.652> [2018.03.10 08:40:04]-[ModeChange] GPS selected as movement indicator 11:40:02.652> [2018.03.10 08:40:04]-[SCHED] lvcan request 11:40:02.652> [2018.03.10 08:40:04]-[ModeChange] Nothing to change 11:40:02.652> [2018.03.10 08:40:04]- [MODEM.STATUS]->Modem is Initializing (tmo:266) 11:40:02.902> [2018.03.10 08:40:04]- [MODEM.STATUS]->Modem is Initializing (tmo:265) 11:40:02.964> [2018.03.10 08:40:05]- [MODEM.STATUS]->Modem is Initializing (tmo:264) 11:40:03.902> [2018.03.10 08:40:05]-[AppSend] No sending procedure in 100600788 sec, configured period: 600 sec 11:40:04.089> [2018.03.10 08:40:06]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:40:04.652> [2018.03.10 08:40:06]- [MODEM.STATUS]->Modem is Initializing (tmo:263) 11:40:04.902> [2018.03.10 08:40:07]- [MODEM.STATUS]->Modem is Initializing (tmo:262) 11:40:05.902> [2018.03.10 08:40:08]- [MODEM.STATUS]->Modem is Initializing (tmo:261) 11:40:06.902> [2018.03.10 08:40:09]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:360[1800] ds18:0[1800] lvcan:0[1800] 11:40:07.840> [2018.03.10 08:40:09]- [MODEM.STATUS]->Modem is Initializing (tmo:260) 11:40:07.902> [2018.03.10 08:40:10]- [MODEM.STATUS]->Modem is Initializing (tmo:259) 11:40:08.902> [2018.03.10 08:40:11]- [MODEM.STATUS]->Modem is Initializing (tmo:258) 11:40:09.902> [2018.03.10 08:40:12]- [MODEM.STATUS]->Modem is Initializing (tmo:257) 11:40:10.902> [2018.03.10 08:40:13]- [MODEM.STATUS]->Modem is Initializing (tmo:256) 11:40:11.902> [2018.03.10 08:40:13]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:40:12.090> [2018.03.10 08:40:13]-[AppSend] Checking SMS Data Sending 11:40:12.090> [2018.03.10 08:40:13]-[AppSend] SMS Data Sending is Disabled 11:40:12.090> [2018.03.10 08:40:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:40:12.652> [2018.03.10 08:40:14]-[SCHED] lvcan request 11:40:12.652> [2018.03.10 08:40:14]- [MODEM.STATUS]->Modem is Initializing (tmo:255) 11:40:12.902> [2018.03.10 08:40:14]- [MODEM.STATUS]->Modem is Initializing (tmo:254) 11:40:12.965> [2018.03.10 08:40:15]- [MODEM.STATUS]->Modem is Initializing (tmo:253) 11:40:13.902> [2018.03.10 08:40:15]-[AppSend] No sending procedure in 100600798 sec, configured period: 600 sec 11:40:14.090> [2018.03.10 08:40:16]- [MODEM.STATUS]->Modem is Initializing (tmo:252) 11:40:14.902> [2018.03.10 08:40:17]- [MODEM.STATUS]->Modem is Initializing (tmo:251) 11:40:15.902> [2018.03.10 08:40:18]- [MODEM.STATUS]->Modem is Initializing (tmo:250) 11:40:16.903> [2018.03.10 08:40:19]- [MODEM.STATUS]->Modem is Initializing (tmo:249) 11:40:17.903> [2018.03.10 08:40:19]-[AutoGeo] AutoGeofence is disabled 11:40:18.028> [2018.03.10 08:40:20]- [MODEM.STATUS]->Modem is Initializing (tmo:248) 11:40:18.903> [2018.03.10 08:40:21]- [MODEM.STATUS]->Modem is Initializing (tmo:247) 11:40:19.903> [2018.03.10 08:40:22]- [MODEM.STATUS]->Modem is Initializing (tmo:246) 11:40:20.903> [2018.03.10 08:40:23]- [MODEM.STATUS]->Modem is Initializing (tmo:245) 11:40:21.903> [2018.03.10 08:40:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:40:22.653> [2018.03.10 08:40:24]-[SCHED] lvcan request 11:40:22.653> [2018.03.10 08:40:24]- [MODEM.STATUS]->Modem is Initializing (tmo:244) 11:40:22.903> [2018.03.10 08:40:24]- [MODEM.STATUS]->Modem is Initializing (tmo:243) 11:40:22.965> [2018.03.10 08:40:25]- [MODEM.STATUS]->Modem is Initializing (tmo:242) 11:40:23.903> [2018.03.10 08:40:25]-[AppSend] No sending procedure in 100600808 sec, configured period: 600 sec 11:40:24.090> [2018.03.10 08:40:26]- [MODEM.STATUS]->Modem is Initializing (tmo:241) 11:40:24.903> [2018.03.10 08:40:27]- [MODEM.STATUS]->Modem is Initializing (tmo:240) 11:40:25.903> [2018.03.10 08:40:28]- [MODEM.STATUS]->Modem is Initializing (tmo:239) 11:40:26.903> [2018.03.10 08:40:29]- [MODEM.STATUS]->Modem is Initializing (tmo:238) 11:40:27.903> [2018.03.10 08:40:30]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:381[1800] ds18:0[1800] lvcan:0[1800] 11:40:28.841> [2018.03.10 08:40:30]- [MODEM.STATUS]->Modem is Initializing (tmo:237) 11:40:28.903> [2018.03.10 08:40:31]- [MODEM.STATUS]->Modem is Initializing (tmo:236) 11:40:29.903> [2018.03.10 08:40:32]- [MODEM.STATUS]->Modem is Initializing (tmo:235) 11:40:30.903> [2018.03.10 08:40:33]- [MODEM.STATUS]->Modem is Initializing (tmo:234) 11:40:31.903> [2018.03.10 08:40:34]-[StackCheck] StackInfo: 11:40:32.653> 11:40:32.653> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:40:32.653> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:40:32.653> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:40:32.653> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:40:32.653> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:40:32.653> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:40:32.653> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:40:32.653> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:40:32.653> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 368/1024 @ StackInfo 11:40:32.653> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:40:32.872> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 800/1024 @ StackInfo 11:40:32.872> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:40:32.872> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:40:32.872> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:40:32.872> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:40:32.872> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:40:32.872> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 264/ 904 @ StackInfo 11:40:32.872> 11:40:32.872> [2018.03.10 08:40:34]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:40:32.872> [2018.03.10 08:40:34]-[SCHED] lvcan request 11:40:32.872> [2018.03.10 08:40:34]-[LLS.485] Address table reseted 11:40:32.872> [2018.03.10 08:40:34]-[LLS.485] Go to SCAN procedure 11:40:32.872> [2018.03.10 08:40:34]-[DeepSleep] SLEEP MODE DISABLED! 11:40:32.872> [2018.03.10 08:40:34]- [MODEM.STATUS]->Modem is Initializing (tmo:233) 11:40:33.294> [2018.03.10 08:40:34]- [MODEM.STATUS]->Modem is Initializing (tmo:232) 11:40:33.294> [2018.03.10 08:40:35]-[DEBUG] 11:40:33.528> 11:40:33.528> UARTTxFullCnt: 0 11:40:33.528> SchTaskRunCnt: 10 11:40:33.528> UARTTskRunCnt: 1000 11:40:33.528> AppSaveRunCnt: 10 11:40:33.528> AppSendRunCnt: 10 11:40:33.528> ValidNMEAPkts: 80 11:40:33.528> RS232 procesed: 0 11:40:33.528> RS232 droped : 0 11:40:33.528> RS485 procesed: 0 11:40:33.528> RS485 droped : 0 11:40:33.528> 11:40:33.528> [2018.03.10 08:40:35]-[PERIODIC.GPS.INFO] 11:40:33.528> 11:40:33.528> Status : 3 (gps on) 11:40:33.528> Sat : 7 11:40:33.528> Lat : 54.558118 11:40:33.528> Long : 36.293259 11:40:33.528> Alt : 228 11:40:33.528> Speed : 0 11:40:33.528> Angle : 0 11:40:33.528> 11:40:33.528> GPS TMO : 900 11:40:33.528> GPS Fix : 1 11:40:33.528> NMEA Flag : 1 11:40:33.528> Time Sync : 1 11:40:33.528> Baudrate : 115200 11:40:33.528> Valid Pkts: 80 11:40:33.528> 11:40:33.528> GPS AP : 1 11:40:33.528> GPS_OFF : 1 11:40:33.528> RAM_GPS_ON: 1 11:40:33.528> SN Config : 0 11:40:33.528> SN State : 0 11:40:33.528> Log2Srv : 0 11:40:33.528> 11:40:33.528> [2018.03.10 08:40:35]- [MODEM.STATUS]->Modem is Initializing (tmo:231) 11:40:34.560> [2018.03.10 08:40:35]-[AppSend] No sending procedure in 100600818 sec, configured period: 600 sec 11:40:34.560> [2018.03.10 08:40:36]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:40:34.622> [2018.03.10 08:40:36]- [MODEM.STATUS]->Modem is Initializing (tmo:230) 11:40:34.903> [2018.03.10 08:40:37]- [MODEM.STATUS]->Modem is Initializing (tmo:229) 11:40:35.903> [2018.03.10 08:40:38]- [MODEM.STATUS]->Modem is Initializing (tmo:228) 11:40:36.903> [2018.03.10 08:40:39]- [MODEM.STATUS]->Modem is Initializing (tmo:227) 11:40:37.904> [2018.03.10 08:40:40]- [MODEM.STATUS]->Modem is Initializing (tmo:226) 11:40:38.904> [2018.03.10 08:40:41]- [MODEM.STATUS]->Modem is Initializing (tmo:225) 11:40:39.904> [2018.03.10 08:40:42]- [MODEM.STATUS]->Modem is Initializing (tmo:224) 11:40:40.654> [2018.03.10 08:40:42]-[GPRS] Repeating task after delay 11:40:40.779> [2018.03.10 08:40:42]- [MODEM.STATUS]->Modem is Initializing (tmo:223) 11:40:40.904> [2018.03.10 08:40:43]- [MODEM.STATUS]->Modem is Initializing (tmo:222) 11:40:41.904> [2018.03.10 08:40:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:40:42.654> [2018.03.10 08:40:44]-[SCHED] lvcan request 11:40:42.654> [2018.03.10 08:40:44]- [MODEM.STATUS]->Modem is Initializing (tmo:221) 11:40:42.904> [2018.03.10 08:40:44]- [MODEM.STATUS]->Modem is Initializing (tmo:220) 11:40:42.966> [2018.03.10 08:40:45]- [MODEM.STATUS]->Modem is Initializing (tmo:219) 11:40:43.904> [2018.03.10 08:40:45]-[AppSend] No sending procedure in 100600828 sec, configured period: 600 sec 11:40:44.091> [2018.03.10 08:40:46]-[AppDatSav] Available 11:40:44.654> [2018.03.10 08:40:46]-[AppDatSav] 114 seconds before periodic record save 11:40:44.654> [2018.03.10 08:40:46]- [MODEM.STATUS]->Modem is Initializing (tmo:218) 11:40:44.904> [2018.03.10 08:40:47]- [MODEM.STATUS]->Modem is Initializing (tmo:217) 11:40:45.904> [2018.03.10 08:40:48]- [MODEM.STATUS]->Modem is Initializing (tmo:216) 11:40:46.904> [2018.03.10 08:40:49]- [MODEM.STATUS]->Modem is Initializing (tmo:215) 11:40:47.904> [2018.03.10 08:40:49]-[ModeChange] GPS selected as movement indicator 11:40:47.967> [2018.03.10 08:40:50]-[ModeChange] GPS selected as movement indicator 11:40:48.654> [2018.03.10 08:40:50]-[ModeChange] Nothing to change 11:40:48.654> [2018.03.10 08:40:50]- [MODEM.STATUS]->Modem is Initializing (tmo:214) 11:40:48.904> [2018.03.10 08:40:51]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:402[1800] ds18:0[1800] lvcan:0[1800] 11:40:49.842> [2018.03.10 08:40:51]- [MODEM.STATUS]->Modem is Initializing (tmo:213) 11:40:49.904> [2018.03.10 08:40:52]- [MODEM.STATUS]->Modem is Initializing (tmo:212) 11:40:50.904> [2018.03.10 08:40:53]- [MODEM.STATUS]->Modem is Initializing (tmo:211) 11:40:51.904> [2018.03.10 08:40:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:40:52.654> [2018.03.10 08:40:54]-[SCHED] lvcan request 11:40:52.654> [2018.03.10 08:40:54]- [MODEM.STATUS]->Modem is Initializing (tmo:210) 11:40:52.904> [2018.03.10 08:40:54]- [MODEM.STATUS]->Modem is Initializing (tmo:209) 11:40:52.967> [2018.03.10 08:40:55]- [MODEM.STATUS]->Modem is Initializing (tmo:208) 11:40:53.904> [2018.03.10 08:40:55]-[AppSend] No sending procedure in 100600838 sec, configured period: 600 sec 11:40:54.092> [2018.03.10 08:40:56]- [MODEM.STATUS]->Modem is Initializing (tmo:207) 11:40:54.904> [2018.03.10 08:40:57]- [MODEM.STATUS]->Modem is Initializing (tmo:206) 11:40:55.904> [2018.03.10 08:40:58]- [MODEM.STATUS]->Modem is Initializing (tmo:205) 11:40:56.904> [2018.03.10 08:40:59]- [MODEM.STATUS]->Modem is Initializing (tmo:204) 11:40:57.905> [2018.03.10 08:41:00]- [MODEM.STATUS]->Modem is Initializing (tmo:203) 11:40:58.905> [2018.03.10 08:41:01]- [MODEM.STATUS]->Modem is Initializing (tmo:202) 11:40:59.905> [2018.03.10 08:41:02]- [MODEM.STATUS]->Modem is Initializing (tmo:201) 11:41:00.905> [2018.03.10 08:41:03]- [MODEM.STATUS]->Modem is Initializing (tmo:200) 11:41:01.905> [2018.03.10 08:41:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:41:02.655> [2018.03.10 08:41:04]-[SCHED] lvcan request 11:41:02.655> [2018.03.10 08:41:04]-[Scheduler] GC is scheduled after: 6450 sec 11:41:02.655> [2018.03.10 08:41:04]- [MODEM.STATUS]->Modem is Initializing (tmo:199) 11:41:02.905> [2018.03.10 08:41:04]- [MODEM.STATUS]->Modem is Initializing (tmo:198) 11:41:02.967> [2018.03.10 08:41:05]- [MODEM.STATUS]->Modem is Initializing (tmo:197) 11:41:03.905> [2018.03.10 08:41:05]-[AppSend] No sending procedure in 100600848 sec, configured period: 600 sec 11:41:04.092> [2018.03.10 08:41:06]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:41:04.655> [2018.03.10 08:41:06]- [MODEM.STATUS]->Modem is Initializing (tmo:196) 11:41:04.905> [2018.03.10 08:41:06]-[AutoGeo] AutoGeofence is disabled 11:41:05.030> [2018.03.10 08:41:07]- [MODEM.STATUS]->Modem is Initializing (tmo:195) 11:41:05.905> [2018.03.10 08:41:08]- [MODEM.STATUS]->Modem is Initializing (tmo:194) 11:41:06.905> [2018.03.10 08:41:09]- [MODEM.STATUS]->Modem is Initializing (tmo:193) 11:41:07.905> [2018.03.10 08:41:10]- [MODEM.STATUS]->Modem is Initializing (tmo:192) 11:41:08.905> [2018.03.10 08:41:11]- [MODEM.STATUS]->Modem is Initializing (tmo:191) 11:41:09.905> [2018.03.10 08:41:12]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:423[1800] ds18:0[1800] lvcan:0[1800] 11:41:10.843> [2018.03.10 08:41:12]- [MODEM.STATUS]->Modem is Initializing (tmo:190) 11:41:10.905> [2018.03.10 08:41:13]- [MODEM.STATUS]->Modem is Initializing (tmo:189) 11:41:11.905> [2018.03.10 08:41:13]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:41:12.093> [2018.03.10 08:41:13]-[AppSend] Checking SMS Data Sending 11:41:12.093> [2018.03.10 08:41:13]-[AppSend] SMS Data Sending is Disabled 11:41:12.093> [2018.03.10 08:41:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:41:12.655> [2018.03.10 08:41:14]-[Scenarios] Periodic info: 11:41:12.655> DOUT1 scenarios disabled 11:41:12.655> [2018.03.10 08:41:14]-[DOUT2.None] State: DISABLED 11:41:12.655> [2018.03.10 08:41:14]-[SCHED] lvcan request 11:41:12.655> [2018.03.10 08:41:14]- [MODEM.STATUS]->Modem is Initializing (tmo:188) 11:41:12.905> [2018.03.10 08:41:14]- [MODEM.STATUS]->Modem is Initializing (tmo:187) 11:41:12.968> [2018.03.10 08:41:15]-[DEBUG] 11:41:13.593> 11:41:13.593> UARTTxFullCnt: 0 11:41:13.593> SchTaskRunCnt: 10 11:41:13.593> UARTTskRunCnt: 1000 11:41:13.593> AppSaveRunCnt: 10 11:41:13.593> AppSendRunCnt: 10 11:41:13.593> ValidNMEAPkts: 80 11:41:13.593> RS232 procesed: 0 11:41:13.593> RS232 droped : 0 11:41:13.593> RS485 procesed: 0 11:41:13.593> RS485 droped : 0 11:41:13.593> 11:41:13.593> [2018.03.10 08:41:15]- [MODEM.STATUS]->Modem is Initializing (tmo:186) 11:41:13.905> [2018.03.10 08:41:15]-[AppSend] No sending procedure in 100600858 sec, configured period: 600 sec 11:41:14.093> [2018.03.10 08:41:16]- [MODEM.STATUS]->Modem is Initializing (tmo:185) 11:41:14.905> [2018.03.10 08:41:17]- [MODEM.STATUS]->Modem is Initializing (tmo:184) 11:41:15.905> [2018.03.10 08:41:18]- [MODEM.STATUS]->Modem is Initializing (tmo:183) 11:41:16.905> [2018.03.10 08:41:19]- [MODEM.STATUS]->Modem is Initializing (tmo:182) 11:41:17.905> [2018.03.10 08:41:20]- [MODEM.STATUS]->Modem is Initializing (tmo:181) 11:41:18.906> [2018.03.10 08:41:21]- [MODEM.STATUS]->Modem is Initializing (tmo:180) 11:41:19.906> [2018.03.10 08:41:22]- [MODEM.STATUS]->Modem is Initializing (tmo:179) 11:41:20.906> [2018.03.10 08:41:23]- [MODEM.STATUS]->Modem is Initializing (tmo:178) 11:41:21.906> [2018.03.10 08:41:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:41:22.656> [2018.03.10 08:41:24]-[SCHED] lvcan request 11:41:22.656> [2018.03.10 08:41:24]- [MODEM.STATUS]->Modem is Initializing (tmo:177) 11:41:22.906> [2018.03.10 08:41:24]- [MODEM.STATUS]->Modem is Initializing (tmo:176) 11:41:22.968> [2018.03.10 08:41:25]- [MODEM.STATUS]->Modem is Initializing (tmo:175) 11:41:23.906> [2018.03.10 08:41:25]-[AppSend] No sending procedure in 100600868 sec, configured period: 600 sec 11:41:24.093> [2018.03.10 08:41:26]- [MODEM.STATUS]->Modem is Initializing (tmo:174) 11:41:24.906> [2018.03.10 08:41:27]- [MODEM.STATUS]->Modem is Initializing (tmo:173) 11:41:25.906> [2018.03.10 08:41:28]- [MODEM.STATUS]->Modem is Initializing (tmo:172) 11:41:26.906> [2018.03.10 08:41:29]- [MODEM.STATUS]->Modem is Initializing (tmo:171) 11:41:27.906> [2018.03.10 08:41:30]- [MODEM.STATUS]->Modem is Initializing (tmo:170) 11:41:28.906> [2018.03.10 08:41:31]- [MODEM.STATUS]->Modem is Initializing (tmo:169) 11:41:29.906> [2018.03.10 08:41:31]-[AppSend] No sending procedure in 100600874 sec, configured period: 600 sec 11:41:30.094> [2018.03.10 08:41:32]- [MODEM.STATUS]->Modem is Initializing (tmo:168) 11:41:30.906> [2018.03.10 08:41:33]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:444[1800] ds18:0[1800] lvcan:0[1800] 11:41:31.844> [2018.03.10 08:41:33]- [MODEM.STATUS]->Modem is Initializing (tmo:167) 11:41:31.906> [2018.03.10 08:41:34]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:41:32.656> [2018.03.10 08:41:34]-[SCHED] lvcan request 11:41:32.656> [2018.03.10 08:41:34]-[LLS.485] Address table reseted 11:41:32.656> [2018.03.10 08:41:34]-[LLS.485] Go to SCAN procedure 11:41:32.656> [2018.03.10 08:41:34]-[DeepSleep] SLEEP MODE DISABLED! 11:41:32.906> [2018.03.10 08:41:34]- [MODEM.STATUS]->Modem is Initializing (tmo:166) 11:41:32.906> [2018.03.10 08:41:34]- [MODEM.STATUS]->Modem is Initializing (tmo:165) 11:41:32.969> [2018.03.10 08:41:35]-[PowerMan] State:NORMAL ExtPG:1 BatPG:0 Ext:12352 mV; BatV:0 mV; BatI:0 mA; GPS:3286 mV; Modem:3877 mV; 11:41:33.594> [2018.03.10 08:41:35]- [MODEM.STATUS]->Modem is Initializing (tmo:164) 11:41:33.906> [2018.03.10 08:41:35]-[ModeChange] GPS selected as movement indicator 11:41:33.969> [2018.03.10 08:41:36]-[ModeChange] GPS selected as movement indicator 11:41:34.656> [2018.03.10 08:41:36]-[ModeChange] Nothing to change 11:41:34.656> [2018.03.10 08:41:36]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:41:34.656> [2018.03.10 08:41:36]- [MODEM.STATUS]->Modem is Initializing (tmo:163) 11:41:34.906> [2018.03.10 08:41:37]- [MODEM.STATUS]->Modem is Initializing (tmo:162) 11:41:35.906> [2018.03.10 08:41:38]- [MODEM.STATUS]->Modem is Initializing (tmo:161) 11:41:36.906> [2018.03.10 08:41:39]- [MODEM.STATUS]->Modem is Initializing (tmo:160) 11:41:37.906> [2018.03.10 08:41:40]- [MODEM.STATUS]->Modem is Initializing (tmo:159) 11:41:38.907> [2018.03.10 08:41:41]- [MODEM.STATUS]->Modem is Initializing (tmo:158) 11:41:39.907> [2018.03.10 08:41:41]-[AppSend] No sending procedure in 100600884 sec, configured period: 600 sec 11:41:40.094> [2018.03.10 08:41:42]- [MODEM.STATUS]->Modem is Initializing (tmo:157) 11:41:40.907> [2018.03.10 08:41:43]- [MODEM.STATUS]->Modem is Initializing (tmo:156) 11:41:41.282> [2018.03.10 08:41:43]-[GPRS] Repeating task after delay 11:41:41.344> [2018.03.10 08:41:43]- [MODEM.STATUS]->Modem is Initializing (tmo:155) 11:41:41.907> [2018.03.10 08:41:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:41:42.657> [2018.03.10 08:41:44]-[SCHED] lvcan request 11:41:42.657> [2018.03.10 08:41:44]- [MODEM.STATUS]->Modem is Initializing (tmo:154) 11:41:42.907> [2018.03.10 08:41:44]- [MODEM.STATUS]->Modem is Initializing (tmo:153) 11:41:42.969> [2018.03.10 08:41:45]- [MODEM.STATUS]->Modem is Initializing (tmo:152) 11:41:43.907> [2018.03.10 08:41:46]- [MODEM.STATUS]->Modem is Initializing (tmo:151) 11:41:44.907> [2018.03.10 08:41:47]-[AppDatSav] Available 11:41:45.657> [2018.03.10 08:41:47]-[AppDatSav] 53 seconds before periodic record save 11:41:45.657> [2018.03.10 08:41:47]- [MODEM.STATUS]->Modem is Initializing (tmo:150) 11:41:45.907> [2018.03.10 08:41:48]- [MODEM.STATUS]->Modem is Initializing (tmo:149) 11:41:46.907> [2018.03.10 08:41:49]- [MODEM.STATUS]->Modem is Initializing (tmo:148) 11:41:47.907> [2018.03.10 08:41:50]- [MODEM.STATUS]->Modem is Initializing (tmo:147) 11:41:48.907> [2018.03.10 08:41:51]- [MODEM.STATUS]->Modem is Initializing (tmo:146) 11:41:49.907> [2018.03.10 08:41:51]-[AppSend] No sending procedure in 100600894 sec, configured period: 600 sec 11:41:50.095> [2018.03.10 08:41:52]- [MODEM.STATUS]->Modem is Initializing (tmo:145) 11:41:50.907> [2018.03.10 08:41:53]- [MODEM.STATUS]->Modem is Initializing (tmo:144) 11:41:51.907> [2018.03.10 08:41:53]-[AutoGeo] AutoGeofence is disabled 11:41:52.032> [2018.03.10 08:41:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:41:52.657> [2018.03.10 08:41:54]-[SCHED] lvcan request 11:41:52.657> [2018.03.10 08:41:54]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:465[1800] ds18:0[1800] lvcan:0[1800] 11:41:52.845> [2018.03.10 08:41:54]- [MODEM.STATUS]->Modem is Initializing (tmo:143) 11:41:52.907> [2018.03.10 08:41:54]- [MODEM.STATUS]->Modem is Initializing (tmo:142) 11:41:52.970> [2018.03.10 08:41:55]-[DEBUG] 11:41:53.595> 11:41:53.595> UARTTxFullCnt: 0 11:41:53.595> SchTaskRunCnt: 10 11:41:53.595> UARTTskRunCnt: 1000 11:41:53.595> AppSaveRunCnt: 10 11:41:53.595> AppSendRunCnt: 10 11:41:53.595> ValidNMEAPkts: 90 11:41:53.595> RS232 procesed: 0 11:41:53.595> RS232 droped : 0 11:41:53.595> RS485 procesed: 0 11:41:53.595> RS485 droped : 0 11:41:53.595> 11:41:53.595> [2018.03.10 08:41:55]- [MODEM.STATUS]->Modem is Initializing (tmo:141) 11:41:53.907> [2018.03.10 08:41:56]- [MODEM.STATUS]->Modem is Initializing (tmo:140) 11:41:54.907> [2018.03.10 08:41:57]- [MODEM.STATUS]->Modem is Initializing (tmo:139) 11:41:55.907> [2018.03.10 08:41:58]- [MODEM.STATUS]->Modem is Initializing (tmo:138) 11:41:56.907> [2018.03.10 08:41:59]- [MODEM.STATUS]->Modem is Initializing (tmo:137) 11:41:57.907> [2018.03.10 08:42:00]- [MODEM.STATUS]->Modem is Initializing (tmo:136) 11:41:58.907> [2018.03.10 08:42:01]- [MODEM.STATUS]->Modem is Initializing (tmo:135) 11:41:59.908> [2018.03.10 08:42:01]-[AppSend] No sending procedure in 100600904 sec, configured period: 600 sec 11:42:00.095> [2018.03.10 08:42:02]- [MODEM.STATUS]->Modem is Initializing (tmo:134) 11:42:00.908> [2018.03.10 08:42:03]- [MODEM.STATUS]->Modem is Initializing (tmo:133) 11:42:01.908> [2018.03.10 08:42:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:42:02.658> [2018.03.10 08:42:04]-[SCHED] lvcan request 11:42:02.658> [2018.03.10 08:42:04]- [MODEM.STATUS]->Modem is Initializing (tmo:132) 11:42:02.908> [2018.03.10 08:42:04]- [MODEM.STATUS]->Modem is Initializing (tmo:131) 11:42:02.970> [2018.03.10 08:42:05]- [MODEM.STATUS]->Modem is Initializing (tmo:130) 11:42:03.908> [2018.03.10 08:42:06]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:42:04.658> [2018.03.10 08:42:06]- [MODEM.STATUS]->Modem is Initializing (tmo:129) 11:42:04.908> [2018.03.10 08:42:07]- [MODEM.STATUS]->Modem is Initializing (tmo:128) 11:42:05.908> [2018.03.10 08:42:08]- [MODEM.STATUS]->Modem is Initializing (tmo:127) 11:42:06.908> [2018.03.10 08:42:09]- [MODEM.STATUS]->Modem is Initializing (tmo:126) 11:42:07.908> [2018.03.10 08:42:10]- [MODEM.STATUS]->Modem is Initializing (tmo:125) 11:42:08.908> [2018.03.10 08:42:11]- [MODEM.STATUS]->Modem is Initializing (tmo:124) 11:42:09.908> [2018.03.10 08:42:12]-[AppSend] No sending procedure in 100600915 sec, configured period: 600 sec 11:42:10.096> [2018.03.10 08:42:12]- [MODEM.STATUS]->Modem is Initializing (tmo:123) 11:42:10.908> [2018.03.10 08:42:13]- [MODEM.STATUS]->Modem is Initializing (tmo:122) 11:42:11.908> [2018.03.10 08:42:14]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:42:12.096> [2018.03.10 08:42:14]-[AppSend] Checking SMS Data Sending 11:42:12.096> [2018.03.10 08:42:14]-[AppSend] SMS Data Sending is Disabled 11:42:12.096> [2018.03.10 08:42:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:42:12.658> [2018.03.10 08:42:14]-[SCHED] lvcan request 11:42:12.658> [2018.03.10 08:42:14]- [MODEM.STATUS]->Modem is Initializing (tmo:121) 11:42:12.908> [2018.03.10 08:42:14]- [MODEM.STATUS]->Modem is Initializing (tmo:120) 11:42:12.971> [2018.03.10 08:42:15]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:486[1800] ds18:0[1800] lvcan:0[1800] 11:42:13.846> [2018.03.10 08:42:15]- [MODEM.STATUS]->Modem is Initializing (tmo:119) 11:42:13.908> [2018.03.10 08:42:16]- [MODEM.STATUS]->Modem is Initializing (tmo:118) 11:42:14.908> [2018.03.10 08:42:17]- [MODEM.STATUS]->Modem is Initializing (tmo:117) 11:42:15.908> [2018.03.10 08:42:18]- [MODEM.STATUS]->Modem is Initializing (tmo:116) 11:42:16.908> [2018.03.10 08:42:19]- [MODEM.STATUS]->Modem is Initializing (tmo:115) 11:42:17.908> [2018.03.10 08:42:20]- [MODEM.STATUS]->Modem is Initializing (tmo:114) 11:42:18.908> [2018.03.10 08:42:21]- [MODEM.STATUS]->Modem is Initializing (tmo:113) 11:42:19.909> [2018.03.10 08:42:21]-[ModeChange] GPS selected as movement indicator 11:42:19.971> [2018.03.10 08:42:22]-[AppSend] No sending procedure in 100600925 sec, configured period: 600 sec 11:42:20.096> [2018.03.10 08:42:22]-[ModeChange] GPS selected as movement indicator 11:42:20.659> [2018.03.10 08:42:22]-[ModeChange] Nothing to change 11:42:20.659> [2018.03.10 08:42:22]- [MODEM.STATUS]->Modem is Initializing (tmo:112) 11:42:20.909> [2018.03.10 08:42:23]- [MODEM.STATUS]->Modem is Initializing (tmo:111) 11:42:21.909> [2018.03.10 08:42:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:42:22.659> [2018.03.10 08:42:24]-[SCHED] lvcan request 11:42:22.659> [2018.03.10 08:42:24]- [MODEM.STATUS]->Modem is Initializing (tmo:110) 11:42:22.909> [2018.03.10 08:42:24]- [MODEM.STATUS]->Modem is Initializing (tmo:109) 11:42:22.971> [2018.03.10 08:42:25]- [MODEM.STATUS]->Modem is Initializing (tmo:108) 11:42:23.909> [2018.03.10 08:42:26]- [MODEM.STATUS]->Modem is Initializing (tmo:107) 11:42:24.909> [2018.03.10 08:42:27]- [MODEM.STATUS]->Modem is Initializing (tmo:106) 11:42:25.909> [2018.03.10 08:42:28]- [MODEM.STATUS]->Modem is Initializing (tmo:105) 11:42:26.909> [2018.03.10 08:42:29]- [MODEM.STATUS]->Modem is Initializing (tmo:104) 11:42:27.909> [2018.03.10 08:42:30]- [MODEM.STATUS]->Modem is Initializing (tmo:103) 11:42:28.909> [2018.03.10 08:42:31]- [MODEM.STATUS]->Modem is Initializing (tmo:102) 11:42:29.909> [2018.03.10 08:42:32]-[AppSend] No sending procedure in 100600935 sec, configured period: 600 sec 11:42:30.096> [2018.03.10 08:42:32]- [MODEM.STATUS]->Modem is Initializing (tmo:101) 11:42:30.909> [2018.03.10 08:42:33]- [MODEM.STATUS]->Modem is Initializing (tmo:100) 11:42:31.909> [2018.03.10 08:42:34]-[StackCheck] StackInfo: 11:42:32.659> 11:42:32.659> TaskId: 2 Name: TRACE Stack: 0x20001B20 Free: 140/ 576 @ StackInfo 11:42:32.659> TaskId: 3 Name: LVCPAP Stack: 0x2000EA80 Free: 276/ 600 @ StackInfo 11:42:32.659> TaskId: 4 Name: EVTGEN Stack: 0x200064D8 Free: 624/ 872 @ StackInfo 11:42:32.659> TaskId: 5 Name: SCHEDU Stack: 0x20004CA8 Free: 464/ 768 @ StackInfo 11:42:32.659> TaskId: 6 Name: HEARTB Stack: 0x20004FA8 Free: 48/ 128 @ StackInfo 11:42:32.659> TaskId: 7 Name: UARTPR Stack: 0x200028F0 Free: 1052/1640 @ StackInfo 11:42:32.659> TaskId: 8 Name: DSLEEP Stack: 0x200095E0 Free: 324/ 768 @ StackInfo 11:42:32.659> TaskId: 9 Name: PWRMAN Stack: 0x2000C148 Free: 584/ 768 @ StackInfo 11:42:32.659> TaskId: 10 Name: DATSAV Stack: 0x2000AAE8 Free: 368/1024 @ StackInfo 11:42:32.722> TaskId: 11 Name: GSMUPD Stack: 0x2000C7D0 Free: 1408/1504 @ StackInfo 11:42:32.722> TaskId: 12 Name: CHGPRS Stack: 0x2000A6E8 Free: 800/1024 @ StackInfo 11:42:32.722> TaskId: 13 Name: OPERS Stack: 0x2000B340 Free: 816/1024 @ StackInfo 11:42:32.722> TaskId: 14 Name: APPSND Stack: 0x2000AEE8 Free: 864/1096 @ StackInfo 11:42:32.722> TaskId: 15 Name: TCPCMD Stack: 0x2000D778 Free: 1380/1536 @ StackInfo 11:42:32.722> TaskId: 16 Name: WDOG Stack: 0x20000CC0 Free: 56/ 296 @ StackInfo 11:42:32.722> TaskId: 17 Name: SMSEVT Stack: 0x2000CE20 Free: 624/1096 @ StackInfo 11:42:32.722> TaskId: 18 Name: INITM Stack: 0x2000C448 Free: 264/ 904 @ StackInfo 11:42:32.722> 11:42:32.784> [2018.03.10 08:42:34]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:42:32.784> [2018.03.10 08:42:34]-[SCHED] lvcan request 11:42:32.784> [2018.03.10 08:42:34]-[LLS.485] Address table reseted 11:42:32.784> [2018.03.10 08:42:34]-[LLS.485] Go to SCAN procedure 11:42:32.784> [2018.03.10 08:42:34]-[DeepSleep] SLEEP MODE DISABLED! 11:42:32.909> [2018.03.10 08:42:34]- [MODEM.STATUS]->Modem is Initializing (tmo:99) 11:42:32.909> [2018.03.10 08:42:34]- [MODEM.STATUS]->Modem is Initializing (tmo:98) 11:42:32.972> [2018.03.10 08:42:35]-[DEBUG] 11:42:33.597> 11:42:33.597> UARTTxFullCnt: 0 11:42:33.597> SchTaskRunCnt: 10 11:42:33.597> UARTTskRunCnt: 1000 11:42:33.597> AppSaveRunCnt: 10 11:42:33.597> AppSendRunCnt: 10 11:42:33.597> ValidNMEAPkts: 90 11:42:33.597> RS232 procesed: 0 11:42:33.597> RS232 droped : 0 11:42:33.597> RS485 procesed: 0 11:42:33.597> RS485 droped : 0 11:42:33.597> 11:42:33.597> [2018.03.10 08:42:35]-[PERIODIC.NMEA.INFO] 11:42:33.597> 11:42:33.597> Total Pkts : 6301 11:42:33.597> Bad Chksum : 0 11:42:33.597> No Chksum : 0 11:42:33.597> 11:42:33.597> HDOP Cycles: 603 11:42:33.597> HDOP Limit : 4.0 11:42:33.597> HDOP Actual: 1.3 11:42:33.597> HDOP Mean : 2.1 11:42:33.597> HDOP Min : 1.3 11:42:33.597> HDOP Max : 3.1 11:42:33.597> PDOP Actual: 2.0 11:42:33.597> HDOP Rejct : 0 11:42:33.597> HDOP Taken : 60 11:42:33.597> HDOP Timer : 60 11:42:33.597> HDOP Ready : 0 11:42:33.597> 11:42:33.597> Total sats GPS : 13 Tracked: 16 5 9 7 13 27 8 30 11:42:33.659> Total sats GLONASS: 0 Tracked: 11:42:33.659> 11:42:33.659> Bad Time: 0 11:42:33.659> Bad Lat : 0 11:42:33.659> Bad Lon : 0 11:42:33.659> Bad Spd : 0 11:42:33.659> Bad Angl: 0 11:42:33.659> Bad HDOP: 0 11:42:33.659> 11:42:33.659> GPS jmps: 0 11:42:33.659> Filtered: 841 11:42:33.659> Rejected: 0 11:42:33.659> 11:42:33.659> [2018.03.10 08:42:35]- [MODEM.STATUS]->Modem is Initializing (tmo:97) 11:42:33.909> [2018.03.10 08:42:36]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:42:34.659> [2018.03.10 08:42:36]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:507[1800] ds18:0[1800] lvcan:0[1800] 11:42:34.847> [2018.03.10 08:42:36]- [MODEM.STATUS]->Modem is Initializing (tmo:96) 11:42:34.909> [2018.03.10 08:42:37]- [MODEM.STATUS]->Modem is Initializing (tmo:95) 11:42:35.909> [2018.03.10 08:42:38]- [MODEM.STATUS]->Modem is Initializing (tmo:94) 11:42:36.909> [2018.03.10 08:42:39]- [MODEM.STATUS]->Modem is Initializing (tmo:93) 11:42:37.909> [2018.03.10 08:42:40]-[AppDatSav] uiMinPeriod (600) record action 11:42:38.659> [2018.03.10 08:42:40]- [APP.GPS]-> Saving Periodic Record 11:42:38.659> [2018.03.10 08:42:40]-[AVL] Record Saved: 11:42:38.659> 11:42:38.659> Timestamp: 1520671360000 11:42:38.659> Priority : 0 11:42:38.659> Latitude : 545581485 11:42:38.659> Longitude: 362931979 11:42:38.659> Altitude : 225 11:42:38.659> Angle : 0 11:42:38.659> Speed : 0 11:42:38.659> HDOP : 1.3 11:42:38.659> SatInUse : 8 11:42:38.659> 11:42:38.659> Event ID : 0 11:42:38.659> 11:42:38.659> RecSize : 30 Bytes 11:42:38.659> 11:42:38.659> [2018.03.10 08:42:40]- [FLASH]->New Record Address: 0x00043B00 Length: 30, 059 / 256 11:42:38.659> [2018.03.10 08:42:40]- [MODEM.STATUS]->Modem is Initializing (tmo:92) 11:42:38.909> [2018.03.10 08:42:40]-[AutoGeo] AutoGeofence is disabled 11:42:39.034> [2018.03.10 08:42:41]- [MODEM.STATUS]->Modem is Initializing (tmo:91) 11:42:39.909> [2018.03.10 08:42:42]-[AppSend] No sending procedure in 100600945 sec, configured period: 600 sec 11:42:40.097> [2018.03.10 08:42:42]- [MODEM.STATUS]->Modem is Initializing (tmo:90) 11:42:40.910> [2018.03.10 08:42:43]- [MODEM.STATUS]->Modem is Initializing (tmo:89) 11:42:41.847> [2018.03.10 08:42:43]- [MODEM.STATUS]->Modem is Initializing (tmo:88) 11:42:41.910> [2018.03.10 08:42:43]-[GPRS] Repeating task after delay 11:42:41.972> [2018.03.10 08:42:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:42:42.660> [2018.03.10 08:42:44]-[SCHED] lvcan request 11:42:42.660> [2018.03.10 08:42:44]- [MODEM.STATUS]->Modem is Initializing (tmo:87) 11:42:42.910> [2018.03.10 08:42:44]- [MODEM.STATUS]->Modem is Initializing (tmo:86) 11:42:42.972> [2018.03.10 08:42:45]- [MODEM.STATUS]->Modem is Initializing (tmo:85) 11:42:43.910> [2018.03.10 08:42:46]- [MODEM.STATUS]->Modem is Initializing (tmo:84) 11:42:45.128> [2018.03.10 08:42:47]- [MODEM.STATUS]->Modem is Initializing (tmo:83) 11:42:45.910> [2018.03.10 08:42:48]-[AppDatSav] Available 11:42:46.660> [2018.03.10 08:42:48]-[AppDatSav] 592 seconds before periodic record save 11:42:46.660> [2018.03.10 08:42:48]- [MODEM.STATUS]->Modem is Initializing (tmo:82) 11:42:46.910> [2018.03.10 08:42:49]-[Scenarios] Periodic info: 11:42:47.660> DOUT1 scenarios disabled 11:42:47.660> [2018.03.10 08:42:49]-[DOUT2.None] State: DISABLED 11:42:47.660> [2018.03.10 08:42:49]- [MODEM.STATUS]->Modem is Initializing (tmo:81) 11:42:47.910> [2018.03.10 08:42:50]- [MODEM.STATUS]->Modem is Initializing (tmo:80) 11:42:48.910> [2018.03.10 08:42:51]- [MODEM.STATUS]->Modem is Initializing (tmo:79) 11:42:49.910> [2018.03.10 08:42:52]-[AppSend] No sending procedure in 100600955 sec, configured period: 600 sec 11:42:50.097> [2018.03.10 08:42:52]- [MODEM.STATUS]->Modem is Initializing (tmo:78) 11:42:50.910> [2018.03.10 08:42:53]- [MODEM.STATUS]->Modem is Initializing (tmo:77) 11:42:51.910> [2018.03.10 08:42:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:42:52.660> [2018.03.10 08:42:54]-[SCHED] lvcan request 11:42:52.660> [2018.03.10 08:42:54]- [MODEM.STATUS]->Modem is Initializing (tmo:76) 11:42:52.910> [2018.03.10 08:42:54]- [MODEM.STATUS]->Modem is Initializing (tmo:75) 11:42:52.973> [2018.03.10 08:42:55]- [MODEM.STATUS]->Modem is Initializing (tmo:74) 11:42:53.910> [2018.03.10 08:42:56]- [MODEM.STATUS]->Modem is Initializing (tmo:73) 11:42:54.910> [2018.03.10 08:42:57]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:528[1800] ds18:0[1800] lvcan:0[1800] 11:42:55.848> [2018.03.10 08:42:57]- [MODEM.STATUS]->Modem is Initializing (tmo:72) 11:42:55.910> [2018.03.10 08:42:58]- [MODEM.STATUS]->Modem is Initializing (tmo:71) 11:42:56.910> [2018.03.10 08:42:59]- [MODEM.STATUS]->Modem is Initializing (tmo:70) 11:42:57.910> [2018.03.10 08:43:00]- [MODEM.STATUS]->Modem is Initializing (tmo:69) 11:42:58.910> [2018.03.10 08:43:01]- [MODEM.STATUS]->Modem is Initializing (tmo:68) 11:42:59.910> [2018.03.10 08:43:02]-[AppSend] No sending procedure in 100600965 sec, configured period: 600 sec 11:43:00.098> [2018.03.10 08:43:02]- [MODEM.STATUS]->Modem is Initializing (tmo:67) 11:43:00.910> [2018.03.10 08:43:03]- [MODEM.STATUS]->Modem is Initializing (tmo:66) 11:43:01.911> [2018.03.10 08:43:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:43:02.661> [2018.03.10 08:43:04]-[SCHED] lvcan request 11:43:02.661> [2018.03.10 08:43:04]- [MODEM.STATUS]->Modem is Initializing (tmo:65) 11:43:02.911> [2018.03.10 08:43:04]- [MODEM.STATUS]->Modem is Initializing (tmo:64) 11:43:02.973> [2018.03.10 08:43:05]- [MODEM.STATUS]->Modem is Initializing (tmo:63) 11:43:03.911> [2018.03.10 08:43:06]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:43:04.661> [2018.03.10 08:43:06]- [MODEM.STATUS]->Modem is Initializing (tmo:62) 11:43:04.911> [2018.03.10 08:43:07]- [MODEM.STATUS]->Modem is Initializing (tmo:61) 11:43:05.911> [2018.03.10 08:43:07]-[ModeChange] GPS selected as movement indicator 11:43:05.973> [2018.03.10 08:43:08]-[ModeChange] GPS selected as movement indicator 11:43:06.661> [2018.03.10 08:43:08]-[ModeChange] Nothing to change 11:43:06.661> [2018.03.10 08:43:08]- [MODEM.STATUS]->Modem is Initializing (tmo:60) 11:43:06.911> [2018.03.10 08:43:09]- [MODEM.STATUS]->Modem is Initializing (tmo:59) 11:43:07.911> [2018.03.10 08:43:10]- [MODEM.STATUS]->Modem is Initializing (tmo:58) 11:43:08.911> [2018.03.10 08:43:11]- [MODEM.STATUS]->Modem is Initializing (tmo:57) 11:43:09.911> [2018.03.10 08:43:12]-[AppSend] No sending procedure in 100600975 sec, configured period: 600 sec 11:43:10.098> [2018.03.10 08:43:12]- [MODEM.STATUS]->Modem is Initializing (tmo:56) 11:43:10.911> [2018.03.10 08:43:13]- [MODEM.STATUS]->Modem is Initializing (tmo:55) 11:43:11.911> [2018.03.10 08:43:14]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:43:12.099> [2018.03.10 08:43:14]-[AppSend] Checking SMS Data Sending 11:43:12.161> [2018.03.10 08:43:14]-[AppSend] SMS Data Sending is Disabled 11:43:12.161> [2018.03.10 08:43:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:43:12.661> [2018.03.10 08:43:14]-[SCHED] lvcan request 11:43:12.661> [2018.03.10 08:43:14]- [MODEM.STATUS]->Modem is Initializing (tmo:54) 11:43:12.911> [2018.03.10 08:43:14]- [MODEM.STATUS]->Modem is Initializing (tmo:53) 11:43:12.974> [2018.03.10 08:43:15]-[DEBUG] 11:43:13.661> 11:43:13.661> UARTTxFullCnt: 0 11:43:13.661> SchTaskRunCnt: 10 11:43:13.661> UARTTskRunCnt: 1000 11:43:13.661> AppSaveRunCnt: 10 11:43:13.661> AppSendRunCnt: 10 11:43:13.661> ValidNMEAPkts: 90 11:43:13.661> RS232 procesed: 0 11:43:13.661> RS232 droped : 0 11:43:13.661> RS485 procesed: 0 11:43:13.661> RS485 droped : 0 11:43:13.661> 11:43:13.661> [2018.03.10 08:43:15]- [MODEM.STATUS]->Modem is Initializing (tmo:52) 11:43:13.942> [2018.03.10 08:43:16]- [MODEM.STATUS]->Modem is Initializing (tmo:51) 11:43:14.911> [2018.03.10 08:43:17]- [MODEM.STATUS]->Modem is Initializing (tmo:50) 11:43:15.911> [2018.03.10 08:43:18]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:549[1800] ds18:0[1800] lvcan:0[1800] 11:43:16.849> [2018.03.10 08:43:18]- [MODEM.STATUS]->Modem is Initializing (tmo:49) 11:43:16.911> [2018.03.10 08:43:19]- [MODEM.STATUS]->Modem is Initializing (tmo:48) 11:43:17.911> [2018.03.10 08:43:20]- [MODEM.STATUS]->Modem is Initializing (tmo:47) 11:43:18.911> [2018.03.10 08:43:21]- [MODEM.STATUS]->Modem is Initializing (tmo:46) 11:43:19.911> [2018.03.10 08:43:22]-[AppSend] No sending procedure in 100600985 sec, configured period: 600 sec 11:43:20.161> [2018.03.10 08:43:22]- [MODEM.STATUS]->Modem is Initializing (tmo:45) 11:43:20.911> [2018.03.10 08:43:23]- [MODEM.STATUS]->Modem is Initializing (tmo:44) 11:43:21.912> [2018.03.10 08:43:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:43:22.662> [2018.03.10 08:43:24]-[SCHED] lvcan request 11:43:22.662> [2018.03.10 08:43:24]- [MODEM.STATUS]->Modem is Initializing (tmo:43) 11:43:22.912> [2018.03.10 08:43:24]- [MODEM.STATUS]->Modem is Initializing (tmo:42) 11:43:22.974> [2018.03.10 08:43:25]- [MODEM.STATUS]->Modem is Initializing (tmo:41) 11:43:23.912> [2018.03.10 08:43:26]- [MODEM.STATUS]->Modem is Initializing (tmo:40) 11:43:24.912> [2018.03.10 08:43:27]- [MODEM.STATUS]->Modem is Initializing (tmo:39) 11:43:25.912> [2018.03.10 08:43:27]-[AutoGeo] AutoGeofence is disabled 11:43:26.037> [2018.03.10 08:43:28]- [MODEM.STATUS]->Modem is Initializing (tmo:38) 11:43:26.912> [2018.03.10 08:43:29]- [MODEM.STATUS]->Modem is Initializing (tmo:37) 11:43:27.912> [2018.03.10 08:43:30]- [MODEM.STATUS]->Modem is Initializing (tmo:36) 11:43:28.912> [2018.03.10 08:43:31]- [MODEM.STATUS]->Modem is Initializing (tmo:35) 11:43:29.912> [2018.03.10 08:43:32]-[AppSend] No sending procedure in 100600995 sec, configured period: 600 sec 11:43:30.162> [2018.03.10 08:43:32]- [MODEM.STATUS]->Modem is Initializing (tmo:34) 11:43:30.912> [2018.03.10 08:43:33]- [MODEM.STATUS]->Modem is Initializing (tmo:33) 11:43:31.912> [2018.03.10 08:43:34]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:43:32.662> [2018.03.10 08:43:34]-[SCHED] lvcan request 11:43:32.662> [2018.03.10 08:43:34]-[Scheduler] GC is scheduled after: 6300 sec 11:43:32.662> [2018.03.10 08:43:34]-[LLS.485] Address table reseted 11:43:32.662> [2018.03.10 08:43:34]-[LLS.485] Go to SCAN procedure 11:43:32.662> [2018.03.10 08:43:34]-[DeepSleep] SLEEP MODE DISABLED! 11:43:32.912> [2018.03.10 08:43:34]- [MODEM.STATUS]->Modem is Initializing (tmo:32) 11:43:32.912> [2018.03.10 08:43:34]- [MODEM.STATUS]->Modem is Initializing (tmo:31) 11:43:32.975> [2018.03.10 08:43:35]-[PERIODIC.BATRAM.INFO] 11:43:33.662> 11:43:33.662> BootSign : 0x0000 11:43:33.662> BootSign Inv: 0x0000 11:43:33.662> 11:43:33.662> Date : 2018.03.10 11:43:33.662> TimeSync : 1 11:43:33.662> Uptime : 908 11:43:33.662> 11:43:33.662> Restart Cnt : 0 11:43:33.662> WDG Restarts: 0 11:43:33.662> Bad Records : 7 11:43:33.662> AuthState : 0 11:43:33.662> GC Timer : 901 11:43:33.662> 11:43:33.662> [2018.03.10 08:43:35]- [MODEM.STATUS]->Modem is Initializing (tmo:30) 11:43:34.256> [2018.03.10 08:43:36]-[Trip] Periodic info: State -> Stop. Spd:0km/h, Mov:NO, Ign:OFF 11:43:34.662> [2018.03.10 08:43:36]- [MODEM.STATUS]->Modem is Initializing (tmo:29) 11:43:34.912> [2018.03.10 08:43:37]- [MODEM.STATUS]->Modem is Initializing (tmo:28) 11:43:35.912> [2018.03.10 08:43:38]- [MODEM.STATUS]->Modem is Initializing (tmo:27) 11:43:36.912> [2018.03.10 08:43:39]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:570[1800] ds18:0[1800] lvcan:0[1800] 11:43:37.850> [2018.03.10 08:43:39]- [MODEM.STATUS]->Modem is Initializing (tmo:26) 11:43:37.912> [2018.03.10 08:43:40]- [MODEM.STATUS]->Modem is Initializing (tmo:25) 11:43:38.912> [2018.03.10 08:43:41]- [MODEM.STATUS]->Modem is Initializing (tmo:24) 11:43:39.912> [2018.03.10 08:43:42]-[AppSend] No sending procedure in 100601005 sec, configured period: 600 sec 11:43:40.162> [2018.03.10 08:43:42]- [MODEM.STATUS]->Modem is Initializing (tmo:23) 11:43:40.912> [2018.03.10 08:43:43]- [MODEM.STATUS]->Modem is Initializing (tmo:22) 11:43:41.912> [2018.03.10 08:43:44]- [MODEM.STATUS]->Modem is Initializing (tmo:21) 11:43:42.475> [2018.03.10 08:43:44]-[GPRS] Repeating task after delay 11:43:42.538> [2018.03.10 08:43:44]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:43:42.663> [2018.03.10 08:43:44]-[SCHED] lvcan request 11:43:42.663> [2018.03.10 08:43:44]- [MODEM.STATUS]->Modem is Initializing (tmo:20) 11:43:42.913> [2018.03.10 08:43:44]- [MODEM.STATUS]->Modem is Initializing (tmo:19) 11:43:42.975> [2018.03.10 08:43:45]- [MODEM.STATUS]->Modem is Initializing (tmo:18) 11:43:43.913> [2018.03.10 08:43:46]- [MODEM.STATUS]->Modem is Initializing (tmo:17) 11:43:44.913> [2018.03.10 08:43:47]- [MODEM.STATUS]->Modem is Initializing (tmo:16) 11:43:45.913> [2018.03.10 08:43:48]- [MODEM.STATUS]->Modem is Initializing (tmo:15) 11:43:46.913> [2018.03.10 08:43:49]-[AppDatSav] Available 11:43:47.663> [2018.03.10 08:43:49]-[AppDatSav] 531 seconds before periodic record save 11:43:47.663> [2018.03.10 08:43:49]- [MODEM.STATUS]->Modem is Initializing (tmo:14) 11:43:47.913> [2018.03.10 08:43:50]- [MODEM.STATUS]->Modem is Initializing (tmo:13) 11:43:48.913> [2018.03.10 08:43:51]- [MODEM.STATUS]->Modem is Initializing (tmo:12) 11:43:49.913> [2018.03.10 08:43:52]-[AppSend] No sending procedure in 100601015 sec, configured period: 600 sec 11:43:50.163> [2018.03.10 08:43:52]- [MODEM.STATUS]->Modem is Initializing (tmo:11) 11:43:50.913> [2018.03.10 08:43:53]- [MODEM.STATUS]->Modem is Initializing (tmo:10) 11:43:51.913> [2018.03.10 08:43:53]-[ModeChange] GPS selected as movement indicator 11:43:51.975> [2018.03.10 08:43:54]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:43:52.663> [2018.03.10 08:43:54]-[ModeChange] GPS selected as movement indicator 11:43:52.663> [2018.03.10 08:43:54]-[SCHED] lvcan request 11:43:52.663> [2018.03.10 08:43:54]-[ModeChange] Nothing to change 11:43:52.663> [2018.03.10 08:43:54]- [MODEM.STATUS]->Modem is Initializing (tmo:9) 11:43:52.913> [2018.03.10 08:43:54]- [MODEM.STATUS]->Modem is Initializing (tmo:8) 11:43:52.976> [2018.03.10 08:43:55]-[DEBUG] 11:43:53.663> 11:43:53.663> UARTTxFullCnt: 0 11:43:53.663> SchTaskRunCnt: 10 11:43:53.663> UARTTskRunCnt: 1000 11:43:53.663> AppSaveRunCnt: 10 11:43:53.663> AppSendRunCnt: 10 11:43:53.663> ValidNMEAPkts: 90 11:43:53.663> RS232 procesed: 0 11:43:53.663> RS232 droped : 0 11:43:53.663> RS485 procesed: 0 11:43:53.663> RS485 droped : 0 11:43:53.663> 11:43:53.663> [2018.03.10 08:43:55]- [MODEM.STATUS]->Modem is Initializing (tmo:7) 11:43:53.913> [2018.03.10 08:43:56]- [MODEM.STATUS]->Modem is Initializing (tmo:6) 11:43:54.913> [2018.03.10 08:43:57]- [MODEM.STATUS]->Modem is Initializing (tmo:5) 11:43:55.913> [2018.03.10 08:43:58]- [MODEM.STATUS]->Modem is Initializing (tmo:4) 11:43:56.913> [2018.03.10 08:43:59]- [MODEM.STATUS]->Modem is Initializing (tmo:3) 11:43:57.913> [2018.03.10 08:44:00]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:591[1800] ds18:0[1800] lvcan:0[1800] 11:43:58.851> [2018.03.10 08:44:00]- [MODEM.STATUS]->Modem is Initializing (tmo:2) 11:43:58.913> [2018.03.10 08:44:01]- [MODEM.STATUS]->Modem is Initializing (tmo:1) 11:43:59.913> [2018.03.10 08:44:02]-[AppSend] No sending procedure in 100601025 sec, configured period: 600 sec 11:44:00.163> [2018.03.10 08:44:02]- [MODEM.STATUS]->Modem is Initializing (tmo:0) 11:44:00.913> [2018.03.10 08:44:03]- [MODEM.STATUS]->Initiating Modem Restart Procedure 11:44:01.913> [2018.03.10 08:44:04]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:44:02.664> [2018.03.10 08:44:04]-[SCHED] lvcan request 11:44:02.664> [2018.03.10 08:44:04]- [MODEM.STATUS]->Modem is Initializing (tmo:300) 11:44:02.914> [2018.03.10 08:44:04]- [MODEM.STATUS]->Modem is Initializing (tmo:299) 11:44:02.976> [2018.03.10 08:44:05]- [MODEM.STATUS]->Modem is Initializing (tmo:298) 11:44:03.914> [2018.03.10 08:44:06]-[Trip] Periodic info: State -> Stop. Spd:1km/h, Mov:NO, Ign:OFF 11:44:04.664> [2018.03.10 08:44:06]- [MODEM.STATUS]->Modem is Initializing (tmo:297) 11:44:04.914> [2018.03.10 08:44:07]- [MODEM.STATUS]->Modem is Initializing (tmo:296) 11:44:05.914> [2018.03.10 08:44:08]- [MODEM.STATUS]->Modem is Initializing (tmo:295) 11:44:06.914> [2018.03.10 08:44:09]- [MODEM.STATUS]->Modem is Initializing (tmo:294) 11:44:07.914> [2018.03.10 08:44:10]- [MODEM.STATUS]->Modem is Initializing (tmo:293) 11:44:08.914> [2018.03.10 08:44:11]- [MODEM.STATUS]->Modem is Initializing (tmo:292) 11:44:09.914> [2018.03.10 08:44:12]-[AppSend] No sending procedure in 100601035 sec, configured period: 600 sec 11:44:10.164> [2018.03.10 08:44:12]- [MODEM.STATUS]->Modem is Initializing (tmo:291) 11:44:10.914> [2018.03.10 08:44:13]- [MODEM.STATUS]->Modem is Initializing (tmo:290) 11:44:11.914> [2018.03.10 08:44:14]-[AppSend] Time left before Periodic Data SMS Send Check Procedure: 0 sec(s) 11:44:12.164> [2018.03.10 08:44:14]-[AppSend] Checking SMS Data Sending 11:44:12.164> [2018.03.10 08:44:14]-[AppSend] SMS Data Sending is Disabled 11:44:12.164> [2018.03.10 08:44:14]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:44:12.664> [2018.03.10 08:44:14]-[SCHED] lvcan request 11:44:12.664> [2018.03.10 08:44:14]- [MODEM.STATUS]->Modem is Initializing (tmo:289) 11:44:12.914> [2018.03.10 08:44:14]- [MODEM.STATUS]->Modem is Initializing (tmo:288) 11:44:12.977> [2018.03.10 08:44:14]-[AutoGeo] AutoGeofence is disabled 11:44:13.039> [2018.03.10 08:44:15]- [MODEM.STATUS]->Modem is Initializing (tmo:287) 11:44:13.914> [2018.03.10 08:44:16]- [MODEM.STATUS]->Modem is Initializing (tmo:286) 11:44:14.914> [2018.03.10 08:44:17]- [MODEM.STATUS]->Modem is Initializing (tmo:285) 11:44:15.914> [2018.03.10 08:44:18]- [MODEM.STATUS]->Modem is Initializing (tmo:284) 11:44:16.914> [2018.03.10 08:44:19]- [MODEM.STATUS]->Modem is Initializing (tmo:283) 11:44:17.914> [2018.03.10 08:44:20]- [MODEM.STATUS]->Modem is Initializing (tmo:282) 11:44:18.914> [2018.03.10 08:44:21]- [MTX.WDG]->sr:0[1800] fl:0[1800] mod:0[1800] pr:612[1800] ds18:0[1800] lvcan:0[1800] 11:44:19.852> [2018.03.10 08:44:21]- [MODEM.STATUS]->Modem is Initializing (tmo:281) 11:44:19.914> [2018.03.10 08:44:22]-[AppSend] No sending procedure in 100601045 sec, configured period: 600 sec 11:44:20.164> [2018.03.10 08:44:22]- [MODEM.STATUS]->Modem is Initializing (tmo:280) 11:44:20.914> [2018.03.10 08:44:23]- [MODEM.STATUS]->Modem is Initializing (tmo:279) 11:44:21.914> [2018.03.10 08:44:24]-[SCHED] Simple Tacho debug: Already sent: false, is active: true. 11:44:22.664> [2018.03.10 08:44:24]-[Scenarios] Periodic info: 11:44:22.664> DOUT1 scenarios disabled 11:44:22.664> [2018.03.10 08:44:24]-[DOUT2.None] State: DISABLED 11:44:22.664> [2018.03.10 08:44:24]-[SCHED] lvcan request 11:44:22.664> [2018.03.10 08:44:24]- [MODEM.STATUS]->Modem is Initializing (tmo:278) 11:44:22.914> [2018.03.10 08:44:24]- [MODEM.STATUS]->Modem is Initializing (tmo:277) 11:44:22.977> ----------------------------------------------- Date: 10.03.2018 - 11:44:23 End log file