2019-10-10T17:57:53.725Z,1570730273.725 [DataOverHttps](INFO): Received command:restart logs 2019-10-10T17:57:53.737Z,1570730273.737 [CommandLine](IMPORTANT): got command restart logs 2019-10-10T17:58:08.334Z,1570730288.334 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T17:58:11.209Z,1570730291.209 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20191010T033715/Express0095.lzma 2019-10-10T17:58:13.215Z,1570730293.215 [DataOverHttps](INFO): Moved sent file to Logs/20191010T033715/Express0095.lzma.bak 2019-10-10T17:58:13.215Z,1570730293.215 [DataOverHttps](INFO): SBD MOMSN=11872144 2019-10-10T17:58:40.416Z,1570730320.416 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191010T033715/Courier0097.lzma 2019-10-10T17:58:43.195Z,1570730323.195 [DataOverHttps](INFO): Moved sent file to Logs/20191010T033715/Courier0097.lzma.bak 2019-10-10T17:58:43.196Z,1570730323.196 [DataOverHttps](INFO): SBD MOMSN=11872148 2019-10-10T17:59:08.220Z,1570730348.220 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191010T175753/Courier0000.lzma 2019-10-10T17:59:10.227Z,1570730350.227 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Courier0000.lzma.bak 2019-10-10T17:59:10.227Z,1570730350.227 [DataOverHttps](INFO): SBD MOMSN=11872155 2019-10-10T17:59:39.533Z,1570730379.533 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20191010T033715/Express0098.lzma 2019-10-10T17:59:41.540Z,1570730381.540 [DataOverHttps](INFO): Moved sent file to Logs/20191010T033715/Express0098.lzma.bak 2019-10-10T17:59:41.540Z,1570730381.540 [DataOverHttps](INFO): SBD MOMSN=11872159 2019-10-10T18:00:08.284Z,1570730408.284 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191010T175753/Express0001.lzma 2019-10-10T18:00:10.290Z,1570730410.290 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Express0001.lzma.bak 2019-10-10T18:00:10.291Z,1570730410.291 [DataOverHttps](INFO): SBD MOMSN=11872163 2019-10-10T18:00:13.220Z,1570730413.220 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T18:00:13.220Z,1570730413.220 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T18:00:13.220Z,1570730413.220 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T18:05:13.800Z,1570730713.800 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T18:05:13.800Z,1570730713.800 [Default:CheckIn:C.Wait] Stopped 2019-10-10T18:05:13.800Z,1570730713.800 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T18:05:13.800Z,1570730713.800 [Default:CheckIn:D] Running Loop=1 2019-10-10T18:05:14.196Z,1570730714.196 [Default:CheckIn:D] Stopped 2019-10-10T18:05:14.196Z,1570730714.196 [Default:CheckIn:E] Running Loop=1 2019-10-10T18:05:14.608Z,1570730714.608 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.321598 min 2019-10-10T18:05:14.608Z,1570730714.608 [Default:CheckIn:E] Stopped 2019-10-10T18:05:14.608Z,1570730714.608 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T18:05:14.608Z,1570730714.608 [Default:CheckIn] Stopped 2019-10-10T18:05:14.608Z,1570730714.608 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T18:05:14.609Z,1570730714.609 [Default:CheckIn](INFO): Running loop #15 2019-10-10T18:05:14.609Z,1570730714.609 [Default:CheckIn] Running Loop=15 2019-10-10T18:05:14.609Z,1570730714.609 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T18:05:14.609Z,1570730714.609 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T18:05:16.201Z,1570730716.201 [NAL9602](DEBUG): Fix Requested 2019-10-10T18:05:16.596Z,1570730716.596 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180420.00,A,3648.16564,N,12147.28449,W,0.330,337.10,101019,,,A*76 2019-10-10T18:05:16.598Z,1570730716.598 [NAL9602](INFO): GPS fix at 20191010T180420: (36.802761, -121.788075) 2019-10-10T18:05:16.624Z,1570730716.624 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T18:05:16.624Z,1570730716.624 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T18:05:21.332Z,1570730721.332 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191010T175753/Courier0003.lzma 2019-10-10T18:05:22.363Z,1570730722.363 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Courier0003.lzma.bak 2019-10-10T18:05:22.363Z,1570730722.363 [DataOverHttps](INFO): SBD MOMSN=11872177 2019-10-10T18:05:47.214Z,1570730747.214 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-10-10T18:05:47.294Z,1570730747.294 [NAL9602](FAULT): received: +CSQ:0 OK50, 0, 0, 0, 0 OK 2019-10-10T18:05:47.294Z,1570730747.294 [NAL9602] Data Fault, FailCount= 1 2019-10-10T18:05:47.294Z,1570730747.294 [NAL9602](ERROR): Data Fault 2019-10-10T18:05:47.373Z,1570730747.373 [CBIT](ERROR): Data Fault in component: NAL9602 2019-10-10T18:05:47.618Z,1570730747.618 [NAL9602](INFO): Powering down 2019-10-10T18:05:48.514Z,1570730748.514 [CBIT](INFO): Clearing failed state for component NAL9602 2019-10-10T18:05:48.514Z,1570730748.514 [NAL9602] No Fault, FailCount= 1 2019-10-10T18:05:50.605Z,1570730750.605 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20191010T175753/Express0004.lzma 2019-10-10T18:05:52.610Z,1570730752.610 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Express0004.lzma.bak 2019-10-10T18:05:52.611Z,1570730752.611 [DataOverHttps](INFO): SBD MOMSN=11872181 2019-10-10T18:05:55.739Z,1570730755.739 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T18:05:55.740Z,1570730755.740 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T18:05:55.740Z,1570730755.740 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T18:06:17.929Z,1570730777.929 [NAL9602](INFO): Powering up NAL9602 2019-10-10T18:06:28.830Z,1570730788.830 [NAL9602](INFO): NAL9602 initialized 2019-10-10T18:06:59.946Z,1570730819.946 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T18:08:07.002Z,1570730887.002 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-10-10T18:08:07.002Z,1570730887.002 [DropWeight] Hardware Fault, FailCount= 1 2019-10-10T18:08:07.002Z,1570730887.002 [DropWeight](ERROR): Hardware Fault 2019-10-10T18:08:07.096Z,1570730887.096 [CommandLine](FAULT): Scheduling is paused 2019-10-10T18:08:07.097Z,1570730887.097 [CBIT](INFO): Critical error at 20191010T180807 2019-10-10T18:08:07.099Z,1570730887.099 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-10-10T18:08:07.100Z,1570730887.100 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-10-10T18:08:07.455Z,1570730887.455 [CBIT](INFO): Critical error at 20191010T180807 2019-10-10T18:10:56.304Z,1570731056.304 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T18:10:56.304Z,1570731056.304 [Default:CheckIn:C.Wait] Stopped 2019-10-10T18:10:56.304Z,1570731056.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T18:10:56.304Z,1570731056.304 [Default:CheckIn:D] Running Loop=1 2019-10-10T18:10:56.730Z,1570731056.730 [Default:CheckIn:D] Stopped 2019-10-10T18:10:56.731Z,1570731056.731 [Default:CheckIn:E] Running Loop=1 2019-10-10T18:10:57.133Z,1570731057.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.030509 min 2019-10-10T18:10:57.133Z,1570731057.133 [Default:CheckIn:E] Stopped 2019-10-10T18:10:57.133Z,1570731057.133 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T18:10:57.133Z,1570731057.133 [Default:CheckIn] Stopped 2019-10-10T18:10:57.134Z,1570731057.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T18:10:57.134Z,1570731057.134 [Default:CheckIn](INFO): Running loop #16 2019-10-10T18:10:57.134Z,1570731057.134 [Default:CheckIn] Running Loop=16 2019-10-10T18:10:57.134Z,1570731057.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T18:10:57.134Z,1570731057.134 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T18:10:58.717Z,1570731058.717 [NAL9602](DEBUG): Fix Requested 2019-10-10T18:10:59.111Z,1570731059.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181002.00,A,3648.16242,N,12147.27653,W,0.408,0.00,101019,,,A*7C 2019-10-10T18:10:59.113Z,1570731059.113 [NAL9602](INFO): GPS fix at 20191010T181002: (36.802707, -121.787942) 2019-10-10T18:10:59.140Z,1570731059.140 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T18:10:59.140Z,1570731059.140 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T18:11:00.694Z,1570731060.694 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-10-10T18:11:03.459Z,1570731063.459 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20191010T175753/Courier0006.lzma 2019-10-10T18:11:04.547Z,1570731064.547 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Courier0006.lzma.bak 2019-10-10T18:11:04.547Z,1570731064.547 [DataOverHttps](INFO): SBD MOMSN=11872206 2019-10-10T18:11:31.194Z,1570731091.194 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20191010T175753/Express0007.lzma 2019-10-10T18:11:31.402Z,1570731091.402 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T18:11:33.195Z,1570731093.195 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Express0007.lzma.bak 2019-10-10T18:11:33.196Z,1570731093.196 [DataOverHttps](INFO): SBD MOMSN=11872214 2019-10-10T18:11:36.280Z,1570731096.280 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T18:11:36.280Z,1570731096.280 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T18:11:36.280Z,1570731096.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T18:16:30.812Z,1570731390.812 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-10-10T18:16:30.854Z,1570731390.854 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-10-10T18:16:36.900Z,1570731396.900 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T18:16:36.900Z,1570731396.900 [Default:CheckIn:C.Wait] Stopped 2019-10-10T18:16:36.900Z,1570731396.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T18:16:36.900Z,1570731396.900 [Default:CheckIn:D] Running Loop=1 2019-10-10T18:16:37.292Z,1570731397.292 [Default:CheckIn:D] Stopped 2019-10-10T18:16:37.292Z,1570731397.292 [Default:CheckIn:E] Running Loop=1 2019-10-10T18:16:37.660Z,1570731397.660 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.706543 min 2019-10-10T18:16:37.660Z,1570731397.660 [Default:CheckIn:E] Stopped 2019-10-10T18:16:37.660Z,1570731397.660 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T18:16:37.660Z,1570731397.660 [Default:CheckIn] Stopped 2019-10-10T18:16:37.661Z,1570731397.661 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T18:16:37.661Z,1570731397.661 [Default:CheckIn](INFO): Running loop #17 2019-10-10T18:16:37.661Z,1570731397.661 [Default:CheckIn] Running Loop=17 2019-10-10T18:16:37.661Z,1570731397.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T18:16:37.661Z,1570731397.661 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T18:16:39.265Z,1570731399.265 [NAL9602](DEBUG): Fix Requested 2019-10-10T18:16:39.655Z,1570731399.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181543.00,A,3648.16449,N,12147.28277,W,0.078,0.00,101019,,,A*7F 2019-10-10T18:16:39.657Z,1570731399.657 [NAL9602](INFO): GPS fix at 20191010T181543: (36.802742, -121.788046) 2019-10-10T18:16:39.700Z,1570731399.700 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T18:16:39.700Z,1570731399.700 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T18:16:41.774Z,1570731401.774 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-10-10T18:16:41.993Z,1570731401.993 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-10-10T18:16:44.689Z,1570731404.689 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191010T175753/Courier0009.lzma 2019-10-10T18:16:46.702Z,1570731406.702 [DataOverHttps](INFO): Moved sent file to Logs/20191010T175753/Courier0009.lzma.bak 2019-10-10T18:16:46.703Z,1570731406.703 [DataOverHttps](INFO): SBD MOMSN=11872229 2019-10-10T18:16:52.800Z,1570731412.800 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-10-10T18:16:53.041Z,1570731413.041 [BPC1](ERROR): BPC1B failed to parse battery data due to u