2022-06-30T18:47:20.166Z,1656614840.166 [CommandExec](IMPORTANT): got command restart logs
2022-06-30T18:50:41.555Z,1656615041.555 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T18:50:41.555Z,1656615041.555 [Default:CheckIn:C.Wait] Stopped
2022-06-30T18:50:41.555Z,1656615041.555 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T18:50:41.555Z,1656615041.555 [Default:CheckIn:D] Running Loop=1
2022-06-30T18:50:41.936Z,1656615041.936 [Default:CheckIn:D] Stopped
2022-06-30T18:50:41.936Z,1656615041.936 [Default:CheckIn:E] Running Loop=1
2022-06-30T18:50:42.336Z,1656615042.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.597363 min 
2022-06-30T18:50:42.336Z,1656615042.336 [Default:CheckIn:E] Stopped
2022-06-30T18:50:42.336Z,1656615042.336 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T18:50:42.336Z,1656615042.336 [Default:CheckIn] Stopped
2022-06-30T18:50:42.336Z,1656615042.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T18:50:42.336Z,1656615042.336 [Default:CheckIn](INFO): Running loop #2
2022-06-30T18:50:42.337Z,1656615042.337 [Default:CheckIn] Running Loop=2
2022-06-30T18:50:42.337Z,1656615042.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T18:50:42.337Z,1656615042.337 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T18:50:43.453Z,1656615043.453 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239281
2022-06-30T18:50:44.344Z,1656615044.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185043.00,A,3648.35511,N,12149.33192,W,0.486,90.41,300622,,,D*4E
2022-06-30T18:50:44.346Z,1656615044.346 [NAL9602](INFO): GPS fix at 20220630T185043: (36.805918, -121.822199)
2022-06-30T18:50:44.384Z,1656615044.384 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T18:50:44.384Z,1656615044.384 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T18:51:06.639Z,1656615066.639 [NAL9602](INFO): SBD MO Status=1, MOMSN=19757, MT Status=0, MTMSN=0
2022-06-30T18:51:06.689Z,1656615066.689 [NAL9602](INFO): Sent 39 bytes from file Logs/20220630T014802/Courier0093.lzma
2022-06-30T18:51:06.689Z,1656615066.689 [NAL9602](INFO): Packets left to send: 0
2022-06-30T18:51:16.585Z,1656615076.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:51:26.899Z,1656615086.899 [NAL9602](INFO): SBD MO Status=1, MOMSN=19758, MT Status=0, MTMSN=0
2022-06-30T18:51:26.953Z,1656615086.953 [NAL9602](INFO): Sent 268 bytes from file Logs/20220630T184720/Courier0000.lzma
2022-06-30T18:51:26.954Z,1656615086.954 [NAL9602](INFO): Packets left to send: 0
2022-06-30T18:51:43.133Z,1656615103.133 [NAL9602](INFO): SBD MO Status=1, MOMSN=19759, MT Status=0, MTMSN=0
2022-06-30T18:51:43.185Z,1656615103.185 [NAL9602](INFO): Sent 103 bytes from file Logs/20220630T014802/Express0094.lzma
2022-06-30T18:51:43.185Z,1656615103.185 [NAL9602](INFO): Packets left to send: 0
2022-06-30T18:51:51.737Z,1656615111.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:51:56.533Z,1656615116.533 [NAL9602](INFO): SBD MO Status=1, MOMSN=19760, MT Status=0, MTMSN=0
2022-06-30T18:51:56.593Z,1656615116.593 [NAL9602](INFO): Sent 332 bytes from file Logs/20220630T184720/Express0001.lzma
2022-06-30T18:51:56.593Z,1656615116.593 [NAL9602](INFO): Packets left to send: 1
2022-06-30T18:52:07.178Z,1656615127.178 [NAL9602](INFO): SBD MO Status=1, MOMSN=19761, MT Status=0, MTMSN=0
2022-06-30T18:52:07.230Z,1656615127.230 [NAL9602](INFO): Sent 49 bytes from file Logs/20220630T184720/Express0001.lzma
2022-06-30T18:52:07.230Z,1656615127.230 [NAL9602](INFO): Packets left to send: 0
2022-06-30T18:52:16.875Z,1656615136.875 [NAL9602](INFO): SBD MO Status=0, MOMSN=19762, MT Status=0, MTMSN=0
2022-06-30T18:52:16.962Z,1656615136.962 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T18:52:16.962Z,1656615136.962 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T18:52:16.962Z,1656615136.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T18:52:26.877Z,1656615146.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:52:47.578Z,1656615167.578 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T18:53:02.017Z,1656615182.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:53:15.789Z,1656615195.789 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003195
2022-06-30T18:54:48.729Z,1656615288.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:55:02.785Z,1656615302.785 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239384
2022-06-30T18:55:59.029Z,1656615359.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:56:34.169Z,1656615394.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:57:05.742Z,1656615425.742 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2022-06-30T18:57:09.309Z,1656615429.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:57:17.480Z,1656615437.480 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T18:57:17.480Z,1656615437.480 [Default:CheckIn:C.Wait] Stopped
2022-06-30T18:57:17.480Z,1656615437.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T18:57:17.481Z,1656615437.481 [Default:CheckIn:D] Running Loop=1
2022-06-30T18:57:17.876Z,1656615437.876 [Default:CheckIn:D] Stopped
2022-06-30T18:57:17.877Z,1656615437.877 [Default:CheckIn:E] Running Loop=1
2022-06-30T18:57:18.263Z,1656615438.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.196375 min 
2022-06-30T18:57:18.263Z,1656615438.263 [Default:CheckIn:E] Stopped
2022-06-30T18:57:18.263Z,1656615438.263 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T18:57:18.263Z,1656615438.263 [Default:CheckIn] Stopped
2022-06-30T18:57:18.264Z,1656615438.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T18:57:18.264Z,1656615438.264 [Default:CheckIn](INFO): Running loop #3
2022-06-30T18:57:18.264Z,1656615438.264 [Default:CheckIn] Running Loop=3
2022-06-30T18:57:18.264Z,1656615438.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T18:57:18.264Z,1656615438.264 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T18:57:20.275Z,1656615440.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185719.00,A,3648.23047,N,12147.73364,W,21.363,76.60,300622,,,D*72
2022-06-30T18:57:20.277Z,1656615440.277 [NAL9602](INFO): GPS fix at 20220630T185719: (36.803841, -121.795561)
2022-06-30T18:57:20.288Z,1656615440.288 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T18:57:20.288Z,1656615440.288 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T18:57:44.449Z,1656615464.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:57:45.393Z,1656615465.393 [NAL9602](INFO): SBD MO Status=1, MOMSN=19763, MT Status=0, MTMSN=0
2022-06-30T18:57:45.441Z,1656615465.441 [NAL9602](INFO): Sent 73 bytes from file Logs/20220630T184720/Courier0003.lzma
2022-06-30T18:57:45.441Z,1656615465.441 [NAL9602](INFO): Packets left to send: 0
2022-06-30T18:58:02.015Z,1656615482.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=19764, MT Status=2, MTMSN=0
2022-06-30T18:58:02.015Z,1656615482.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T18:58:19.597Z,1656615499.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:58:29.613Z,1656615509.613 [NAL9602](INFO): SBD MO Status=1, MOMSN=19764, MT Status=0, MTMSN=0
2022-06-30T18:58:29.670Z,1656615509.670 [NAL9602](INFO): Sent 139 bytes from file Logs/20220630T184720/Express0004.lzma
2022-06-30T18:58:29.671Z,1656615509.671 [NAL9602](INFO): Packets left to send: 0
2022-06-30T18:58:49.425Z,1656615529.425 [NAL9602](INFO): SBD MO Status=0, MOMSN=19765, MT Status=0, MTMSN=0
2022-06-30T18:58:49.518Z,1656615529.518 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T18:58:49.518Z,1656615529.518 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T18:58:49.518Z,1656615529.518 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T18:58:54.737Z,1656615534.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T18:59:20.115Z,1656615560.115 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T18:59:29.877Z,1656615569.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T19:00:05.017Z,1656615605.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T19:00:40.157Z,1656615640.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T19:01:15.299Z,1656615675.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T19:01:35.472Z,1656615695.472 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2022-06-30T19:01:50.437Z,1656615710.437 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T19:02:25.577Z,1656615745.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-06-30T19:02:47.237Z,1656615767.237 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003125
2022-06-30T19:03:50.021Z,1656615830.021 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:03:50.021Z,1656615830.021 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:03:50.021Z,1656615830.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:03:50.022Z,1656615830.022 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:03:50.408Z,1656615830.408 [Default:CheckIn:D] Stopped
2022-06-30T19:03:50.408Z,1656615830.408 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:03:50.812Z,1656615830.812 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.738564 min 
2022-06-30T19:03:50.812Z,1656615830.812 [Default:CheckIn:E] Stopped
2022-06-30T19:03:50.812Z,1656615830.812 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:03:50.812Z,1656615830.812 [Default:CheckIn] Stopped
2022-06-30T19:03:50.813Z,1656615830.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:03:50.813Z,1656615830.813 [Default:CheckIn](INFO): Running loop #4
2022-06-30T19:03:50.813Z,1656615830.813 [Default:CheckIn] Running Loop=4
2022-06-30T19:03:50.813Z,1656615830.813 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:03:50.813Z,1656615830.813 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:03:52.820Z,1656615832.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190352.00,A,3648.23415,N,12147.14168,W,4.996,191.52,300622,,,D*7F
2022-06-30T19:03:52.822Z,1656615832.822 [NAL9602](INFO): GPS fix at 20220630T190352: (36.803902, -121.785695)
2022-06-30T19:03:52.833Z,1656615832.833 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:03:52.833Z,1656615832.833 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:04:00.180Z,1656615840.180 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0006.lzma
2022-06-30T19:04:01.182Z,1656615841.182 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0006.lzma.bak
2022-06-30T19:04:01.183Z,1656615841.183 [DataOverHttps](INFO): SBD MOMSN=16948346
2022-06-30T19:04:17.436Z,1656615857.436 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20220630T184720/Express0007.lzma
2022-06-30T19:04:18.438Z,1656615858.438 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0007.lzma.bak
2022-06-30T19:04:18.439Z,1656615858.439 [DataOverHttps](INFO): SBD MOMSN=16948349
2022-06-30T19:04:20.292Z,1656615860.292 [NAL9602](INFO): SBD MO Status=2, MOMSN=19766, MT Status=2, MTMSN=0
2022-06-30T19:04:20.292Z,1656615860.292 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:04:30.803Z,1656615870.803 [NAL9602](INFO): SBD MO Status=0, MOMSN=19766, MT Status=0, MTMSN=0
2022-06-30T19:04:30.803Z,1656615870.803 [NAL9602](INFO): No messages in MT queue
2022-06-30T19:04:34.820Z,1656615874.820 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20220630T184720/Express0010.lzma
2022-06-30T19:04:35.823Z,1656615875.823 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0010.lzma.bak
2022-06-30T19:04:35.823Z,1656615875.823 [DataOverHttps](INFO): SBD MOMSN=16948352
2022-06-30T19:04:37.299Z,1656615877.299 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:04:37.299Z,1656615877.299 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:04:37.300Z,1656615877.300 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:05:01.510Z,1656615901.510 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:09:37.853Z,1656616177.853 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:09:37.853Z,1656616177.853 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:09:37.853Z,1656616177.853 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:09:37.853Z,1656616177.853 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:09:38.255Z,1656616178.255 [Default:CheckIn:D] Stopped
2022-06-30T19:09:38.255Z,1656616178.255 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.536023 min 
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn:E] Stopped
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn] Stopped
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn](INFO): Running loop #5
2022-06-30T19:09:38.669Z,1656616178.669 [Default:CheckIn] Running Loop=5
2022-06-30T19:09:38.670Z,1656616178.670 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:09:38.670Z,1656616178.670 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:09:40.672Z,1656616180.672 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190939.00,A,3648.16206,N,12147.21476,W,0.039,235.61,300622,,,D*73
2022-06-30T19:09:40.675Z,1656616180.675 [NAL9602](INFO): GPS fix at 20220630T190939: (36.802701, -121.786913)
2022-06-30T19:09:40.706Z,1656616180.706 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:09:40.706Z,1656616180.706 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:09:48.236Z,1656616188.236 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220630T184720/Courier0012.lzma
2022-06-30T19:09:49.238Z,1656616189.238 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0012.lzma.bak
2022-06-30T19:09:49.239Z,1656616189.239 [DataOverHttps](INFO): SBD MOMSN=16948357
2022-06-30T19:10:05.680Z,1656616205.680 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220630T184720/Express0013.lzma
2022-06-30T19:10:06.683Z,1656616206.683 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0013.lzma.bak
2022-06-30T19:10:06.683Z,1656616206.683 [DataOverHttps](INFO): SBD MOMSN=16948360
2022-06-30T19:10:06.939Z,1656616206.939 [NAL9602](INFO): SBD MO Status=0, MOMSN=19767, MT Status=0, MTMSN=0
2022-06-30T19:10:06.939Z,1656616206.939 [NAL9602](INFO): No messages in MT queue
2022-06-30T19:10:08.151Z,1656616208.151 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:10:08.151Z,1656616208.151 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:10:08.151Z,1656616208.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:10:37.634Z,1656616237.634 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:15:08.745Z,1656616508.745 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:15:08.746Z,1656616508.746 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:15:08.746Z,1656616508.746 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:15:08.746Z,1656616508.746 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:15:09.131Z,1656616509.131 [Default:CheckIn:D] Stopped
2022-06-30T19:15:09.131Z,1656616509.131 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:15:09.571Z,1656616509.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.050616 min 
2022-06-30T19:15:09.571Z,1656616509.571 [Default:CheckIn:E] Stopped
2022-06-30T19:15:09.571Z,1656616509.571 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:15:09.571Z,1656616509.571 [Default:CheckIn] Stopped
2022-06-30T19:15:09.572Z,1656616509.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:15:09.572Z,1656616509.572 [Default:CheckIn](INFO): Running loop #6
2022-06-30T19:15:09.572Z,1656616509.572 [Default:CheckIn] Running Loop=6
2022-06-30T19:15:09.572Z,1656616509.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:15:09.572Z,1656616509.572 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:15:11.548Z,1656616511.548 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191510.00,A,3648.16474,N,12147.21655,W,0.039,294.92,300622,,,A*77
2022-06-30T19:15:11.551Z,1656616511.551 [NAL9602](INFO): GPS fix at 20220630T191510: (36.802746, -121.786942)
2022-06-30T19:15:11.585Z,1656616511.585 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:15:11.585Z,1656616511.585 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:15:19.370Z,1656616519.370 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0015.lzma
2022-06-30T19:15:20.366Z,1656616520.366 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0015.lzma.bak
2022-06-30T19:15:20.367Z,1656616520.367 [DataOverHttps](INFO): SBD MOMSN=16948364
2022-06-30T19:15:31.747Z,1656616531.747 [NAL9602](INFO): SBD MO Status=0, MOMSN=19768, MT Status=0, MTMSN=0
2022-06-30T19:15:31.747Z,1656616531.747 [NAL9602](INFO): No messages in MT queue
2022-06-30T19:15:36.841Z,1656616536.841 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220630T184720/Express0016.lzma
2022-06-30T19:15:37.843Z,1656616537.843 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0016.lzma.bak
2022-06-30T19:15:37.843Z,1656616537.843 [DataOverHttps](INFO): SBD MOMSN=16948367
2022-06-30T19:15:39.036Z,1656616539.036 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:15:39.036Z,1656616539.036 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:15:39.037Z,1656616539.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:16:02.455Z,1656616562.455 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:17:55.585Z,1656616675.585 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2022-06-30T19:20:39.644Z,1656616839.644 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:20:39.644Z,1656616839.644 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:20:39.649Z,1656616839.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:20:39.649Z,1656616839.649 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:20:40.009Z,1656616840.009 [Default:CheckIn:D] Stopped
2022-06-30T19:20:40.009Z,1656616840.009 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:20:40.425Z,1656616840.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.565251 min 
2022-06-30T19:20:40.425Z,1656616840.425 [Default:CheckIn:E] Stopped
2022-06-30T19:20:40.425Z,1656616840.425 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:20:40.425Z,1656616840.425 [Default:CheckIn] Stopped
2022-06-30T19:20:40.425Z,1656616840.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:20:40.426Z,1656616840.426 [Default:CheckIn](INFO): Running loop #7
2022-06-30T19:20:40.426Z,1656616840.426 [Default:CheckIn] Running Loop=7
2022-06-30T19:20:40.426Z,1656616840.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:20:40.426Z,1656616840.426 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:20:42.424Z,1656616842.424 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192041.00,A,3648.14148,N,12147.24380,W,2.235,291.72,300622,,,A*72
2022-06-30T19:20:42.426Z,1656616842.426 [NAL9602](INFO): GPS fix at 20220630T192041: (36.802358, -121.787397)
2022-06-30T19:20:42.453Z,1656616842.453 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:20:42.453Z,1656616842.453 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:20:50.400Z,1656616850.400 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220630T184720/Courier0018.lzma
2022-06-30T19:20:51.403Z,1656616851.403 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0018.lzma.bak
2022-06-30T19:20:51.403Z,1656616851.403 [DataOverHttps](INFO): SBD MOMSN=16948373
2022-06-30T19:20:54.151Z,1656616854.151 [NAL9602](INFO): SBD MO Status=0, MOMSN=19769, MT Status=0, MTMSN=0
2022-06-30T19:20:54.151Z,1656616854.151 [NAL9602](INFO): No messages in MT queue
2022-06-30T19:21:07.788Z,1656616867.788 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220630T184720/Express0019.lzma
2022-06-30T19:21:08.790Z,1656616868.790 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0019.lzma.bak
2022-06-30T19:21:08.791Z,1656616868.791 [DataOverHttps](INFO): SBD MOMSN=16948376
2022-06-30T19:21:09.910Z,1656616869.910 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:21:09.910Z,1656616869.910 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:21:09.910Z,1656616869.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:21:24.840Z,1656616884.840 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:26:10.513Z,1656617170.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:26:10.513Z,1656617170.513 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:26:10.513Z,1656617170.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:26:10.514Z,1656617170.514 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:26:10.920Z,1656617170.920 [Default:CheckIn:D] Stopped
2022-06-30T19:26:10.920Z,1656617170.920 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:26:11.369Z,1656617171.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.080436 min 
2022-06-30T19:26:11.369Z,1656617171.369 [Default:CheckIn:E] Stopped
2022-06-30T19:26:11.369Z,1656617171.369 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:26:11.370Z,1656617171.370 [Default:CheckIn] Stopped
2022-06-30T19:26:11.370Z,1656617171.370 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:26:11.370Z,1656617171.370 [Default:CheckIn](INFO): Running loop #8
2022-06-30T19:26:11.371Z,1656617171.371 [Default:CheckIn] Running Loop=8
2022-06-30T19:26:11.371Z,1656617171.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:26:11.371Z,1656617171.371 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:26:13.336Z,1656617173.336 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192612.00,A,3648.17069,N,12147.27876,W,0.719,302.45,300622,,,A*74
2022-06-30T19:26:13.343Z,1656617173.343 [NAL9602](INFO): GPS fix at 20220630T192612: (36.802845, -121.787979)
2022-06-30T19:26:13.391Z,1656617173.391 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:26:13.391Z,1656617173.391 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:26:21.776Z,1656617181.776 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0021.lzma
2022-06-30T19:26:22.849Z,1656617182.849 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0021.lzma.bak
2022-06-30T19:26:22.850Z,1656617182.850 [DataOverHttps](INFO): SBD MOMSN=16948381
2022-06-30T19:26:39.092Z,1656617199.092 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220630T184720/Express0022.lzma
2022-06-30T19:26:40.094Z,1656617200.094 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0022.lzma.bak
2022-06-30T19:26:40.095Z,1656617200.095 [DataOverHttps](INFO): SBD MOMSN=16948384
2022-06-30T19:26:41.638Z,1656617201.638 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:26:41.638Z,1656617201.638 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:26:41.638Z,1656617201.638 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:28:15.442Z,1656617295.442 [NAL9602](INFO): SBD MO Status=2, MOMSN=19770, MT Status=2, MTMSN=0
2022-06-30T19:28:15.442Z,1656617295.442 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:28:56.965Z,1656617336.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=19770, MT Status=2, MTMSN=0
2022-06-30T19:28:56.966Z,1656617336.966 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:29:30.908Z,1656617370.908 [NAL9602](INFO): SBD MO Status=2, MOMSN=19770, MT Status=2, MTMSN=0
2022-06-30T19:29:30.909Z,1656617370.909 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:29:54.335Z,1656617394.335 [NAL9602](INFO): SBD MO Status=0, MOMSN=19770, MT Status=0, MTMSN=0
2022-06-30T19:29:54.336Z,1656617394.336 [NAL9602](INFO): No messages in MT queue
2022-06-30T19:30:25.042Z,1656617425.042 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:31:42.215Z,1656617502.215 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:31:42.216Z,1656617502.216 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:31:42.216Z,1656617502.216 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:31:42.216Z,1656617502.216 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:31:42.622Z,1656617502.622 [Default:CheckIn:D] Stopped
2022-06-30T19:31:42.622Z,1656617502.622 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:31:43.038Z,1656617503.038 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.608801 min 
2022-06-30T19:31:43.038Z,1656617503.038 [Default:CheckIn:E] Stopped
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn] Stopped
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn](INFO): Running loop #9
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn] Running Loop=9
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:31:43.039Z,1656617503.039 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:31:45.035Z,1656617505.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193144.00,A,3648.16801,N,12147.27512,W,0.914,158.34,300622,,,A*71
2022-06-30T19:31:45.038Z,1656617505.038 [NAL9602](INFO): GPS fix at 20220630T193144: (36.802800, -121.787919)
2022-06-30T19:31:45.048Z,1656617505.048 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:31:45.049Z,1656617505.049 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:31:52.472Z,1656617512.472 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0024.lzma
2022-06-30T19:31:53.475Z,1656617513.475 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0024.lzma.bak
2022-06-30T19:31:53.475Z,1656617513.475 [DataOverHttps](INFO): SBD MOMSN=16948387
2022-06-30T19:32:09.736Z,1656617529.736 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220630T184720/Express0025.lzma
2022-06-30T19:32:10.739Z,1656617530.739 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0025.lzma.bak
2022-06-30T19:32:10.739Z,1656617530.739 [DataOverHttps](INFO): SBD MOMSN=16948390
2022-06-30T19:32:12.120Z,1656617532.120 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:32:12.120Z,1656617532.120 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:32:12.120Z,1656617532.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:32:20.187Z,1656617540.187 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:32:20.187Z,1656617540.187 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:32:56.541Z,1656617576.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:32:56.542Z,1656617576.542 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:36:47.226Z,1656617807.226 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-30T19:37:12.688Z,1656617832.688 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:37:12.688Z,1656617832.688 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:37:12.688Z,1656617832.688 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:37:12.688Z,1656617832.688 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:37:13.090Z,1656617833.090 [Default:CheckIn:D] Stopped
2022-06-30T19:37:13.090Z,1656617833.090 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.116606 min 
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn:E] Stopped
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn] Stopped
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn](INFO): Running loop #10
2022-06-30T19:37:13.520Z,1656617833.520 [Default:CheckIn] Running Loop=10
2022-06-30T19:37:13.541Z,1656617833.541 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:37:13.541Z,1656617833.541 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:37:15.513Z,1656617835.513 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193714.00,A,3648.16962,N,12147.27651,W,0.272,158.34,300622,,,A*79
2022-06-30T19:37:15.515Z,1656617835.515 [NAL9602](INFO): GPS fix at 20220630T193714: (36.802827, -121.787942)
2022-06-30T19:37:15.545Z,1656617835.545 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:37:15.545Z,1656617835.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:37:23.140Z,1656617843.140 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0027.lzma
2022-06-30T19:37:24.142Z,1656617844.142 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0027.lzma.bak
2022-06-30T19:37:24.143Z,1656617844.143 [DataOverHttps](INFO): SBD MOMSN=16948394
2022-06-30T19:37:40.901Z,1656617860.901 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20220630T184720/Express0028.lzma
2022-06-30T19:37:41.903Z,1656617861.903 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0028.lzma.bak
2022-06-30T19:37:41.903Z,1656617861.903 [DataOverHttps](INFO): SBD MOMSN=16948397
2022-06-30T19:37:43.440Z,1656617863.440 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:37:43.445Z,1656617863.445 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:37:43.445Z,1656617863.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:37:47.430Z,1656617867.430 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:42:43.971Z,1656618163.971 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:42:43.972Z,1656618163.972 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:42:43.972Z,1656618163.972 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:42:43.972Z,1656618163.972 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:42:44.376Z,1656618164.376 [Default:CheckIn:D] Stopped
2022-06-30T19:42:44.376Z,1656618164.376 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:42:44.776Z,1656618164.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.638033 min 
2022-06-30T19:42:44.776Z,1656618164.776 [Default:CheckIn:E] Stopped
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn] Stopped
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn](INFO): Running loop #11
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn] Running Loop=11
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:42:44.777Z,1656618164.777 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:42:46.798Z,1656618166.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194246.00,A,3648.16881,N,12147.28161,W,0.758,143.94,300622,,,A*76
2022-06-30T19:42:46.801Z,1656618166.801 [NAL9602](INFO): GPS fix at 20220630T194246: (36.802813, -121.788027)
2022-06-30T19:42:46.811Z,1656618166.811 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:42:46.811Z,1656618166.811 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:42:54.844Z,1656618174.844 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220630T184720/Courier0030.lzma
2022-06-30T19:42:55.846Z,1656618175.846 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0030.lzma.bak
2022-06-30T19:42:55.847Z,1656618175.847 [DataOverHttps](INFO): SBD MOMSN=16948401
2022-06-30T19:43:12.124Z,1656618192.124 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20220630T184720/Express0031.lzma
2022-06-30T19:43:13.127Z,1656618193.127 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0031.lzma.bak
2022-06-30T19:43:13.127Z,1656618193.127 [DataOverHttps](INFO): SBD MOMSN=16948404
2022-06-30T19:43:14.273Z,1656618194.273 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:43:14.273Z,1656618194.273 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:43:14.274Z,1656618194.274 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:43:17.490Z,1656618197.490 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-06-30T19:43:17.565Z,1656618197.565 [NAL9602](ERROR): received: 
+CSQ:0

OK771, 2, 0, 0, 0

OK
2022-06-30T19:48:06.351Z,1656618486.351 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:48:06.351Z,1656618486.351 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:48:06.758Z,1656618486.758 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-30T19:48:08.562Z,1656618488.562 [DAT](INFO): DAT read: user:5>Rx Time:19:48:01.8283

2022-06-30T19:48:09.065Z,1656618489.065 [DAT](INFO): DAT read: 

2022-06-30T19:48:09.066Z,1656618489.066 [DAT](INFO): DAT read: $Packet for address 50

2022-06-30T19:48:09.066Z,1656618489.066 [DAT](INFO): received a packet notification
2022-06-30T19:48:14.849Z,1656618494.849 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:48:14.849Z,1656618494.849 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:48:14.849Z,1656618494.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:48:14.850Z,1656618494.850 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:48:15.264Z,1656618495.264 [Default:CheckIn:D] Stopped
2022-06-30T19:48:15.264Z,1656618495.264 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:48:15.716Z,1656618495.716 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.152840 min 
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn:E] Stopped
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn] Stopped
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn](INFO): Running loop #12
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn] Running Loop=12
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:48:15.721Z,1656618495.721 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:48:17.665Z,1656618497.665 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194816.00,A,3648.16651,N,12147.28158,W,0.447,152.48,300622,,,A*7C
2022-06-30T19:48:17.667Z,1656618497.667 [NAL9602](INFO): GPS fix at 20220630T194816: (36.802775, -121.788026)
2022-06-30T19:48:17.727Z,1656618497.727 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:48:17.727Z,1656618497.727 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:48:25.676Z,1656618505.676 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0033.lzma
2022-06-30T19:48:26.678Z,1656618506.678 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0033.lzma.bak
2022-06-30T19:48:26.679Z,1656618506.679 [DataOverHttps](INFO): SBD MOMSN=16948407
2022-06-30T19:48:43.569Z,1656618523.569 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220630T184720/Express0034.lzma
2022-06-30T19:48:44.571Z,1656618524.571 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0034.lzma.bak
2022-06-30T19:48:44.571Z,1656618524.571 [DataOverHttps](INFO): SBD MOMSN=16948410
2022-06-30T19:48:45.959Z,1656618525.959 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:48:45.960Z,1656618525.960 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:48:45.960Z,1656618525.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:48:47.561Z,1656618527.561 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-06-30T19:48:47.563Z,1656618527.563 [BPC1](INFO): Received data from all battery sticks.
2022-06-30T19:48:50.386Z,1656618530.386 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T19:49:10.590Z,1656618550.590 [DAT](INFO): DAT read: Rx Time:19:49:03.8831

2022-06-30T19:49:10.591Z,1656618550.591 [DAT](INFO): received an acoustic signal
2022-06-30T19:49:11.094Z,1656618551.094 [DAT](INFO): DAT read: $Error in header

2022-06-30T19:49:11.094Z,1656618551.094 [DAT](INFO): Received a bad header
2022-06-30T19:49:19.473Z,1656618559.473 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-06-30T19:49:19.474Z,1656618559.474 [DropWeight] Hardware Fault, FailCount= 1
2022-06-30T19:49:19.474Z,1656618559.474 [DropWeight](ERROR): Hardware Fault
2022-06-30T19:49:19.542Z,1656618559.542 [CommandExec](FAULT): Scheduling is paused
2022-06-30T19:49:19.542Z,1656618559.542 [CBIT](INFO): Critical error at 20220630T194919
2022-06-30T19:49:19.545Z,1656618559.545 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-06-30T19:49:19.545Z,1656618559.545 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-06-30T19:49:19.898Z,1656618559.898 [CBIT](INFO): Critical error at 20220630T194919
2022-06-30T19:53:46.539Z,1656618826.539 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:53:46.539Z,1656618826.539 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:53:46.539Z,1656618826.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:53:46.539Z,1656618826.539 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:53:46.935Z,1656618826.935 [Default:CheckIn:D] Stopped
2022-06-30T19:53:46.935Z,1656618826.935 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:53:47.394Z,1656618827.394 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.680697 min 
2022-06-30T19:53:47.394Z,1656618827.394 [Default:CheckIn:E] Stopped
2022-06-30T19:53:47.394Z,1656618827.394 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:53:47.394Z,1656618827.394 [Default:CheckIn] Stopped
2022-06-30T19:53:47.394Z,1656618827.394 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:53:47.395Z,1656618827.395 [Default:CheckIn](INFO): Running loop #13
2022-06-30T19:53:47.395Z,1656618827.395 [Default:CheckIn] Running Loop=13
2022-06-30T19:53:47.395Z,1656618827.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:53:47.395Z,1656618827.395 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:53:49.378Z,1656618829.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195348.00,A,3648.16644,N,12147.28107,W,0.253,214.64,300622,,,A*7F
2022-06-30T19:53:49.386Z,1656618829.386 [NAL9602](INFO): GPS fix at 20220630T195348: (36.802774, -121.788018)
2022-06-30T19:53:49.431Z,1656618829.431 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:53:49.431Z,1656618829.431 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:53:57.348Z,1656618837.348 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20220630T184720/Courier0036.lzma
2022-06-30T19:53:58.343Z,1656618838.343 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0036.lzma.bak
2022-06-30T19:53:58.344Z,1656618838.344 [DataOverHttps](INFO): SBD MOMSN=16948414
2022-06-30T19:54:15.192Z,1656618855.192 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20220630T184720/Express0037.lzma
2022-06-30T19:54:16.195Z,1656618856.195 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0037.lzma.bak
2022-06-30T19:54:16.195Z,1656618856.195 [DataOverHttps](INFO): SBD MOMSN=16948419
2022-06-30T19:54:17.674Z,1656618857.674 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:54:17.674Z,1656618857.674 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:54:17.674Z,1656618857.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:55:51.389Z,1656618951.389 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:55:51.389Z,1656618951.389 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:56:13.185Z,1656618973.185 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:56:13.185Z,1656618973.185 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:57:10.555Z,1656619030.555 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:57:10.555Z,1656619030.555 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:57:25.503Z,1656619045.503 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:57:25.503Z,1656619045.503 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:57:57.423Z,1656619077.423 [NAL9602](INFO): SBD MO Status=2, MOMSN=19771, MT Status=2, MTMSN=0
2022-06-30T19:57:57.423Z,1656619077.423 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-06-30T19:58:51.558Z,1656619131.558 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-06-30T19:59:18.237Z,1656619158.237 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T19:59:18.237Z,1656619158.237 [Default:CheckIn:C.Wait] Stopped
2022-06-30T19:59:18.237Z,1656619158.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T19:59:18.238Z,1656619158.238 [Default:CheckIn:D] Running Loop=1
2022-06-30T19:59:18.653Z,1656619158.653 [Default:CheckIn:D] Stopped
2022-06-30T19:59:18.653Z,1656619158.653 [Default:CheckIn:E] Running Loop=1
2022-06-30T19:59:19.044Z,1656619159.044 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.209326 min 
2022-06-30T19:59:19.044Z,1656619159.044 [Default:CheckIn:E] Stopped
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn] Stopped
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn](INFO): Running loop #14
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn] Running Loop=14
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T19:59:19.045Z,1656619159.045 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T19:59:21.051Z,1656619161.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195920.00,A,3648.17676,N,12147.27459,W,0.408,347.51,300622,,,A*73
2022-06-30T19:59:21.054Z,1656619161.054 [NAL9602](INFO): GPS fix at 20220630T195920: (36.802946, -121.787910)
2022-06-30T19:59:21.064Z,1656619161.064 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T19:59:21.064Z,1656619161.064 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T19:59:29.037Z,1656619169.037 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0039.lzma
2022-06-30T19:59:30.039Z,1656619170.039 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0039.lzma.bak
2022-06-30T19:59:30.039Z,1656619170.039 [DataOverHttps](INFO): SBD MOMSN=16948424
2022-06-30T19:59:46.432Z,1656619186.432 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20220630T184720/Express0040.lzma
2022-06-30T19:59:47.435Z,1656619187.435 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0040.lzma.bak
2022-06-30T19:59:47.435Z,1656619187.435 [DataOverHttps](INFO): SBD MOMSN=16948428
2022-06-30T19:59:48.940Z,1656619188.940 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T19:59:48.941Z,1656619188.941 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T19:59:48.941Z,1656619188.941 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T19:59:53.408Z,1656619193.408 [NAL9602](INFO): Not Powering down - fast GPS
2022-06-30T20:04:19.651Z,1656619459.651 [CBIT](INFO): Clearing failed state for component DropWeight
2022-06-30T20:04:19.651Z,1656619459.651 [DropWeight] No Fault, FailCount= 1
2022-06-30T20:04:49.546Z,1656619489.546 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-06-30T20:04:49.546Z,1656619489.546 [Default:CheckIn:C.Wait] Stopped
2022-06-30T20:04:49.546Z,1656619489.546 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T20:04:49.546Z,1656619489.546 [Default:CheckIn:D] Running Loop=1
2022-06-30T20:04:49.916Z,1656619489.916 [Default:CheckIn:D] Stopped
2022-06-30T20:04:49.916Z,1656619489.916 [Default:CheckIn:E] Running Loop=1
2022-06-30T20:04:50.325Z,1656619490.325 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.730363 min 
2022-06-30T20:04:50.325Z,1656619490.325 [Default:CheckIn:E] Stopped
2022-06-30T20:04:50.325Z,1656619490.325 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-06-30T20:04:50.325Z,1656619490.325 [Default:CheckIn] Stopped
2022-06-30T20:04:50.325Z,1656619490.325 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T20:04:50.326Z,1656619490.326 [Default:CheckIn](INFO): Running loop #15
2022-06-30T20:04:50.326Z,1656619490.326 [Default:CheckIn] Running Loop=15
2022-06-30T20:04:50.326Z,1656619490.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-06-30T20:04:50.326Z,1656619490.326 [Default:CheckIn:Read_GPS] Running Loop=1
2022-06-30T20:04:52.328Z,1656619492.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200451.00,A,3648.16946,N,12147.27572,W,1.341,182.86,300622,,,A*78
2022-06-30T20:04:52.330Z,1656619492.330 [NAL9602](INFO): GPS fix at 20220630T200451: (36.802824, -121.787929)
2022-06-30T20:04:52.384Z,1656619492.384 [Default:CheckIn:Read_GPS] Stopped
2022-06-30T20:04:52.384Z,1656619492.384 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-06-30T20:04:59.964Z,1656619499.964 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220630T184720/Courier0042.lzma
2022-06-30T20:05:00.966Z,1656619500.966 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Courier0042.lzma.bak
2022-06-30T20:05:00.967Z,1656619500.967 [DataOverHttps](INFO): SBD MOMSN=16948433
2022-06-30T20:05:17.420Z,1656619517.420 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220630T184720/Express0043.lzma
2022-06-30T20:05:41.514Z,1656619541.514 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2022-06-30T20:05:49.260Z,1656619549.260 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220630T184720/Express0043.lzma
2022-06-30T20:05:50.265Z,1656619550.265 [DataOverHttps](INFO): Moved sent file to Logs/20220630T184720/Express0043.lzma.bak
2022-06-30T20:05:50.266Z,1656619550.266 [DataOverHttps](INFO): SBD MOMSN=16948436
2022-06-30T20:05:51.748Z,1656619551.748 [Default:CheckIn:Read_Iridium] Stopped
2022-06-30T20:05:51.748Z,1656619551.748 [Default:CheckIn:C.Wait] Running Loop=1
2022-06-30T20:05:51.748Z,1656619551.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-06-30T20:06:57.013Z,1656619617.013 [CommandExec](IMPORTANT): got command quit
2022-06-30T20:06:58.017Z,1656619618.017 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:58.017Z,1656619618.017 [CommandExec](INFO): Uninitializing the command executive.
2022-06-30T20:06:58.017Z,1656619618.017 [CommandExec](INFO): Uninitializing the command scheduler.
2022-06-30T20:06:58.017Z,1656619618.017 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.149Z,1656619618.149 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-06-30T20:06:58.149Z,1656619618.149 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-06-30T20:06:58.149Z,1656619618.149 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.150Z,1656619618.150 [NavChartDb](INFO): Join timeout helper Thread ID is 7960
2022-06-30T20:06:58.150Z,1656619618.150 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:58.151Z,1656619618.151 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.151Z,1656619618.151 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2022-06-30T20:06:58.151Z,1656619618.151 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.152Z,1656619618.152 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7961
2022-06-30T20:06:58.161Z,1656619618.161 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:58.161Z,1656619618.161 [WetLabsUBAT](INFO): Powering down
2022-06-30T20:06:58.162Z,1656619618.162 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.170Z,1656619618.170 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-06-30T20:06:58.170Z,1656619618.170 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.171Z,1656619618.171 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7962
2022-06-30T20:06:58.573Z,1656619618.573 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:58.573Z,1656619618.573 [WetLabsBB2FL](INFO): Powering down
2022-06-30T20:06:58.574Z,1656619618.574 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.577Z,1656619618.577 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-06-30T20:06:58.577Z,1656619618.577 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:58.577Z,1656619618.577 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7963
2022-06-30T20:06:59.354Z,1656619619.354 [CTD_Seabird](INFO): Powering down
2022-06-30T20:06:59.369Z,1656619619.369 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:59.371Z,1656619619.371 [CTD_Seabird](INFO): Powering down
2022-06-30T20:06:59.385Z,1656619619.385 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:59.385Z,1656619619.385 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-06-30T20:06:59.385Z,1656619619.385 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:59.386Z,1656619619.386 [DAT](INFO): Join timeout helper Thread ID is 7964
2022-06-30T20:06:59.413Z,1656619619.413 [DAT](INFO): Powering down
2022-06-30T20:06:59.485Z,1656619619.485 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:59.486Z,1656619619.486 [DAT](INFO): Powering down
2022-06-30T20:06:59.486Z,1656619619.486 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:59.494Z,1656619619.494 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-06-30T20:06:59.494Z,1656619619.494 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:59.494Z,1656619619.494 [Radio_Surface](INFO): Join timeout helper Thread ID is 7965
2022-06-30T20:06:59.773Z,1656619619.773 [Radio_Surface](INFO): Powering down
2022-06-30T20:06:59.774Z,1656619619.774 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:06:59.774Z,1656619619.774 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:59.789Z,1656619619.789 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-06-30T20:06:59.789Z,1656619619.789 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:06:59.790Z,1656619619.790 [Onboard](INFO): Join timeout helper Thread ID is 7966
2022-06-30T20:06:59.869Z,1656619619.869 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-06-30T20:07:02.449Z,1656619622.449 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:07:02.449Z,1656619622.449 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.461Z,1656619622.461 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-06-30T20:07:02.462Z,1656619622.462 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.462Z,1656619622.462 [DataOverHttps](INFO): Join timeout helper Thread ID is 7967
2022-06-30T20:07:02.881Z,1656619622.881 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:07:02.881Z,1656619622.881 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.901Z,1656619622.901 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-06-30T20:07:02.901Z,1656619622.901 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.902Z,1656619622.902 [BackseatComponent](INFO): Join timeout helper Thread ID is 7968
2022-06-30T20:07:02.933Z,1656619622.933 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:07:02.933Z,1656619622.933 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.947Z,1656619622.947 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-06-30T20:07:02.947Z,1656619622.947 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.947Z,1656619622.947 [logger](INFO): Join timeout helper Thread ID is 7969
2022-06-30T20:07:02.961Z,1656619622.961 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:07:02.961Z,1656619622.961 [logger ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.962Z,1656619622.962 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-06-30T20:07:02.963Z,1656619622.963 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.963Z,1656619622.963 [CommandLine](INFO): Join timeout helper Thread ID is 7970
2022-06-30T20:07:02.977Z,1656619622.977 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:07:02.977Z,1656619622.977 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.981Z,1656619622.981 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-06-30T20:07:02.981Z,1656619622.981 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.982Z,1656619622.982 [CommandExec](INFO): Join timeout helper Thread ID is 7971
2022-06-30T20:07:02.983Z,1656619622.983 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-06-30T20:07:02.983Z,1656619622.983 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:02.984Z,1656619622.984 [controlThread](INFO): Join timeout helper Thread ID is 7972
2022-06-30T20:07:03.209Z,1656619623.209 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-06-30T20:07:03.209Z,1656619623.209 [controlThread](DEBUG): Uninitializing ControlThread
2022-06-30T20:07:03.209Z,1656619623.209 [AHRS_M2](INFO): Powering down
2022-06-30T20:07:03.282Z,1656619623.282 [NAL9602](INFO): Powering down
2022-06-30T20:07:03.353Z,1656619623.353 [RDI_Pathfinder](INFO): Powering down
2022-06-30T20:07:03.354Z,1656619623.354 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-06-30T20:07:03.355Z,1656619623.355 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-06-30T20:07:03.355Z,1656619623.355 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-06-30T20:07:03.356Z,1656619623.356 [MissionManager](INFO): Uninitializing Mission Default
2022-06-30T20:07:03.356Z,1656619623.356 [Default] Stopped
2022-06-30T20:07:03.356Z,1656619623.356 [Default](DEBUG): Aggregate::uninitialize Default
2022-06-30T20:07:03.356Z,1656619623.356 [Default:B.GoToSurface] Stopped
2022-06-30T20:07:03.356Z,1656619623.356 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-06-30T20:07:03.356Z,1656619623.356 [Default:CheckIn] Stopped
2022-06-30T20:07:03.357Z,1656619623.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-06-30T20:07:03.357Z,1656619623.357 [Default:CheckIn:C.Wait] Stopped
2022-06-30T20:07:03.357Z,1656619623.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-06-30T20:07:03.359Z,1656619623.359 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-06-30T20:07:03.360Z,1656619623.360 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-06-30T20:07:03.360Z,1656619623.360 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-06-30T20:07:03.360Z,1656619623.360 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-06-30T20:07:03.361Z,1656619623.361 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-06-30T20:07:03.361Z,1656619623.361 [BuoyancyServo](INFO): Powering down
2022-06-30T20:07:03.373Z,1656619623.373 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-06-30T20:07:03.373Z,1656619623.373 [ElevatorServo](INFO): Powering down
2022-06-30T20:07:03.374Z,1656619623.374 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-06-30T20:07:03.374Z,1656619623.374 [MassServo](INFO): Powering down
2022-06-30T20:07:03.375Z,1656619623.375 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-06-30T20:07:03.375Z,1656619623.375 [RudderServo](INFO): Powering down
2022-06-30T20:07:03.376Z,1656619623.376 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-06-30T20:07:03.376Z,1656619623.376 [ThrusterServo](INFO): Powering down
2022-06-30T20:07:03.377Z,1656619623.377 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-06-30T20:07:03.377Z,1656619623.377 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-06-30T20:07:03.377Z,1656619623.377 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-06-30T20:07:03.377Z,1656619623.377 [CBIT](DEBUG): Powering off loads.
2022-06-30T20:07:03.388Z,1656619623.388 [CBIT](DEBUG): Disabling WDT.
2022-06-30T20:07:03.400Z,1656619623.400 [CBIT](DEBUG): Opening all GF detection circuits.
2022-06-30T20:07:03.401Z,1656619623.401 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.451Z,1656619623.451 [DAT ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.459Z,1656619623.459 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.461Z,1656619623.461 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.467Z,1656619623.467 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.510Z,1656619623.510 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.575Z,1656619623.575 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.577Z,1656619623.577 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.580Z,1656619623.580 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.588Z,1656619623.588 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-06-30T20:07:03.669Z,1656619623.669 [logger ThreadHandler](INFO): Thread cancelled.