2021-04-20T15:55:25.224Z,1618934125.224 [DataOverHttps](INFO): Received command:restart logs 2021-04-20T15:55:25.253Z,1618934125.253 [CommandLine](IMPORTANT): got command restart logs 2021-04-20T15:56:37.970Z,1618934197.970 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T15:56:37.970Z,1618934197.970 [Default:CheckIn:C.Wait] Stopped 2021-04-20T15:56:37.971Z,1618934197.971 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T15:56:37.971Z,1618934197.971 [Default:CheckIn:D] Running Loop=1 2021-04-20T15:56:38.365Z,1618934198.365 [Default:CheckIn:D] Stopped 2021-04-20T15:56:38.365Z,1618934198.365 [Default:CheckIn:E] Running Loop=1 2021-04-20T15:56:38.763Z,1618934198.763 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.399608 min 2021-04-20T15:56:38.768Z,1618934198.768 [Default:CheckIn:E] Stopped 2021-04-20T15:56:38.768Z,1618934198.768 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T15:56:38.768Z,1618934198.768 [Default:CheckIn] Stopped 2021-04-20T15:56:38.768Z,1618934198.768 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T15:56:38.768Z,1618934198.768 [Default:CheckIn](INFO): Running loop #3 2021-04-20T15:56:38.769Z,1618934198.769 [Default:CheckIn] Running Loop=3 2021-04-20T15:56:38.769Z,1618934198.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T15:56:38.769Z,1618934198.769 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T15:56:40.789Z,1618934200.789 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155640.00,A,3648.33758,N,12149.54967,W,9.564,229.45,200421,,,A*79 2021-04-20T15:56:40.791Z,1618934200.791 [NAL9602](INFO): GPS fix at 20210420T155640: (36.805626, -121.825828) 2021-04-20T15:56:40.805Z,1618934200.805 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T15:56:40.806Z,1618934200.806 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T15:56:51.807Z,1618934211.807 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20210420T035301/Courier0091.lzma 2021-04-20T15:56:52.810Z,1618934212.810 [DataOverHttps](INFO): Moved sent file to Logs/20210420T035301/Courier0091.lzma.bak 2021-04-20T15:56:52.810Z,1618934212.810 [DataOverHttps](INFO): SBD MOMSN=15590431 2021-04-20T15:57:01.845Z,1618934221.845 [NAL9602](INFO): SBD MO Status=1, MOMSN=41318, MT Status=0, MTMSN=0 2021-04-20T15:57:01.845Z,1618934221.845 [NAL9602](INFO): No messages in MT queue 2021-04-20T15:57:08.620Z,1618934228.620 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20210420T155525/Courier0000.lzma 2021-04-20T15:57:09.621Z,1618934229.621 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0000.lzma.bak 2021-04-20T15:57:09.622Z,1618934229.622 [DataOverHttps](INFO): SBD MOMSN=15590433 2021-04-20T15:57:27.363Z,1618934247.363 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210420T155525/Courier0003.lzma 2021-04-20T15:57:28.365Z,1618934248.365 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0003.lzma.bak 2021-04-20T15:57:28.366Z,1618934248.366 [DataOverHttps](INFO): SBD MOMSN=15590437 2021-04-20T15:57:32.565Z,1618934252.565 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T15:57:45.024Z,1618934265.024 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20210420T035301/Express0092.lzma 2021-04-20T15:57:46.026Z,1618934266.026 [DataOverHttps](INFO): Moved sent file to Logs/20210420T035301/Express0092.lzma.bak 2021-04-20T15:57:46.026Z,1618934266.026 [DataOverHttps](INFO): SBD MOMSN=15590439 2021-04-20T15:58:01.756Z,1618934281.756 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20210420T155525/Express0001.lzma 2021-04-20T15:58:02.758Z,1618934282.758 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0001.lzma.bak 2021-04-20T15:58:02.758Z,1618934282.758 [DataOverHttps](INFO): SBD MOMSN=15590444 2021-04-20T15:58:18.480Z,1618934298.480 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Express0004.lzma 2021-04-20T15:58:19.482Z,1618934299.482 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0004.lzma.bak 2021-04-20T15:58:19.482Z,1618934299.482 [DataOverHttps](INFO): SBD MOMSN=15590448 2021-04-20T15:58:20.635Z,1618934300.635 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T15:58:20.635Z,1618934300.635 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T15:58:20.635Z,1618934300.635 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:01:24.028Z,1618934484.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:01:55.160Z,1618934515.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:02:26.284Z,1618934546.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:02:57.408Z,1618934577.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:02:59.416Z,1618934579.416 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.015396 2021-04-20T16:03:21.212Z,1618934601.212 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:03:21.212Z,1618934601.212 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:03:21.212Z,1618934601.212 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:03:21.212Z,1618934601.212 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:03:21.627Z,1618934601.627 [Default:CheckIn:D] Stopped 2021-04-20T16:03:21.627Z,1618934601.627 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:03:22.016Z,1618934602.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.120650 min 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn:E] Stopped 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn] Stopped 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn](INFO): Running loop #4 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn] Running Loop=4 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:03:22.017Z,1618934602.017 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:03:24.029Z,1618934604.029 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160323.00,A,3647.92771,N,12151.35564,W,12.246,267.11,200421,,,A*47 2021-04-20T16:03:24.031Z,1618934604.031 [NAL9602](INFO): GPS fix at 20210420T160323: (36.798795, -121.855927) 2021-04-20T16:03:24.041Z,1618934604.041 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:03:24.041Z,1618934604.041 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:03:55.183Z,1618934635.183 [NAL9602](INFO): SBD MO Status=1, MOMSN=41319, MT Status=0, MTMSN=0 2021-04-20T16:03:55.236Z,1618934635.236 [NAL9602](INFO): Sent 72 bytes from file Logs/20210420T155525/Courier0006.lzma 2021-04-20T16:03:55.236Z,1618934635.236 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:03:58.652Z,1618934638.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:04:01.692Z,1618934641.692 [NAL9602](INFO): SBD MO Status=1, MOMSN=41320, MT Status=0, MTMSN=0 2021-04-20T16:04:01.746Z,1618934641.746 [NAL9602](INFO): Sent 144 bytes from file Logs/20210420T155525/Express0007.lzma 2021-04-20T16:04:01.746Z,1618934641.746 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:04:22.298Z,1618934662.298 [NAL9602](INFO): SBD MO Status=0, MOMSN=41321, MT Status=0, MTMSN=0 2021-04-20T16:04:22.383Z,1618934662.383 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:04:22.398Z,1618934662.398 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:04:22.398Z,1618934662.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:04:29.784Z,1618934669.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:04:52.995Z,1618934692.995 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:05:00.908Z,1618934700.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:05:32.032Z,1618934732.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:06:03.156Z,1618934763.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:06:34.280Z,1618934794.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:07:05.404Z,1618934825.404 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:07:36.528Z,1618934856.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:08:07.652Z,1618934887.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:08:38.792Z,1618934918.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:09:09.940Z,1618934949.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:09:22.874Z,1618934962.874 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:09:22.874Z,1618934962.874 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:09:22.874Z,1618934962.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:09:22.874Z,1618934962.874 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:09:23.283Z,1618934963.283 [Default:CheckIn:D] Stopped 2021-04-20T16:09:23.283Z,1618934963.283 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.148246 min 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn:E] Stopped 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn] Stopped 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn](INFO): Running loop #5 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn] Running Loop=5 2021-04-20T16:09:23.699Z,1618934963.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:09:23.700Z,1618934963.700 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:09:25.703Z,1618934965.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160925.00,A,3647.99249,N,12151.34540,W,4.471,56.09,200421,,,A*45 2021-04-20T16:09:25.705Z,1618934965.705 [NAL9602](INFO): GPS fix at 20210420T160925: (36.799875, -121.855757) 2021-04-20T16:09:25.715Z,1618934965.715 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:09:25.715Z,1618934965.715 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:09:41.064Z,1618934981.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:09:51.214Z,1618934991.214 [NAL9602](INFO): SBD MO Status=1, MOMSN=41322, MT Status=0, MTMSN=0 2021-04-20T16:09:51.268Z,1618934991.268 [NAL9602](INFO): Sent 73 bytes from file Logs/20210420T155525/Courier0009.lzma 2021-04-20T16:09:51.268Z,1618934991.268 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:10:12.188Z,1618935012.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:10:16.715Z,1618935016.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=41323, MT Status=2, MTMSN=0 2021-04-20T16:10:16.715Z,1618935016.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T16:10:43.320Z,1618935043.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:10:46.665Z,1618935046.665 [NAL9602](INFO): SBD MO Status=1, MOMSN=41323, MT Status=0, MTMSN=0 2021-04-20T16:10:46.713Z,1618935046.713 [NAL9602](INFO): Sent 138 bytes from file Logs/20210420T155525/Express0010.lzma 2021-04-20T16:10:46.713Z,1618935046.713 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:10:59.182Z,1618935059.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=41324, MT Status=0, MTMSN=0 2021-04-20T16:10:59.259Z,1618935059.259 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:10:59.260Z,1618935059.260 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:10:59.260Z,1618935059.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:11:14.444Z,1618935074.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:11:29.887Z,1618935089.887 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:11:45.572Z,1618935105.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:12:16.696Z,1618935136.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:12:47.828Z,1618935167.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:13:18.960Z,1618935198.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:13:50.084Z,1618935230.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:14:18.448Z,1618935258.448 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002975 2021-04-20T16:15:59.772Z,1618935359.772 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:15:59.772Z,1618935359.772 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:15:59.772Z,1618935359.772 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:15:59.773Z,1618935359.773 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:16:00.182Z,1618935360.182 [Default:CheckIn:D] Stopped 2021-04-20T16:16:00.182Z,1618935360.182 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:16:00.586Z,1618935360.586 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.763230 min 2021-04-20T16:16:00.586Z,1618935360.586 [Default:CheckIn:E] Stopped 2021-04-20T16:16:00.586Z,1618935360.586 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:16:00.586Z,1618935360.586 [Default:CheckIn] Stopped 2021-04-20T16:16:00.587Z,1618935360.587 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:16:00.587Z,1618935360.587 [Default:CheckIn](INFO): Running loop #6 2021-04-20T16:16:00.587Z,1618935360.587 [Default:CheckIn] Running Loop=6 2021-04-20T16:16:00.587Z,1618935360.587 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:16:00.587Z,1618935360.587 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:16:02.605Z,1618935362.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161602.00,A,3648.56807,N,12149.41539,W,9.117,307.88,200421,,,A*71 2021-04-20T16:16:02.607Z,1618935362.607 [NAL9602](INFO): GPS fix at 20210420T161602: (36.809468, -121.823590) 2021-04-20T16:16:02.617Z,1618935362.617 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:16:02.617Z,1618935362.617 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:16:09.462Z,1618935369.462 [NAL9602](INFO): SBD MO Status=0, MOMSN=41325, MT Status=0, MTMSN=0 2021-04-20T16:16:09.462Z,1618935369.462 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:16:18.267Z,1618935378.267 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0012.lzma 2021-04-20T16:16:19.270Z,1618935379.270 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0012.lzma.bak 2021-04-20T16:16:19.270Z,1618935379.270 [DataOverHttps](INFO): SBD MOMSN=15590496 2021-04-20T16:16:40.205Z,1618935400.205 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:17:30.480Z,1618935450.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:18:01.616Z,1618935481.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:18:32.740Z,1618935512.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:19:00.406Z,1618935540.406 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:19:03.864Z,1618935543.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:19:12.126Z,1618935552.126 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:19:23.846Z,1618935563.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:19:27.969Z,1618935567.969 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.323380 2021-04-20T16:19:35.150Z,1618935575.150 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:19:46.522Z,1618935586.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:19:46.535Z,1618935586.535 [NAL9602](INFO): SBD MO Status=1, MOMSN=41326, MT Status=0, MTMSN=0 2021-04-20T16:19:46.584Z,1618935586.584 [NAL9602](INFO): Sent 145 bytes from file Logs/20210420T155525/Express0013.lzma 2021-04-20T16:19:46.584Z,1618935586.584 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:19:58.246Z,1618935598.246 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:19:59.096Z,1618935599.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:20:02.281Z,1618935602.281 [NAL9602](INFO): SBD MO Status=0, MOMSN=41327, MT Status=0, MTMSN=0 2021-04-20T16:20:02.363Z,1618935602.363 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:20:02.363Z,1618935602.363 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:20:02.363Z,1618935602.363 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:20:09.958Z,1618935609.958 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:20:21.330Z,1618935621.330 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T16:20:30.220Z,1618935630.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:20:32.984Z,1618935632.984 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:21:01.344Z,1618935661.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:21:32.468Z,1618935692.468 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:22:03.592Z,1618935723.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:22:34.736Z,1618935754.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:23:05.868Z,1618935785.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:23:37.000Z,1618935817.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:24:08.140Z,1618935848.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:24:39.264Z,1618935879.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:25:02.932Z,1618935902.932 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:25:02.932Z,1618935902.932 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:25:02.932Z,1618935902.932 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:25:02.932Z,1618935902.932 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:25:03.284Z,1618935903.284 [Default:CheckIn:D] Stopped 2021-04-20T16:25:03.284Z,1618935903.284 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:25:03.698Z,1618935903.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.814937 min 2021-04-20T16:25:03.698Z,1618935903.698 [Default:CheckIn:E] Stopped 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn] Stopped 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn](INFO): Running loop #7 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn] Running Loop=7 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:25:03.699Z,1618935903.699 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:25:05.685Z,1618935905.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162505.00,A,3648.55738,N,12149.07087,W,4.918,97.89,200421,,,A*45 2021-04-20T16:25:05.688Z,1618935905.688 [NAL9602](INFO): GPS fix at 20210420T162505: (36.809290, -121.817848) 2021-04-20T16:25:05.714Z,1618935905.714 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:25:05.714Z,1618935905.714 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:25:10.388Z,1618935910.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:25:22.315Z,1618935922.315 [NAL9602](INFO): SBD MO Status=1, MOMSN=41328, MT Status=0, MTMSN=0 2021-04-20T16:25:22.368Z,1618935922.368 [NAL9602](INFO): Sent 72 bytes from file Logs/20210420T155525/Courier0015.lzma 2021-04-20T16:25:22.368Z,1618935922.368 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:25:30.903Z,1618935930.903 [NAL9602](INFO): SBD MO Status=1, MOMSN=41329, MT Status=0, MTMSN=0 2021-04-20T16:25:30.952Z,1618935930.952 [NAL9602](INFO): Sent 141 bytes from file Logs/20210420T155525/Express0016.lzma 2021-04-20T16:25:30.952Z,1618935930.952 [NAL9602](INFO): Packets left to send: 0 2021-04-20T16:25:31.249Z,1618935931.249 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2021-04-20T16:25:31.250Z,1618935931.250 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2021-04-20T16:25:31.253Z,1618935931.253 [BPC1](INFO): Received data from all battery sticks. 2021-04-20T16:25:39.330Z,1618935939.330 [NAL9602](INFO): SBD MO Status=0, MOMSN=41330, MT Status=0, MTMSN=0 2021-04-20T16:25:39.423Z,1618935939.423 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:25:39.423Z,1618935939.423 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:25:39.423Z,1618935939.423 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:25:41.520Z,1618935941.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:26:10.027Z,1618935970.027 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:26:12.645Z,1618935972.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:26:43.768Z,1618936003.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:27:14.892Z,1618936034.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:27:46.016Z,1618936066.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:28:17.148Z,1618936097.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:28:48.284Z,1618936128.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:29:19.416Z,1618936159.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:29:27.876Z,1618936167.876 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002958 2021-04-20T16:30:39.911Z,1618936239.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:30:39.911Z,1618936239.911 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:30:39.911Z,1618936239.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:30:39.912Z,1618936239.912 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:30:40.322Z,1618936240.322 [Default:CheckIn:D] Stopped 2021-04-20T16:30:40.322Z,1618936240.322 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.432231 min 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn:E] Stopped 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn] Stopped 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn](INFO): Running loop #8 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn] Running Loop=8 2021-04-20T16:30:40.734Z,1618936240.734 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:30:40.735Z,1618936240.735 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:30:42.733Z,1618936242.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163042.00,A,3648.46049,N,12148.61829,W,3.713,107.82,200421,,,A*7D 2021-04-20T16:30:42.735Z,1618936242.735 [NAL9602](INFO): GPS fix at 20210420T163042: (36.807675, -121.810305) 2021-04-20T16:30:42.778Z,1618936242.778 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:30:42.778Z,1618936242.778 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:30:52.888Z,1618936252.888 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20210420T155525/Courier0018.lzma 2021-04-20T16:30:53.890Z,1618936253.890 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0018.lzma.bak 2021-04-20T16:30:53.890Z,1618936253.890 [DataOverHttps](INFO): SBD MOMSN=15590545 2021-04-20T16:31:09.434Z,1618936269.434 [NAL9602](INFO): SBD MO Status=0, MOMSN=41331, MT Status=0, MTMSN=0 2021-04-20T16:31:09.435Z,1618936269.435 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:31:09.731Z,1618936269.731 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210420T155525/Express0019.lzma 2021-04-20T16:31:10.734Z,1618936270.734 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0019.lzma.bak 2021-04-20T16:31:10.734Z,1618936270.734 [DataOverHttps](INFO): SBD MOMSN=15590548 2021-04-20T16:31:11.866Z,1618936271.866 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:31:11.866Z,1618936271.866 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:31:11.866Z,1618936271.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:31:40.135Z,1618936300.135 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:36:12.503Z,1618936572.503 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:36:12.503Z,1618936572.503 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:36:12.503Z,1618936572.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:36:12.504Z,1618936572.504 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:36:12.900Z,1618936572.900 [Default:CheckIn:D] Stopped 2021-04-20T16:36:12.900Z,1618936572.900 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:36:13.310Z,1618936573.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.975203 min 2021-04-20T16:36:13.310Z,1618936573.310 [Default:CheckIn:E] Stopped 2021-04-20T16:36:13.310Z,1618936573.310 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:36:13.310Z,1618936573.310 [Default:CheckIn] Stopped 2021-04-20T16:36:13.310Z,1618936573.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:36:13.311Z,1618936573.311 [Default:CheckIn](INFO): Running loop #9 2021-04-20T16:36:13.311Z,1618936573.311 [Default:CheckIn] Running Loop=9 2021-04-20T16:36:13.311Z,1618936573.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:36:13.311Z,1618936573.311 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:36:15.321Z,1618936575.321 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163614.00,A,3648.33478,N,12148.23873,W,1.983,93.60,200421,,,A*40 2021-04-20T16:36:15.323Z,1618936575.323 [NAL9602](INFO): GPS fix at 20210420T163614: (36.805580, -121.803979) 2021-04-20T16:36:15.333Z,1618936575.333 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:36:15.333Z,1618936575.333 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:36:22.939Z,1618936582.939 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0021.lzma 2021-04-20T16:36:23.943Z,1618936583.943 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0021.lzma.bak 2021-04-20T16:36:23.943Z,1618936583.943 [DataOverHttps](INFO): SBD MOMSN=15590565 2021-04-20T16:36:33.515Z,1618936593.515 [NAL9602](INFO): SBD MO Status=2, MOMSN=41332, MT Status=2, MTMSN=0 2021-04-20T16:36:33.515Z,1618936593.515 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T16:36:42.615Z,1618936602.615 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210420T155525/Express0022.lzma 2021-04-20T16:36:43.618Z,1618936603.618 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0022.lzma.bak 2021-04-20T16:36:43.618Z,1618936603.618 [DataOverHttps](INFO): SBD MOMSN=15590568 2021-04-20T16:36:44.903Z,1618936604.903 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:36:44.903Z,1618936604.903 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:36:44.903Z,1618936604.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:36:53.057Z,1618936613.057 [NAL9602](INFO): SBD MO Status=0, MOMSN=41332, MT Status=0, MTMSN=0 2021-04-20T16:36:53.058Z,1618936613.058 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:37:23.725Z,1618936643.725 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:40:47.948Z,1618936847.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-04-20T16:41:45.534Z,1618936905.534 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:41:45.534Z,1618936905.534 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:41:45.534Z,1618936905.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:41:45.535Z,1618936905.535 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:41:45.934Z,1618936905.934 [Default:CheckIn:D] Stopped 2021-04-20T16:41:45.934Z,1618936905.934 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:41:46.332Z,1618936906.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.525765 min 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn:E] Stopped 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn] Stopped 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn](INFO): Running loop #10 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn] Running Loop=10 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:41:46.333Z,1618936906.333 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:41:48.353Z,1618936908.353 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164147.00,A,3648.29944,N,12147.91253,W,5.287,83.77,200421,,,D*48 2021-04-20T16:41:48.355Z,1618936908.355 [NAL9602](INFO): GPS fix at 20210420T164147: (36.804991, -121.798542) 2021-04-20T16:41:48.365Z,1618936908.365 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:41:48.365Z,1618936908.365 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:42:03.293Z,1618936923.293 [NAL9602](INFO): SBD MO Status=0, MOMSN=41333, MT Status=0, MTMSN=0 2021-04-20T16:42:03.293Z,1618936923.293 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:42:13.435Z,1618936933.435 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0024.lzma 2021-04-20T16:42:14.438Z,1618936934.438 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0024.lzma.bak 2021-04-20T16:42:14.438Z,1618936934.438 [DataOverHttps](INFO): SBD MOMSN=15590586 2021-04-20T16:42:30.115Z,1618936950.115 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210420T155525/Express0025.lzma 2021-04-20T16:42:31.118Z,1618936951.118 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0025.lzma.bak 2021-04-20T16:42:31.118Z,1618936951.118 [DataOverHttps](INFO): SBD MOMSN=15590590 2021-04-20T16:42:32.394Z,1618936952.394 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:42:32.394Z,1618936952.394 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:42:32.395Z,1618936952.395 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:42:33.995Z,1618936953.995 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:47:32.858Z,1618937252.858 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:47:32.859Z,1618937252.859 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:47:32.859Z,1618937252.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:47:32.859Z,1618937252.859 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:47:33.253Z,1618937253.253 [Default:CheckIn:D] Stopped 2021-04-20T16:47:33.253Z,1618937253.253 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:47:33.654Z,1618937253.654 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.314412 min 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn:E] Stopped 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn] Stopped 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn](INFO): Running loop #11 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn] Running Loop=11 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:47:33.655Z,1618937253.655 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:47:35.668Z,1618937255.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164735.00,A,3648.41219,N,12147.40610,W,4.646,59.52,200421,,,D*41 2021-04-20T16:47:35.670Z,1618937255.670 [NAL9602](INFO): GPS fix at 20210420T164735: (36.806870, -121.790102) 2021-04-20T16:47:35.680Z,1618937255.680 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:47:35.680Z,1618937255.680 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:47:42.932Z,1618937262.932 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0027.lzma 2021-04-20T16:47:43.930Z,1618937263.930 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0027.lzma.bak 2021-04-20T16:47:43.930Z,1618937263.930 [DataOverHttps](INFO): SBD MOMSN=15590636 2021-04-20T16:47:59.103Z,1618937279.103 [NAL9602](INFO): SBD MO Status=0, MOMSN=41334, MT Status=0, MTMSN=0 2021-04-20T16:47:59.103Z,1618937279.103 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:47:59.655Z,1618937279.655 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210420T155525/Express0028.lzma 2021-04-20T16:48:00.658Z,1618937280.658 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0028.lzma.bak 2021-04-20T16:48:00.658Z,1618937280.658 [DataOverHttps](INFO): SBD MOMSN=15590640 2021-04-20T16:48:01.939Z,1618937281.939 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:48:01.939Z,1618937281.939 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:48:01.939Z,1618937281.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:48:29.807Z,1618937309.807 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:53:02.703Z,1618937582.703 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:53:02.703Z,1618937582.703 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:53:02.703Z,1618937582.703 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:53:02.703Z,1618937582.703 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:53:03.098Z,1618937583.098 [Default:CheckIn:D] Stopped 2021-04-20T16:53:03.098Z,1618937583.098 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:53:03.502Z,1618937583.502 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.811833 min 2021-04-20T16:53:03.502Z,1618937583.502 [Default:CheckIn:E] Stopped 2021-04-20T16:53:03.502Z,1618937583.502 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:53:03.503Z,1618937583.503 [Default:CheckIn] Stopped 2021-04-20T16:53:03.503Z,1618937583.503 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:53:03.503Z,1618937583.503 [Default:CheckIn](INFO): Running loop #12 2021-04-20T16:53:03.503Z,1618937583.503 [Default:CheckIn] Running Loop=12 2021-04-20T16:53:03.503Z,1618937583.503 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:53:03.503Z,1618937583.503 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:53:05.518Z,1618937585.518 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165304.00,A,3648.43736,N,12147.11215,W,4.840,182.21,200421,,,D*72 2021-04-20T16:53:05.521Z,1618937585.521 [NAL9602](INFO): GPS fix at 20210420T165304: (36.807289, -121.785202) 2021-04-20T16:53:05.530Z,1618937585.530 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:53:05.530Z,1618937585.530 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:53:12.023Z,1618937592.023 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210420T155525/Courier0030.lzma 2021-04-20T16:53:13.026Z,1618937593.026 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0030.lzma.bak 2021-04-20T16:53:13.026Z,1618937593.026 [DataOverHttps](INFO): SBD MOMSN=15590657 2021-04-20T16:53:18.439Z,1618937598.439 [NAL9602](INFO): SBD MO Status=0, MOMSN=41335, MT Status=0, MTMSN=0 2021-04-20T16:53:18.440Z,1618937598.440 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:53:28.659Z,1618937608.659 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210420T155525/Express0031.lzma 2021-04-20T16:53:29.662Z,1618937609.662 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0031.lzma.bak 2021-04-20T16:53:29.662Z,1618937609.662 [DataOverHttps](INFO): SBD MOMSN=15590664 2021-04-20T16:53:30.978Z,1618937610.978 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:53:30.978Z,1618937610.978 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:53:30.978Z,1618937610.978 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:53:49.147Z,1618937629.147 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T16:58:31.687Z,1618937911.687 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T16:58:31.687Z,1618937911.687 [Default:CheckIn:C.Wait] Stopped 2021-04-20T16:58:31.687Z,1618937911.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T16:58:31.687Z,1618937911.687 [Default:CheckIn:D] Running Loop=1 2021-04-20T16:58:32.087Z,1618937912.087 [Default:CheckIn:D] Stopped 2021-04-20T16:58:32.087Z,1618937912.087 [Default:CheckIn:E] Running Loop=1 2021-04-20T16:58:32.490Z,1618937912.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.294987 min 2021-04-20T16:58:32.490Z,1618937912.490 [Default:CheckIn:E] Stopped 2021-04-20T16:58:32.490Z,1618937912.490 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T16:58:32.490Z,1618937912.490 [Default:CheckIn] Stopped 2021-04-20T16:58:32.490Z,1618937912.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T16:58:32.491Z,1618937912.491 [Default:CheckIn](INFO): Running loop #13 2021-04-20T16:58:32.491Z,1618937912.491 [Default:CheckIn] Running Loop=13 2021-04-20T16:58:32.491Z,1618937912.491 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T16:58:32.491Z,1618937912.491 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T16:58:34.495Z,1618937914.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165833.00,A,3648.12989,N,12147.21002,W,0.330,261.06,200421,,,D*77 2021-04-20T16:58:34.498Z,1618937914.498 [NAL9602](INFO): GPS fix at 20210420T165833: (36.802165, -121.786834) 2021-04-20T16:58:34.513Z,1618937914.513 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T16:58:34.513Z,1618937914.513 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T16:58:44.016Z,1618937924.016 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0033.lzma 2021-04-20T16:58:45.018Z,1618937925.018 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0033.lzma.bak 2021-04-20T16:58:45.018Z,1618937925.018 [DataOverHttps](INFO): SBD MOMSN=15590713 2021-04-20T16:58:47.505Z,1618937927.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=41336, MT Status=0, MTMSN=0 2021-04-20T16:58:47.505Z,1618937927.505 [NAL9602](INFO): No messages in MT queue 2021-04-20T16:59:04.275Z,1618937944.275 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210420T155525/Express0034.lzma 2021-04-20T16:59:05.278Z,1618937945.278 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0034.lzma.bak 2021-04-20T16:59:05.278Z,1618937945.278 [DataOverHttps](INFO): SBD MOMSN=15590716 2021-04-20T16:59:06.553Z,1618937946.553 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T16:59:06.554Z,1618937946.554 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T16:59:06.554Z,1618937946.554 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T16:59:18.263Z,1618937958.263 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:04:07.240Z,1618938247.240 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:04:07.240Z,1618938247.240 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:04:07.240Z,1618938247.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:04:07.240Z,1618938247.240 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:04:07.641Z,1618938247.641 [Default:CheckIn:D] Stopped 2021-04-20T17:04:07.642Z,1618938247.642 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:04:08.052Z,1618938248.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.887557 min 2021-04-20T17:04:08.052Z,1618938248.052 [Default:CheckIn:E] Stopped 2021-04-20T17:04:08.052Z,1618938248.052 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:04:08.052Z,1618938248.052 [Default:CheckIn] Stopped 2021-04-20T17:04:08.052Z,1618938248.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:04:08.053Z,1618938248.053 [Default:CheckIn](INFO): Running loop #14 2021-04-20T17:04:08.053Z,1618938248.053 [Default:CheckIn] Running Loop=14 2021-04-20T17:04:08.053Z,1618938248.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:04:08.053Z,1618938248.053 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:04:10.057Z,1618938250.057 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170409.00,A,3648.13205,N,12147.20909,W,0.019,261.06,200421,,,D*73 2021-04-20T17:04:10.060Z,1618938250.060 [NAL9602](INFO): GPS fix at 20210420T170409: (36.802201, -121.786818) 2021-04-20T17:04:10.085Z,1618938250.085 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:04:10.086Z,1618938250.086 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:04:17.031Z,1618938257.031 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0036.lzma 2021-04-20T17:04:18.034Z,1618938258.034 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0036.lzma.bak 2021-04-20T17:04:18.034Z,1618938258.034 [DataOverHttps](INFO): SBD MOMSN=15590735 2021-04-20T17:04:29.457Z,1618938269.457 [NAL9602](INFO): SBD MO Status=2, MOMSN=41337, MT Status=2, MTMSN=0 2021-04-20T17:04:29.457Z,1618938269.457 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:04:34.983Z,1618938274.983 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210420T155525/Express0037.lzma 2021-04-20T17:04:35.986Z,1618938275.986 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0037.lzma.bak 2021-04-20T17:04:35.986Z,1618938275.986 [DataOverHttps](INFO): SBD MOMSN=15590738 2021-04-20T17:04:37.143Z,1618938277.143 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:04:37.143Z,1618938277.143 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:04:37.143Z,1618938277.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:04:46.497Z,1618938286.497 [NAL9602](INFO): SBD MO Status=2, MOMSN=41337, MT Status=2, MTMSN=0 2021-04-20T17:04:46.498Z,1618938286.498 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:05:02.249Z,1618938302.249 [NAL9602](INFO): SBD MO Status=2, MOMSN=41337, MT Status=2, MTMSN=0 2021-04-20T17:05:02.250Z,1618938302.250 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:05:36.996Z,1618938336.996 [NAL9602](INFO): SBD MO Status=0, MOMSN=41337, MT Status=0, MTMSN=0 2021-04-20T17:05:36.996Z,1618938336.996 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:06:07.695Z,1618938367.695 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:09:37.919Z,1618938577.919 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:09:37.920Z,1618938577.920 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:09:37.920Z,1618938577.920 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:09:37.920Z,1618938577.920 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:09:38.338Z,1618938578.338 [Default:CheckIn:D] Stopped 2021-04-20T17:09:38.338Z,1618938578.338 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.399162 min 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn:E] Stopped 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn] Stopped 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn](INFO): Running loop #15 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn] Running Loop=15 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:09:38.725Z,1618938578.725 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:09:40.743Z,1618938580.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170940.00,A,3648.13822,N,12147.20449,W,0.058,261.06,200421,,,A*75 2021-04-20T17:09:40.745Z,1618938580.745 [NAL9602](INFO): GPS fix at 20210420T170940: (36.802304, -121.786742) 2021-04-20T17:09:40.759Z,1618938580.759 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:09:40.759Z,1618938580.759 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:09:47.675Z,1618938587.675 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20210420T155525/Courier0039.lzma 2021-04-20T17:09:48.678Z,1618938588.678 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0039.lzma.bak 2021-04-20T17:09:48.678Z,1618938588.678 [DataOverHttps](INFO): SBD MOMSN=15590760 2021-04-20T17:10:04.291Z,1618938604.291 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210420T155525/Express0040.lzma 2021-04-20T17:10:05.294Z,1618938605.294 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0040.lzma.bak 2021-04-20T17:10:05.294Z,1618938605.294 [DataOverHttps](INFO): SBD MOMSN=15590763 2021-04-20T17:10:06.597Z,1618938606.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=41338, MT Status=2, MTMSN=0 2021-04-20T17:10:06.597Z,1618938606.597 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:10:06.607Z,1618938606.607 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:10:06.607Z,1618938606.607 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:10:06.607Z,1618938606.607 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:10:35.677Z,1618938635.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=41338, MT Status=0, MTMSN=0 2021-04-20T17:10:35.677Z,1618938635.677 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:11:06.441Z,1618938666.441 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:12:37.346Z,1618938757.346 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:12:37.346Z,1618938757.346 [AHRS_M2] Data Fault, FailCount= 1 2021-04-20T17:12:37.346Z,1618938757.346 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:12:37.366Z,1618938757.366 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:12:37.728Z,1618938757.728 [AHRS_M2](INFO): Powering down 2021-04-20T17:12:38.563Z,1618938758.563 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:12:38.563Z,1618938758.563 [AHRS_M2] No Fault, FailCount= 1 2021-04-20T17:12:38.940Z,1618938758.940 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:12:52.352Z,1618938772.352 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:12:52.352Z,1618938772.352 [AHRS_M2] Data Fault, FailCount= 2 2021-04-20T17:12:52.352Z,1618938772.352 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:12:52.371Z,1618938772.371 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:12:52.732Z,1618938772.732 [AHRS_M2](INFO): Powering down 2021-04-20T17:12:53.565Z,1618938773.565 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:12:53.565Z,1618938773.565 [AHRS_M2] No Fault, FailCount= 2 2021-04-20T17:12:53.950Z,1618938773.950 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:13:07.302Z,1618938787.302 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:13:07.302Z,1618938787.302 [AHRS_M2] Data Fault, FailCount= 3 2021-04-20T17:13:07.302Z,1618938787.302 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:13:07.326Z,1618938787.326 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:13:07.680Z,1618938787.680 [AHRS_M2](INFO): Powering down 2021-04-20T17:13:08.515Z,1618938788.515 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:13:08.515Z,1618938788.515 [AHRS_M2] No Fault, FailCount= 3 2021-04-20T17:13:08.892Z,1618938788.892 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:13:22.240Z,1618938802.240 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:13:22.240Z,1618938802.240 [AHRS_M2] Data Fault, FailCount= 4 2021-04-20T17:13:22.240Z,1618938802.240 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:13:22.286Z,1618938802.286 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:13:22.630Z,1618938802.630 [AHRS_M2](INFO): Powering down 2021-04-20T17:13:23.452Z,1618938803.452 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:13:23.452Z,1618938803.452 [AHRS_M2] No Fault, FailCount= 4 2021-04-20T17:13:23.840Z,1618938803.840 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:13:37.192Z,1618938817.192 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:13:37.192Z,1618938817.192 [AHRS_M2] Data Fault, FailCount= 5 2021-04-20T17:13:37.192Z,1618938817.192 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:13:37.242Z,1618938817.242 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:13:37.243Z,1618938817.243 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2021-04-20T17:13:37.576Z,1618938817.576 [AHRS_M2](INFO): Powering down 2021-04-20T17:13:37.671Z,1618938817.671 [CommandLine](FAULT): Scheduling is paused 2021-04-20T17:13:37.672Z,1618938817.672 [CBIT](INFO): Critical error at 20210420T171337 2021-04-20T17:15:07.326Z,1618938907.326 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:15:07.326Z,1618938907.326 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:15:07.326Z,1618938907.326 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:15:07.326Z,1618938907.326 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:15:07.731Z,1618938907.731 [Default:CheckIn:D] Stopped 2021-04-20T17:15:07.731Z,1618938907.731 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:15:08.139Z,1618938908.139 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.889038 min 2021-04-20T17:15:08.139Z,1618938908.139 [Default:CheckIn:E] Stopped 2021-04-20T17:15:08.139Z,1618938908.139 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:15:08.139Z,1618938908.139 [Default:CheckIn] Stopped 2021-04-20T17:15:08.139Z,1618938908.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:15:08.139Z,1618938908.139 [Default:CheckIn](INFO): Running loop #16 2021-04-20T17:15:08.140Z,1618938908.140 [Default:CheckIn] Running Loop=16 2021-04-20T17:15:08.140Z,1618938908.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:15:08.140Z,1618938908.140 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:15:10.151Z,1618938910.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171509.00,A,3648.13874,N,12147.20485,W,0.058,261.06,200421,,,A*76 2021-04-20T17:15:10.153Z,1618938910.153 [NAL9602](INFO): GPS fix at 20210420T171509: (36.802312, -121.786748) 2021-04-20T17:15:10.175Z,1618938910.175 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:15:10.175Z,1618938910.175 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:15:15.819Z,1618938915.819 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20210420T155525/Courier0042.lzma 2021-04-20T17:15:16.822Z,1618938916.822 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0042.lzma.bak 2021-04-20T17:15:16.822Z,1618938916.822 [DataOverHttps](INFO): SBD MOMSN=15590782 2021-04-20T17:15:32.296Z,1618938932.296 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20210420T155525/Express0043.lzma 2021-04-20T17:15:33.298Z,1618938933.298 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0043.lzma.bak 2021-04-20T17:15:33.298Z,1618938933.298 [DataOverHttps](INFO): SBD MOMSN=15590786 2021-04-20T17:15:34.410Z,1618938934.410 [NAL9602](INFO): SBD MO Status=2, MOMSN=41339, MT Status=2, MTMSN=0 2021-04-20T17:15:34.411Z,1618938934.411 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:15:34.420Z,1618938934.420 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:15:34.420Z,1618938934.420 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:15:34.420Z,1618938934.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:15:36.844Z,1618938936.844 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:15:36.844Z,1618938936.844 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-20T17:15:36.844Z,1618938936.844 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:15:36.869Z,1618938936.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:15:37.284Z,1618938937.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:15:37.284Z,1618938937.284 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0 2021-04-20T17:17:37.707Z,1618939057.707 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:17:37.708Z,1618939057.708 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-20T17:17:37.708Z,1618939057.708 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:17:37.729Z,1618939057.729 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:17:38.145Z,1618939058.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:17:38.145Z,1618939058.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-04-20T17:18:37.487Z,1618939117.487 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:18:37.487Z,1618939117.487 [AHRS_M2] No Fault, FailCount= 5 2021-04-20T17:18:37.876Z,1618939117.876 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:18:41.248Z,1618939121.248 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:18:51.688Z,1618939131.688 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:18:51.688Z,1618939131.688 [AHRS_M2] Data Fault, FailCount= 1 2021-04-20T17:18:51.688Z,1618939131.688 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:18:51.717Z,1618939131.717 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:18:52.068Z,1618939132.068 [AHRS_M2](INFO): Powering down 2021-04-20T17:18:52.902Z,1618939132.902 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:18:52.902Z,1618939132.902 [AHRS_M2] No Fault, FailCount= 1 2021-04-20T17:18:53.277Z,1618939133.277 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:19:06.632Z,1618939146.632 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:19:06.632Z,1618939146.632 [AHRS_M2] Data Fault, FailCount= 2 2021-04-20T17:19:06.632Z,1618939146.632 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:19:06.659Z,1618939146.659 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:19:07.016Z,1618939147.016 [AHRS_M2](INFO): Powering down 2021-04-20T17:19:07.850Z,1618939147.850 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:19:07.850Z,1618939147.850 [AHRS_M2] No Fault, FailCount= 2 2021-04-20T17:19:08.228Z,1618939148.228 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:19:21.584Z,1618939161.584 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:19:21.584Z,1618939161.584 [AHRS_M2] Data Fault, FailCount= 3 2021-04-20T17:19:21.584Z,1618939161.584 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:19:21.603Z,1618939161.603 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:19:21.965Z,1618939161.965 [AHRS_M2](INFO): Powering down 2021-04-20T17:19:22.798Z,1618939162.798 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:19:22.798Z,1618939162.798 [AHRS_M2] No Fault, FailCount= 3 2021-04-20T17:19:23.177Z,1618939163.177 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:19:26.504Z,1618939166.504 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:19:36.932Z,1618939176.932 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:19:36.932Z,1618939176.932 [AHRS_M2] Data Fault, FailCount= 4 2021-04-20T17:19:36.932Z,1618939176.932 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:19:36.979Z,1618939176.979 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:19:37.317Z,1618939177.317 [AHRS_M2](INFO): Powering down 2021-04-20T17:19:38.147Z,1618939178.147 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:19:38.147Z,1618939178.147 [AHRS_M2] No Fault, FailCount= 4 2021-04-20T17:19:38.529Z,1618939178.529 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:19:51.880Z,1618939191.880 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-20T17:19:51.880Z,1618939191.880 [AHRS_M2] Data Fault, FailCount= 5 2021-04-20T17:19:51.880Z,1618939191.880 [AHRS_M2](ERROR): Data Fault 2021-04-20T17:19:51.899Z,1618939191.899 [CBIT](ERROR): Data Fault in component: AHRS_M2 2021-04-20T17:19:51.900Z,1618939191.900 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2021-04-20T17:19:52.269Z,1618939192.269 [AHRS_M2](INFO): Powering down 2021-04-20T17:19:52.402Z,1618939192.402 [CBIT](INFO): Critical error at 20210420T171951 2021-04-20T17:20:12.469Z,1618939212.469 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-04-20T17:20:35.095Z,1618939235.095 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:20:35.095Z,1618939235.095 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:20:35.095Z,1618939235.095 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:20:35.095Z,1618939235.095 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:20:35.505Z,1618939235.505 [Default:CheckIn:D] Stopped 2021-04-20T17:20:35.505Z,1618939235.505 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:20:35.907Z,1618939235.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.351945 min 2021-04-20T17:20:35.907Z,1618939235.907 [Default:CheckIn:E] Stopped 2021-04-20T17:20:35.907Z,1618939235.907 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:20:35.907Z,1618939235.907 [Default:CheckIn] Stopped 2021-04-20T17:20:35.907Z,1618939235.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:20:35.940Z,1618939235.940 [Default:CheckIn](INFO): Running loop #17 2021-04-20T17:20:35.940Z,1618939235.940 [Default:CheckIn] Running Loop=17 2021-04-20T17:20:35.940Z,1618939235.940 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:20:35.940Z,1618939235.940 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:20:37.920Z,1618939237.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172037.00,A,3648.13811,N,12147.20726,W,0.078,261.06,200421,,,A*76 2021-04-20T17:20:37.922Z,1618939237.922 [NAL9602](INFO): GPS fix at 20210420T172037: (36.802302, -121.786788) 2021-04-20T17:20:37.931Z,1618939237.931 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:20:37.931Z,1618939237.931 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:20:46.515Z,1618939246.515 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20210420T155525/Courier0045.lzma 2021-04-20T17:20:47.518Z,1618939247.518 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0045.lzma.bak 2021-04-20T17:20:47.518Z,1618939247.518 [DataOverHttps](INFO): SBD MOMSN=15590811 2021-04-20T17:21:03.184Z,1618939263.184 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20210420T155525/Express0046.lzma 2021-04-20T17:21:04.186Z,1618939264.186 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0046.lzma.bak 2021-04-20T17:21:04.186Z,1618939264.186 [DataOverHttps](INFO): SBD MOMSN=15590815 2021-04-20T17:21:05.451Z,1618939265.451 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:21:05.451Z,1618939265.451 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:21:05.451Z,1618939265.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:21:10.289Z,1618939270.289 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:21:51.910Z,1618939311.910 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:21:51.910Z,1618939311.910 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-20T17:21:51.910Z,1618939311.910 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:21:51.922Z,1618939311.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:21:52.318Z,1618939312.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:21:52.318Z,1618939312.318 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0 2021-04-20T17:23:52.767Z,1618939432.767 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:23:52.768Z,1618939432.768 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-20T17:23:52.768Z,1618939432.768 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:23:52.779Z,1618939432.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:23:53.189Z,1618939433.189 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:23:53.189Z,1618939433.189 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-04-20T17:24:52.231Z,1618939492.231 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:24:52.231Z,1618939492.231 [AHRS_M2] No Fault, FailCount= 5 2021-04-20T17:24:52.622Z,1618939492.622 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:24:56.176Z,1618939496.176 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:56.580Z,1618939496.580 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:57.004Z,1618939497.004 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:57.408Z,1618939497.408 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:57.820Z,1618939497.820 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:58.236Z,1618939498.236 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:58.648Z,1618939498.648 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:59.060Z,1618939499.060 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:59.252Z,1618939499.252 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:24:59.824Z,1618939499.824 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:00.232Z,1618939500.232 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:00.652Z,1618939500.652 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:01.076Z,1618939501.076 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:01.488Z,1618939501.488 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:01.904Z,1618939501.904 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:02.320Z,1618939502.320 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:02.488Z,1618939502.488 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:02.848Z,1618939502.848 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:03.236Z,1618939503.236 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:03.652Z,1618939503.652 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:04.284Z,1618939504.284 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:04.476Z,1618939504.476 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:04.872Z,1618939504.872 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:05.500Z,1618939505.500 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:05.500Z,1618939505.500 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:25:05.500Z,1618939505.500 [AHRS_M2] Communications Fault, FailCount= 1 2021-04-20T17:25:05.500Z,1618939505.500 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:25:05.559Z,1618939505.559 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:25:05.600Z,1618939505.600 [AHRS_M2](INFO): Powering down 2021-04-20T17:25:06.409Z,1618939506.409 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:25:06.409Z,1618939506.409 [AHRS_M2] No Fault, FailCount= 1 2021-04-20T17:25:06.789Z,1618939506.789 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:25:10.348Z,1618939510.348 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:10.556Z,1618939510.556 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:11.144Z,1618939511.144 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:11.340Z,1618939511.340 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:11.736Z,1618939511.736 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:12.144Z,1618939512.144 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:12.548Z,1618939512.548 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:12.948Z,1618939512.948 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:13.348Z,1618939513.348 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:13.760Z,1618939513.760 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:14.164Z,1618939514.164 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:14.564Z,1618939514.564 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:14.968Z,1618939514.968 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:15.604Z,1618939515.604 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:15.792Z,1618939515.792 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:16.172Z,1618939516.172 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:16.576Z,1618939516.576 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:16.992Z,1618939516.992 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:17.396Z,1618939517.396 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:17.796Z,1618939517.796 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:18.200Z,1618939518.200 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:18.608Z,1618939518.608 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:19.012Z,1618939519.012 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:19.396Z,1618939519.396 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:19.812Z,1618939519.812 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:19.812Z,1618939519.812 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:25:19.812Z,1618939519.812 [AHRS_M2] Communications Fault, FailCount= 2 2021-04-20T17:25:19.812Z,1618939519.812 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:25:19.847Z,1618939519.847 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:25:20.121Z,1618939520.121 [AHRS_M2](INFO): Powering down 2021-04-20T17:25:20.953Z,1618939520.953 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:25:20.953Z,1618939520.953 [AHRS_M2] No Fault, FailCount= 2 2021-04-20T17:25:21.336Z,1618939521.336 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:25:24.668Z,1618939524.668 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:25.072Z,1618939525.072 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:25.468Z,1618939525.468 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:25.868Z,1618939525.868 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:26.276Z,1618939526.276 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:26.680Z,1618939526.680 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:27.088Z,1618939527.088 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:27.488Z,1618939527.488 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:27.884Z,1618939527.884 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:28.300Z,1618939528.300 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:28.700Z,1618939528.700 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:29.108Z,1618939529.108 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:29.512Z,1618939529.512 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:29.912Z,1618939529.912 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:30.316Z,1618939530.316 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:30.728Z,1618939530.728 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:31.124Z,1618939531.124 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:31.536Z,1618939531.536 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:31.936Z,1618939531.936 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:32.336Z,1618939532.336 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:32.736Z,1618939532.736 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:33.140Z,1618939533.140 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:33.552Z,1618939533.552 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:33.960Z,1618939533.960 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:34.360Z,1618939534.360 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:34.360Z,1618939534.360 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:25:34.360Z,1618939534.360 [AHRS_M2] Communications Fault, FailCount= 3 2021-04-20T17:25:34.360Z,1618939534.360 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:25:34.384Z,1618939534.384 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:25:34.668Z,1618939534.668 [AHRS_M2](INFO): Powering down 2021-04-20T17:25:35.509Z,1618939535.509 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:25:35.509Z,1618939535.509 [AHRS_M2] No Fault, FailCount= 3 2021-04-20T17:25:35.880Z,1618939535.880 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:25:39.200Z,1618939539.200 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:39.608Z,1618939539.608 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:40.016Z,1618939540.016 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:40.408Z,1618939540.408 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:40.824Z,1618939540.824 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:41.228Z,1618939541.228 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:41.632Z,1618939541.632 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:42.036Z,1618939542.036 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:42.448Z,1618939542.448 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:42.844Z,1618939542.844 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:43.248Z,1618939543.248 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:43.656Z,1618939543.656 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:44.060Z,1618939544.060 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:44.464Z,1618939544.464 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:44.864Z,1618939544.864 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:45.260Z,1618939545.260 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:45.668Z,1618939545.668 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:46.076Z,1618939546.076 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:46.484Z,1618939546.484 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:46.880Z,1618939546.880 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:47.280Z,1618939547.280 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:47.692Z,1618939547.692 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:48.096Z,1618939548.096 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:48.504Z,1618939548.504 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:48.892Z,1618939548.892 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:48.892Z,1618939548.892 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:25:48.892Z,1618939548.892 [AHRS_M2] Communications Fault, FailCount= 4 2021-04-20T17:25:48.892Z,1618939548.892 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:25:48.931Z,1618939548.931 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:25:49.210Z,1618939549.210 [AHRS_M2](INFO): Powering down 2021-04-20T17:25:50.057Z,1618939550.057 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:25:50.057Z,1618939550.057 [AHRS_M2] No Fault, FailCount= 4 2021-04-20T17:25:50.428Z,1618939550.428 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:25:53.756Z,1618939553.756 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:53.763Z,1618939553.763 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:25:53.763Z,1618939553.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-04-20T17:25:53.763Z,1618939553.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:25:53.774Z,1618939553.774 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:25:54.160Z,1618939554.160 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:54.192Z,1618939554.192 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:25:54.192Z,1618939554.192 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-04-20T17:25:54.560Z,1618939554.560 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:54.968Z,1618939554.968 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:55.364Z,1618939555.364 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:55.780Z,1618939555.780 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:56.180Z,1618939556.180 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:56.572Z,1618939556.572 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:56.988Z,1618939556.988 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:57.388Z,1618939557.388 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:57.776Z,1618939557.776 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:58.196Z,1618939558.196 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:58.608Z,1618939558.608 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:59.008Z,1618939559.008 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:59.408Z,1618939559.408 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:25:59.816Z,1618939559.816 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:00.220Z,1618939560.220 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:00.624Z,1618939560.624 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:01.028Z,1618939561.028 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:01.424Z,1618939561.424 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:01.832Z,1618939561.832 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:02.240Z,1618939562.240 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:02.636Z,1618939562.636 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:03.036Z,1618939563.036 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:03.448Z,1618939563.448 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:26:03.448Z,1618939563.448 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:26:03.448Z,1618939563.448 [AHRS_M2] Communications Fault, FailCount= 5 2021-04-20T17:26:03.448Z,1618939563.448 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:26:03.485Z,1618939563.485 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:26:03.486Z,1618939563.486 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2021-04-20T17:26:03.753Z,1618939563.753 [AHRS_M2](INFO): Powering down 2021-04-20T17:26:03.847Z,1618939563.847 [CBIT](INFO): Critical error at 20210420T172603 2021-04-20T17:26:06.194Z,1618939566.194 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:26:06.194Z,1618939566.194 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:26:06.194Z,1618939566.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:26:06.194Z,1618939566.194 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:26:06.600Z,1618939566.600 [Default:CheckIn:D] Stopped 2021-04-20T17:26:06.600Z,1618939566.600 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:26:07.003Z,1618939567.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.870199 min 2021-04-20T17:26:07.003Z,1618939567.003 [Default:CheckIn:E] Stopped 2021-04-20T17:26:07.003Z,1618939567.003 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:26:07.003Z,1618939567.003 [Default:CheckIn] Stopped 2021-04-20T17:26:07.003Z,1618939567.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:26:07.003Z,1618939567.003 [Default:CheckIn](INFO): Running loop #18 2021-04-20T17:26:07.004Z,1618939567.004 [Default:CheckIn] Running Loop=18 2021-04-20T17:26:07.004Z,1618939567.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:26:07.004Z,1618939567.004 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:26:09.016Z,1618939569.016 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172608.00,A,3648.13759,N,12147.20453,W,0.058,261.06,200421,,,D*79 2021-04-20T17:26:09.018Z,1618939569.018 [NAL9602](INFO): GPS fix at 20210420T172608: (36.802293, -121.786742) 2021-04-20T17:26:09.042Z,1618939569.042 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:26:09.042Z,1618939569.042 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:26:14.527Z,1618939574.527 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20210420T155525/Courier0048.lzma 2021-04-20T17:26:15.530Z,1618939575.530 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0048.lzma.bak 2021-04-20T17:26:15.530Z,1618939575.530 [DataOverHttps](INFO): SBD MOMSN=15590841 2021-04-20T17:26:30.732Z,1618939590.732 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20210420T155525/Express0049.lzma 2021-04-20T17:26:31.734Z,1618939591.734 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0049.lzma.bak 2021-04-20T17:26:31.734Z,1618939591.734 [DataOverHttps](INFO): SBD MOMSN=15590846 2021-04-20T17:26:32.881Z,1618939592.881 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:26:32.881Z,1618939592.881 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:26:32.881Z,1618939592.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:26:37.749Z,1618939597.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:26:39.805Z,1618939599.805 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-04-20T17:26:39.888Z,1618939599.888 [NAL9602](ERROR): received: +CSQ:0 OK339, 2, 0, 0, 0 OK 2021-04-20T17:26:49.057Z,1618939609.057 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:27:00.369Z,1618939620.369 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:27:11.677Z,1618939631.677 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:27:22.993Z,1618939642.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:27:34.713Z,1618939654.713 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:27:46.440Z,1618939666.440 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:27:54.920Z,1618939674.920 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:27:54.920Z,1618939674.920 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-20T17:27:54.920Z,1618939674.920 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:27:54.932Z,1618939674.932 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:27:55.336Z,1618939675.336 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:27:55.336Z,1618939675.336 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-04-20T17:27:58.145Z,1618939678.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-04-20T17:29:55.764Z,1618939795.764 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:29:55.764Z,1618939795.764 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-04-20T17:29:55.764Z,1618939795.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:29:55.775Z,1618939795.775 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:29:56.189Z,1618939796.189 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:29:56.189Z,1618939796.189 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-04-20T17:30:34.547Z,1618939834.547 [NAL9602](INFO): SBD MO Status=2, MOMSN=41339, MT Status=2, MTMSN=0 2021-04-20T17:30:34.547Z,1618939834.547 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:31:03.755Z,1618939863.755 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:31:03.755Z,1618939863.755 [AHRS_M2] No Fault, FailCount= 5 2021-04-20T17:31:04.120Z,1618939864.120 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:31:07.672Z,1618939867.672 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:08.104Z,1618939868.104 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:08.552Z,1618939868.552 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:08.988Z,1618939868.988 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:09.408Z,1618939869.408 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:09.836Z,1618939869.836 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:10.236Z,1618939870.236 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:10.672Z,1618939870.672 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:11.100Z,1618939871.100 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:11.105Z,1618939871.105 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-04-20T17:31:11.520Z,1618939871.520 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:11.928Z,1618939871.928 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:12.340Z,1618939872.340 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:12.760Z,1618939872.760 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:13.172Z,1618939873.172 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:13.584Z,1618939873.584 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:13.996Z,1618939873.996 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:14.412Z,1618939874.412 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:14.832Z,1618939874.832 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:15.244Z,1618939875.244 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:15.748Z,1618939875.748 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:16.172Z,1618939876.172 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:16.588Z,1618939876.588 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:17.004Z,1618939877.004 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:17.004Z,1618939877.004 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:31:17.004Z,1618939877.004 [AHRS_M2] Communications Fault, FailCount= 1 2021-04-20T17:31:17.004Z,1618939877.004 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:31:17.029Z,1618939877.029 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:31:17.097Z,1618939877.097 [AHRS_M2](INFO): Powering down 2021-04-20T17:31:17.909Z,1618939877.909 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:31:17.909Z,1618939877.909 [AHRS_M2] No Fault, FailCount= 1 2021-04-20T17:31:18.293Z,1618939878.293 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:31:21.852Z,1618939881.852 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:22.268Z,1618939882.268 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:22.680Z,1618939882.680 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:23.096Z,1618939883.096 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:23.500Z,1618939883.500 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:23.920Z,1618939883.920 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:24.336Z,1618939884.336 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:24.756Z,1618939884.756 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:25.172Z,1618939885.172 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:25.592Z,1618939885.592 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:25.996Z,1618939885.996 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:26.408Z,1618939886.408 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:26.824Z,1618939886.824 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:27.240Z,1618939887.240 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:27.664Z,1618939887.664 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:28.080Z,1618939888.080 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:28.496Z,1618939888.496 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:28.916Z,1618939888.916 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:29.328Z,1618939889.328 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:29.728Z,1618939889.728 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:30.192Z,1618939890.192 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:30.608Z,1618939890.608 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:31.024Z,1618939891.024 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:31.440Z,1618939891.440 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:31.440Z,1618939891.440 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:31:31.440Z,1618939891.440 [AHRS_M2] Communications Fault, FailCount= 2 2021-04-20T17:31:31.440Z,1618939891.440 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:31:31.464Z,1618939891.464 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:31:31.529Z,1618939891.529 [AHRS_M2](INFO): Powering down 2021-04-20T17:31:32.324Z,1618939892.324 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:31:32.324Z,1618939892.324 [AHRS_M2] No Fault, FailCount= 2 2021-04-20T17:31:32.709Z,1618939892.709 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:31:33.544Z,1618939893.544 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:31:33.544Z,1618939893.544 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:31:33.544Z,1618939893.544 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:31:33.545Z,1618939893.545 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:31:33.964Z,1618939893.964 [Default:CheckIn:D] Stopped 2021-04-20T17:31:33.964Z,1618939893.964 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:31:34.354Z,1618939894.354 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.326261 min 2021-04-20T17:31:34.354Z,1618939894.354 [Default:CheckIn:E] Stopped 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn] Stopped 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn](INFO): Running loop #19 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn] Running Loop=19 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:31:34.355Z,1618939894.355 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:31:36.264Z,1618939896.264 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:36.708Z,1618939896.708 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:36.719Z,1618939896.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173136.00,A,3648.13756,N,12147.20657,W,0.019,261.06,200421,,,D*7E 2021-04-20T17:31:36.721Z,1618939896.721 [NAL9602](INFO): GPS fix at 20210420T173136: (36.802293, -121.786776) 2021-04-20T17:31:36.730Z,1618939896.730 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:31:36.730Z,1618939896.730 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:31:36.908Z,1618939896.908 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:37.496Z,1618939897.496 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:37.908Z,1618939897.908 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:38.320Z,1618939898.320 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:38.736Z,1618939898.736 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:39.180Z,1618939899.180 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:39.604Z,1618939899.604 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:40.024Z,1618939900.024 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:40.424Z,1618939900.424 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:40.620Z,1618939900.620 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:41.228Z,1618939901.228 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:41.624Z,1618939901.624 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:41.812Z,1618939901.812 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:42.440Z,1618939902.440 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:42.616Z,1618939902.616 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:42.751Z,1618939902.751 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20210420T155525/Courier0051.lzma 2021-04-20T17:31:43.244Z,1618939903.244 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:43.428Z,1618939903.428 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:43.756Z,1618939903.756 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0051.lzma.bak 2021-04-20T17:31:43.756Z,1618939903.756 [DataOverHttps](INFO): SBD MOMSN=15590868 2021-04-20T17:31:44.052Z,1618939904.052 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:44.464Z,1618939904.464 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:44.660Z,1618939904.660 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:45.284Z,1618939905.284 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:45.468Z,1618939905.468 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:46.088Z,1618939906.088 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:46.088Z,1618939906.088 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:31:46.088Z,1618939906.088 [AHRS_M2] Communications Fault, FailCount= 3 2021-04-20T17:31:46.088Z,1618939906.088 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:31:46.132Z,1618939906.132 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:31:46.176Z,1618939906.176 [AHRS_M2](INFO): Powering down 2021-04-20T17:31:47.042Z,1618939907.042 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:31:47.042Z,1618939907.042 [AHRS_M2] No Fault, FailCount= 3 2021-04-20T17:31:47.384Z,1618939907.384 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:31:50.708Z,1618939910.708 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:51.104Z,1618939911.104 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:51.524Z,1618939911.524 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:51.920Z,1618939911.920 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:52.320Z,1618939912.320 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:52.724Z,1618939912.724 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:53.132Z,1618939913.132 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:53.540Z,1618939913.540 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:53.948Z,1618939913.948 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:54.352Z,1618939914.352 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:54.972Z,1618939914.972 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:55.388Z,1618939915.388 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:55.580Z,1618939915.580 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:55.972Z,1618939915.972 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:56.388Z,1618939916.388 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:56.777Z,1618939916.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:56.807Z,1618939916.807 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:31:56.808Z,1618939916.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-20T17:31:56.808Z,1618939916.808 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:31:56.862Z,1618939916.862 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:31:57.404Z,1618939917.404 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:57.437Z,1618939917.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:31:57.437Z,1618939917.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-04-20T17:31:57.592Z,1618939917.592 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:58.216Z,1618939918.216 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:58.404Z,1618939918.404 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:58.804Z,1618939918.804 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:59.132Z,1618939919.132 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20210420T155525/Express0052.lzma 2021-04-20T17:31:59.208Z,1618939919.208 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:31:59.608Z,1618939919.608 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:00.134Z,1618939920.134 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0052.lzma.bak 2021-04-20T17:32:00.134Z,1618939920.134 [DataOverHttps](INFO): SBD MOMSN=15590873 2021-04-20T17:32:00.232Z,1618939920.232 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:00.232Z,1618939920.232 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:32:00.232Z,1618939920.232 [AHRS_M2] Communications Fault, FailCount= 4 2021-04-20T17:32:00.232Z,1618939920.232 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:32:00.302Z,1618939920.302 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:32:00.340Z,1618939920.340 [AHRS_M2](INFO): Powering down 2021-04-20T17:32:01.226Z,1618939921.226 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:32:01.226Z,1618939921.226 [AHRS_M2] No Fault, FailCount= 4 2021-04-20T17:32:01.533Z,1618939921.533 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:32:01.550Z,1618939921.550 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:32:01.550Z,1618939921.550 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:32:01.550Z,1618939921.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:32:04.856Z,1618939924.856 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:05.260Z,1618939925.260 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:05.664Z,1618939925.664 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:06.296Z,1618939926.296 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:06.712Z,1618939926.712 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:06.896Z,1618939926.896 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:07.284Z,1618939927.284 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:07.920Z,1618939927.920 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:08.104Z,1618939928.104 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:08.500Z,1618939928.500 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:08.900Z,1618939928.900 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:09.312Z,1618939929.312 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:09.322Z,1618939929.322 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:32:09.708Z,1618939929.708 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:10.112Z,1618939930.112 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:10.744Z,1618939930.744 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:10.936Z,1618939930.936 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:11.324Z,1618939931.324 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:11.732Z,1618939931.732 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:12.136Z,1618939932.136 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:12.544Z,1618939932.544 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:13.168Z,1618939933.168 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:13.420Z,1618939933.420 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:13.780Z,1618939933.780 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:14.188Z,1618939934.188 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:14.592Z,1618939934.592 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-20T17:32:14.592Z,1618939934.592 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-04-20T17:32:14.592Z,1618939934.592 [AHRS_M2] Communications Fault, FailCount= 5 2021-04-20T17:32:14.592Z,1618939934.592 [AHRS_M2](ERROR): Communications Fault 2021-04-20T17:32:14.658Z,1618939934.658 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-04-20T17:32:14.659Z,1618939934.659 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2021-04-20T17:32:14.896Z,1618939934.896 [AHRS_M2](INFO): Powering down 2021-04-20T17:32:14.991Z,1618939934.991 [CBIT](INFO): Critical error at 20210420T173214 2021-04-20T17:33:12.759Z,1618939992.759 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2021-04-20T17:33:12.759Z,1618939992.759 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2021-04-20T17:33:12.762Z,1618939992.762 [BPC1](INFO): Received data from all battery sticks. 2021-04-20T17:33:57.979Z,1618940037.979 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:33:57.979Z,1618940037.979 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-04-20T17:33:57.979Z,1618940037.979 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:33:57.990Z,1618940037.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:33:58.388Z,1618940038.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:33:58.388Z,1618940038.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-04-20T17:35:58.830Z,1618940158.830 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-20T17:35:58.830Z,1618940158.830 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-04-20T17:35:58.830Z,1618940158.830 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-20T17:35:58.842Z,1618940158.842 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-20T17:35:59.232Z,1618940159.232 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-20T17:35:59.232Z,1618940159.232 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-04-20T17:37:02.316Z,1618940222.316 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:37:02.316Z,1618940222.316 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:37:02.316Z,1618940222.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:37:02.316Z,1618940222.316 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:37:02.713Z,1618940222.713 [Default:CheckIn:D] Stopped 2021-04-20T17:37:02.713Z,1618940222.713 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:37:03.129Z,1618940223.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.805412 min 2021-04-20T17:37:03.129Z,1618940223.129 [Default:CheckIn:E] Stopped 2021-04-20T17:37:03.129Z,1618940223.129 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:37:03.129Z,1618940223.129 [Default:CheckIn] Stopped 2021-04-20T17:37:03.129Z,1618940223.129 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:37:03.130Z,1618940223.130 [Default:CheckIn](INFO): Running loop #20 2021-04-20T17:37:03.130Z,1618940223.130 [Default:CheckIn] Running Loop=20 2021-04-20T17:37:03.130Z,1618940223.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:37:03.130Z,1618940223.130 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:37:05.135Z,1618940225.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173704.00,A,3648.13728,N,12147.20719,W,0.058,261.06,200421,,,D*7E 2021-04-20T17:37:05.137Z,1618940225.137 [NAL9602](INFO): GPS fix at 20210420T173704: (36.802288, -121.786787) 2021-04-20T17:37:05.145Z,1618940225.145 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:37:05.145Z,1618940225.145 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:37:10.707Z,1618940230.707 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20210420T155525/Courier0054.lzma 2021-04-20T17:37:11.710Z,1618940231.710 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0054.lzma.bak 2021-04-20T17:37:11.710Z,1618940231.710 [DataOverHttps](INFO): SBD MOMSN=15590891 2021-04-20T17:37:14.841Z,1618940234.841 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-04-20T17:37:14.841Z,1618940234.841 [AHRS_M2] No Fault, FailCount= 5 2021-04-20T17:37:15.230Z,1618940235.230 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-20T17:37:18.890Z,1618940238.890 [AHRS_M2](INFO): Failure count cleared after critical for AHRS_M2 2021-04-20T17:37:27.448Z,1618940247.448 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20210420T155525/Express0055.lzma 2021-04-20T17:37:28.450Z,1618940248.450 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0055.lzma.bak 2021-04-20T17:37:28.450Z,1618940248.450 [DataOverHttps](INFO): SBD MOMSN=15590899 2021-04-20T17:37:29.794Z,1618940249.794 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:37:29.795Z,1618940249.795 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:37:29.795Z,1618940249.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:37:35.850Z,1618940255.850 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-04-20T17:37:35.932Z,1618940255.932 [NAL9602](ERROR): received: +CSQ:0 OK339, 2, 0, 0, 0 OK 2021-04-20T17:37:49.985Z,1618940269.985 [NAL9602](INFO): SBD MO Status=0, MOMSN=41339, MT Status=0, MTMSN=0 2021-04-20T17:37:49.985Z,1618940269.985 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:38:20.689Z,1618940300.689 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:42:30.511Z,1618940550.511 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:42:30.511Z,1618940550.511 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:42:30.511Z,1618940550.511 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:42:30.511Z,1618940550.511 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:42:30.909Z,1618940550.909 [Default:CheckIn:D] Stopped 2021-04-20T17:42:30.910Z,1618940550.910 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:42:31.312Z,1618940551.312 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.275358 min 2021-04-20T17:42:31.312Z,1618940551.312 [Default:CheckIn:E] Stopped 2021-04-20T17:42:31.312Z,1618940551.312 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:42:31.312Z,1618940551.312 [Default:CheckIn] Stopped 2021-04-20T17:42:31.312Z,1618940551.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:42:31.312Z,1618940551.312 [Default:CheckIn](INFO): Running loop #21 2021-04-20T17:42:31.313Z,1618940551.313 [Default:CheckIn] Running Loop=21 2021-04-20T17:42:31.313Z,1618940551.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:42:31.313Z,1618940551.313 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:42:33.329Z,1618940553.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174232.00,A,3648.13764,N,12147.20449,W,0.039,0.00,200421,,,A*76 2021-04-20T17:42:33.331Z,1618940553.331 [NAL9602](INFO): GPS fix at 20210420T174232: (36.802294, -121.786742) 2021-04-20T17:42:33.365Z,1618940553.365 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:42:33.365Z,1618940553.365 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:42:42.791Z,1618940562.791 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210420T155525/Courier0057.lzma 2021-04-20T17:42:43.794Z,1618940563.794 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0057.lzma.bak 2021-04-20T17:42:43.794Z,1618940563.794 [DataOverHttps](INFO): SBD MOMSN=15590921 2021-04-20T17:42:50.962Z,1618940570.962 [NAL9602](INFO): SBD MO Status=0, MOMSN=41340, MT Status=0, MTMSN=0 2021-04-20T17:42:50.963Z,1618940570.963 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:43:02.467Z,1618940582.467 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210420T155525/Express0058.lzma 2021-04-20T17:43:03.470Z,1618940583.470 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0058.lzma.bak 2021-04-20T17:43:03.470Z,1618940583.470 [DataOverHttps](INFO): SBD MOMSN=15590927 2021-04-20T17:43:04.684Z,1618940584.684 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:43:04.684Z,1618940584.684 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:43:04.684Z,1618940584.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:43:21.643Z,1618940601.643 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:48:05.399Z,1618940885.399 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:48:05.399Z,1618940885.399 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:48:05.399Z,1618940885.399 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:48:05.400Z,1618940885.399 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:48:05.797Z,1618940885.797 [Default:CheckIn:D] Stopped 2021-04-20T17:48:05.798Z,1618940885.798 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.856820 min 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn:E] Stopped 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn] Stopped 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn](INFO): Running loop #22 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn] Running Loop=22 2021-04-20T17:48:06.193Z,1618940886.193 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:48:06.194Z,1618940886.194 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:48:08.214Z,1618940888.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174807.00,A,3648.13790,N,12147.20309,W,0.117,0.00,200421,,,A*7F 2021-04-20T17:48:08.217Z,1618940888.217 [NAL9602](INFO): GPS fix at 20210420T174807: (36.802298, -121.786718) 2021-04-20T17:48:08.227Z,1618940888.227 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:48:08.227Z,1618940888.227 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:48:15.067Z,1618940895.067 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0060.lzma 2021-04-20T17:48:16.070Z,1618940896.070 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0060.lzma.bak 2021-04-20T17:48:16.070Z,1618940896.070 [DataOverHttps](INFO): SBD MOMSN=15590942 2021-04-20T17:48:31.871Z,1618940911.871 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210420T155525/Express0061.lzma 2021-04-20T17:48:32.874Z,1618940912.874 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0061.lzma.bak 2021-04-20T17:48:32.874Z,1618940912.874 [DataOverHttps](INFO): SBD MOMSN=15590948 2021-04-20T17:48:34.236Z,1618940914.236 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:48:34.236Z,1618940914.236 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:48:34.237Z,1618940914.237 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:48:52.345Z,1618940932.345 [NAL9602](INFO): SBD MO Status=2, MOMSN=41341, MT Status=2, MTMSN=0 2021-04-20T17:48:52.345Z,1618940932.345 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-04-20T17:49:04.470Z,1618940944.470 [NAL9602](INFO): SBD MO Status=0, MOMSN=41341, MT Status=0, MTMSN=0 2021-04-20T17:49:04.471Z,1618940944.471 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:49:35.175Z,1618940975.175 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:53:34.867Z,1618941214.867 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:53:34.867Z,1618941214.867 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:53:34.867Z,1618941214.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:53:34.868Z,1618941214.868 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:53:35.264Z,1618941215.264 [Default:CheckIn:D] Stopped 2021-04-20T17:53:35.265Z,1618941215.265 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:53:35.668Z,1618941215.668 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.347941 min 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn:E] Stopped 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn] Stopped 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn](INFO): Running loop #23 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn] Running Loop=23 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:53:35.669Z,1618941215.669 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:53:37.682Z,1618941217.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175337.00,A,3648.13801,N,12147.20143,W,0.175,0.00,200421,,,A*79 2021-04-20T17:53:37.684Z,1618941217.684 [NAL9602](INFO): GPS fix at 20210420T175337: (36.802300, -121.786691) 2021-04-20T17:53:37.694Z,1618941217.694 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:53:37.694Z,1618941217.694 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:53:44.217Z,1618941224.217 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0063.lzma 2021-04-20T17:53:45.218Z,1618941225.218 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0063.lzma.bak 2021-04-20T17:53:45.218Z,1618941225.218 [DataOverHttps](INFO): SBD MOMSN=15590964 2021-04-20T17:53:57.484Z,1618941237.484 [NAL9602](INFO): SBD MO Status=0, MOMSN=41342, MT Status=0, MTMSN=0 2021-04-20T17:53:57.484Z,1618941237.484 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:54:00.827Z,1618941240.827 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210420T155525/Express0064.lzma 2021-04-20T17:54:01.830Z,1618941241.830 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0064.lzma.bak 2021-04-20T17:54:01.830Z,1618941241.830 [DataOverHttps](INFO): SBD MOMSN=15590967 2021-04-20T17:54:03.148Z,1618941243.148 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:54:03.148Z,1618941243.148 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:54:03.148Z,1618941243.148 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T17:54:28.181Z,1618941268.181 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:57:26.551Z,1618941446.551 [CommandLine](IMPORTANT): got command failComponent 2021-04-20T17:57:26.552Z,1618941446.552 [CommandLine](IMPORTANT): Failed components: 2021-04-20T17:57:26.552Z,1618941446.552 [CommandLine](IMPORTANT): No failed Components. 2021-04-20T17:57:28.190Z,1618941448.190 [CommandLine](IMPORTANT): got command ibit 2021-04-20T17:57:28.504Z,1618941448.504 [IBIT](IMPORTANT): Beginning Initiated BIT 2021-04-20T17:57:28.504Z,1618941448.504 [IBIT](IMPORTANT): Beginning control surface checks. 2021-04-20T17:57:28.507Z,1618941448.507 [CBIT](IMPORTANT): Beginning ground fault scan 2021-04-20T17:57:30.108Z,1618941450.108 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175729.00,A,3648.13657,N,12147.20177,W,0.058,0.00,200421,,,A*76 2021-04-20T17:57:30.110Z,1618941450.110 [NAL9602](INFO): GPS fix at 20210420T175729: (36.802276, -121.786696) 2021-04-20T17:57:39.469Z,1618941459.469 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007908 CHAN A1 (24V): -0.000520 CHAN A2 (12V): -0.005666 CHAN A3 (5V): -0.002440 CHAN B0 (3.3V): -0.000711 CHAN B1 (3.15aV): -0.001208 CHAN B2 (3.15bV): -0.001425 CHAN B3 (GND): 0.000199 OPEN: 0.006897 Full Scale Calc: 4.765 mA, -1.589 mA 2021-04-20T17:57:48.735Z,1618941468.735 [NAL9602](INFO): SBD MO Status=0, MOMSN=41343, MT Status=0, MTMSN=0 2021-04-20T17:57:48.735Z,1618941468.735 [NAL9602](INFO): No messages in MT queue 2021-04-20T17:57:49.901Z,1618941469.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175749.00,A,3648.13695,N,12147.20217,W,0.797,0.00,200421,,,A*7F 2021-04-20T17:57:49.904Z,1618941469.904 [NAL9602](INFO): GPS fix at 20210420T175749: (36.802282, -121.786703) 2021-04-20T17:57:52.725Z,1618941472.725 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175752.00,A,3648.13657,N,12147.20125,W,0.933,0.00,200421,,,A*79 2021-04-20T17:57:52.741Z,1618941472.741 [NAL9602](INFO): GPS fix at 20210420T175752: (36.802276, -121.786687) 2021-04-20T17:57:55.967Z,1618941475.967 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175755.00,A,3648.13646,N,12147.20084,W,0.058,0.00,200421,,,A*70 2021-04-20T17:57:55.970Z,1618941475.970 [NAL9602](INFO): GPS fix at 20210420T175755: (36.802274, -121.786681) 2021-04-20T17:57:58.785Z,1618941478.785 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175758.00,A,3648.13640,N,12147.20088,W,0.350,0.00,200421,,,A*7C 2021-04-20T17:57:58.787Z,1618941478.787 [NAL9602](INFO): GPS fix at 20210420T175758: (36.802273, -121.786681) 2021-04-20T17:58:01.617Z,1618941481.617 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175801.00,A,3648.13658,N,12147.20111,W,0.019,0.00,200421,,,A*79 2021-04-20T17:58:01.619Z,1618941481.619 [NAL9602](INFO): GPS fix at 20210420T175801: (36.802276, -121.786685) 2021-04-20T17:58:04.850Z,1618941484.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175804.00,A,3648.13651,N,12147.20065,W,0.680,0.00,200421,,,A*71 2021-04-20T17:58:04.852Z,1618941484.852 [NAL9602](INFO): GPS fix at 20210420T175804: (36.802275, -121.786677) 2021-04-20T17:58:07.673Z,1618941487.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175807.00,A,3648.13685,N,12147.20081,W,0.292,0.00,200421,,,A*76 2021-04-20T17:58:07.675Z,1618941487.675 [NAL9602](INFO): GPS fix at 20210420T175807: (36.802281, -121.786680) 2021-04-20T17:58:10.919Z,1618941490.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175810.00,A,3648.13668,N,12147.20069,W,0.233,0.00,200421,,,A*7E 2021-04-20T17:58:10.930Z,1618941490.930 [NAL9602](INFO): GPS fix at 20210420T175810: (36.802278, -121.786678) 2021-04-20T17:58:13.741Z,1618941493.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175813.00,A,3648.13716,N,12147.20093,W,0.583,0.00,200421,,,A*7C 2021-04-20T17:58:13.743Z,1618941493.743 [NAL9602](INFO): GPS fix at 20210420T175813: (36.802286, -121.786682) 2021-04-20T17:58:15.017Z,1618941495.017 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802284 Longitude: -121.786682 2021-04-20T17:58:15.374Z,1618941495.374 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.294001 2021-04-20T17:58:15.374Z,1618941495.374 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2021-04-20T17:58:15.375Z,1618941495.375 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2021-04-20T17:58:15.792Z,1618941495.792 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2021-04-20T17:58:15.792Z,1618941495.792 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2021-04-20T17:58:15.792Z,1618941495.792 [IBIT](IMPORTANT): Pressure:7.588063 PSI 2021-04-20T17:58:15.793Z,1618941495.793 [IBIT](IMPORTANT): Humidity:8.985472 % 2021-04-20T17:58:16.166Z,1618941496.166 [IBIT](IMPORTANT): Vehicle Pitch:-1.067168 degrees 2021-04-20T17:58:16.167Z,1618941496.167 [IBIT](IMPORTANT): Vehicle Roll:9.432448 degrees 2021-04-20T17:58:16.167Z,1618941496.167 [IBIT](IMPORTANT): Vehicle Heading:203.080948 degrees 2021-04-20T17:58:16.584Z,1618941496.584 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2021-04-20T17:58:16.584Z,1618941496.584 [IBIT](IMPORTANT): buoyancyNeutral: 325.319336 cc 2021-04-20T17:58:16.584Z,1618941496.584 [IBIT](IMPORTANT): massDefault: 0.894689 cm 2021-04-20T17:58:16.585Z,1618941496.585 [IBIT](IMPORTANT): stopDepth: 90.000000 m 2021-04-20T17:58:16.585Z,1618941496.585 [IBIT](IMPORTANT): abortDepth: 100.000000 m 2021-04-20T17:58:16.585Z,1618941496.585 [IBIT](IMPORTANT): IBIT PASSED 2021-04-20T17:58:46.518Z,1618941526.518 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T17:59:03.905Z,1618941543.905 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-04-20T17:59:03.905Z,1618941543.905 [Default:CheckIn:C.Wait] Stopped 2021-04-20T17:59:03.905Z,1618941543.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T17:59:03.905Z,1618941543.905 [Default:CheckIn:D] Running Loop=1 2021-04-20T17:59:04.307Z,1618941544.307 [Default:CheckIn:D] Stopped 2021-04-20T17:59:04.307Z,1618941544.307 [Default:CheckIn:E] Running Loop=1 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.831991 min 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn:E] Stopped 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn] Stopped 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn](INFO): Running loop #24 2021-04-20T17:59:04.712Z,1618941544.712 [Default:CheckIn] Running Loop=24 2021-04-20T17:59:04.713Z,1618941544.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-20T17:59:04.713Z,1618941544.713 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-20T17:59:06.717Z,1618941546.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175906.00,A,3648.13608,N,12147.19989,W,0.175,0.00,200421,,,D*77 2021-04-20T17:59:06.720Z,1618941546.720 [NAL9602](INFO): GPS fix at 20210420T175906: (36.802268, -121.786665) 2021-04-20T17:59:06.729Z,1618941546.729 [Default:CheckIn:Read_GPS] Stopped 2021-04-20T17:59:06.729Z,1618941546.729 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-20T17:59:13.527Z,1618941553.527 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210420T155525/Courier0066.lzma 2021-04-20T17:59:14.530Z,1618941554.530 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Courier0066.lzma.bak 2021-04-20T17:59:14.530Z,1618941554.530 [DataOverHttps](INFO): SBD MOMSN=15591020 2021-04-20T17:59:30.193Z,1618941570.193 [DataOverHttps](INFO): Sending 821 bytes from file Logs/20210420T155525/Express0067.lzma 2021-04-20T17:59:31.194Z,1618941571.194 [DataOverHttps](INFO): Moved sent file to Logs/20210420T155525/Express0067.lzma.bak 2021-04-20T17:59:31.194Z,1618941571.194 [DataOverHttps](INFO): SBD MOMSN=15591024 2021-04-20T17:59:32.590Z,1618941572.590 [Default:CheckIn:Read_Iridium] Stopped 2021-04-20T17:59:32.590Z,1618941572.590 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-20T17:59:32.590Z,1618941572.590 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-20T18:00:12.179Z,1618941612.179 [NAL9602](INFO): SBD MO Status=0, MOMSN=41344, MT Status=0, MTMSN=0 2021-04-20T18:00:12.179Z,1618941612.179 [NAL9602](INFO): No messages in MT queue 2021-04-20T18:00:42.933Z,1618941642.933 [NAL9602](INFO): Not Powering down - fast GPS 2021-04-20T18:00:45.773Z,1618941645.773 [CommandLine](IMPORTANT): got command gfscan 2021-04-20T18:00:45.827Z,1618941645.827 [CBIT](IMPORTANT): Beginning ground fault scan 2021-04-20T18:00:56.717Z,1618941656.717 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007658 CHAN A1 (24V): -0.000518 CHAN A2 (12V): -0.005614 CHAN A3 (5V): -0.002581 CHAN B0 (3.3V): -0.001034 CHAN B1 (3.15aV): -0.001155 CHAN B2 (3.15bV): -0.001241 CHAN B3 (GND): 0.000177 OPEN: 0.006610 Full Scale Calc: 4.765 mA, -1.589 mA 2021-04-20T18:01:18.243Z,1618941678.243 [CommandLine](IMPORTANT): got command restart application 2021-04-20T18:01:19.249Z,1618941679.249 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:19.250Z,1618941679.250 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.269Z,1618941679.269 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-04-20T18:01:19.269Z,1618941679.269 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.270Z,1618941679.270 [CommandLine](INFO): Join timeout helper Thread ID is 4600 2021-04-20T18:01:19.271Z,1618941679.271 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-04-20T18:01:19.271Z,1618941679.271 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.271Z,1618941679.271 [NavChartDb](INFO): Join timeout helper Thread ID is 4601 2021-04-20T18:01:19.381Z,1618941679.381 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:19.382Z,1618941679.382 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.389Z,1618941679.389 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-04-20T18:01:19.389Z,1618941679.389 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.390Z,1618941679.390 [Radio_Surface](INFO): Join timeout helper Thread ID is 4602 2021-04-20T18:01:19.641Z,1618941679.641 [Radio_Surface](INFO): Powering down 2021-04-20T18:01:19.642Z,1618941679.642 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:19.643Z,1618941679.643 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.653Z,1618941679.653 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-04-20T18:01:19.653Z,1618941679.653 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:19.654Z,1618941679.654 [Onboard](INFO): Join timeout helper Thread ID is 4603 2021-04-20T18:01:20.278Z,1618941680.278 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-04-20T18:01:23.494Z,1618941683.494 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:23.494Z,1618941683.494 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:23.501Z,1618941683.501 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-04-20T18:01:23.502Z,1618941683.502 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:23.502Z,1618941683.502 [DataOverHttps](INFO): Join timeout helper Thread ID is 4604 2021-04-20T18:01:24.293Z,1618941684.293 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:24.294Z,1618941684.294 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.306Z,1618941684.306 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-04-20T18:01:24.306Z,1618941684.306 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.307Z,1618941684.307 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4605 2021-04-20T18:01:24.574Z,1618941684.574 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:24.574Z,1618941684.574 [WetLabsBB2FL](INFO): Powering down 2021-04-20T18:01:24.575Z,1618941684.575 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.594Z,1618941684.594 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-04-20T18:01:24.594Z,1618941684.594 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.594Z,1618941684.594 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4606 2021-04-20T18:01:24.925Z,1618941684.925 [CTD_Seabird](INFO): Powering down 2021-04-20T18:01:24.941Z,1618941684.941 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:24.941Z,1618941684.941 [CTD_Seabird](INFO): Powering down 2021-04-20T18:01:24.953Z,1618941684.953 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.958Z,1618941684.958 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-04-20T18:01:24.958Z,1618941684.958 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.959Z,1618941684.959 [logger](INFO): Join timeout helper Thread ID is 4607 2021-04-20T18:01:24.989Z,1618941684.989 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:24.990Z,1618941684.990 [logger ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.998Z,1618941684.998 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-04-20T18:01:24.998Z,1618941684.998 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.998Z,1618941684.998 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-04-20T18:01:24.998Z,1618941684.998 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:24.999Z,1618941684.999 [controlThread](INFO): Join timeout helper Thread ID is 4608 2021-04-20T18:01:25.089Z,1618941685.089 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-04-20T18:01:25.090Z,1618941685.090 [controlThread](DEBUG): Uninitializing ControlThread 2021-04-20T18:01:25.091Z,1618941685.091 [AHRS_M2](INFO): Powering down 2021-04-20T18:01:25.162Z,1618941685.162 [NAL9602](INFO): Powering down 2021-04-20T18:01:25.163Z,1618941685.163 [DAT](INFO): Powering down 2021-04-20T18:01:25.282Z,1618941685.282 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-04-20T18:01:25.283Z,1618941685.283 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-04-20T18:01:25.283Z,1618941685.283 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-04-20T18:01:25.284Z,1618941685.284 [MissionManager](INFO): Uninitializing Mission Default 2021-04-20T18:01:25.284Z,1618941685.284 [Default] Stopped 2021-04-20T18:01:25.284Z,1618941685.284 [Default](DEBUG): Aggregate::uninitialize Default 2021-04-20T18:01:25.284Z,1618941685.284 [Default:B.GoToSurface] Stopped 2021-04-20T18:01:25.284Z,1618941685.284 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-04-20T18:01:25.284Z,1618941685.284 [Default:CheckIn] Stopped 2021-04-20T18:01:25.284Z,1618941685.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-04-20T18:01:25.285Z,1618941685.285 [Default:CheckIn:C.Wait] Stopped 2021-04-20T18:01:25.285Z,1618941685.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-04-20T18:01:25.287Z,1618941685.287 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-04-20T18:01:25.288Z,1618941685.288 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-04-20T18:01:25.288Z,1618941685.288 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-04-20T18:01:25.288Z,1618941685.288 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-04-20T18:01:25.289Z,1618941685.289 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-04-20T18:01:25.289Z,1618941685.289 [BuoyancyServo](INFO): Powering down 2021-04-20T18:01:25.301Z,1618941685.301 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-04-20T18:01:25.301Z,1618941685.301 [ElevatorServo](INFO): Powering down 2021-04-20T18:01:25.302Z,1618941685.302 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-04-20T18:01:25.302Z,1618941685.302 [MassServo](INFO): Powering down 2021-04-20T18:01:25.303Z,1618941685.303 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-04-20T18:01:25.303Z,1618941685.303 [RudderServo](INFO): Powering down 2021-04-20T18:01:25.304Z,1618941685.304 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-04-20T18:01:25.304Z,1618941685.304 [ThrusterServo](INFO): Powering down 2021-04-20T18:01:25.305Z,1618941685.305 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-04-20T18:01:25.305Z,1618941685.305 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-04-20T18:01:25.306Z,1618941685.306 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-04-20T18:01:25.306Z,1618941685.306 [CBIT](DEBUG): Powering off loads. 2021-04-20T18:01:25.317Z,1618941685.317 [CBIT](DEBUG): Disabling WDT. 2021-04-20T18:01:25.329Z,1618941685.329 [CBIT](DEBUG): Opening all GF detection circuits. 2021-04-20T18:01:25.330Z,1618941685.330 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.366Z,1618941685.366 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.372Z,1618941685.372 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.403Z,1618941685.403 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.404Z,1618941685.404 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.411Z,1618941685.411 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.498Z,1618941685.498 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-04-20T18:01:25.575Z,1618941685.575 [logger ThreadHandler](INFO): Thread cancelled.