2023-02-17T22:43:46.163Z,1676673826.163 [CommandExec](IMPORTANT): got command restart logs
2023-02-17T22:43:47.146Z,1676673827.146 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003085
2023-02-17T22:43:59.160Z,1676673839.160 [NAL9602](INFO): SBD MO Status=1, MOMSN=22598, MT Status=0, MTMSN=0
2023-02-17T22:43:59.210Z,1676673839.210 [NAL9602](INFO): Sent 73 bytes from file Logs/20230217T050349/Courier0117.lzma
2023-02-17T22:43:59.210Z,1676673839.210 [NAL9602](INFO): Packets left to send: 0
2023-02-17T22:44:03.413Z,1676673843.413 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230217T050349/Courier0120.lzma
2023-02-17T22:44:04.416Z,1676673844.416 [DataOverHttps](INFO): Moved sent file to Logs/20230217T050349/Courier0120.lzma.bak
2023-02-17T22:44:04.416Z,1676673844.416 [DataOverHttps](INFO): SBD MOMSN=17482713
2023-02-17T22:44:21.509Z,1676673861.509 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230217T050349/Express0118.lzma
2023-02-17T22:44:22.512Z,1676673862.512 [DataOverHttps](INFO): Moved sent file to Logs/20230217T050349/Express0118.lzma.bak
2023-02-17T22:44:22.512Z,1676673862.512 [DataOverHttps](INFO): SBD MOMSN=17482715
2023-02-17T22:44:29.866Z,1676673869.866 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T22:44:38.725Z,1676673878.725 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230217T050349/Express0121.lzma
2023-02-17T22:44:39.728Z,1676673879.728 [DataOverHttps](INFO): Moved sent file to Logs/20230217T050349/Express0121.lzma.bak
2023-02-17T22:44:39.728Z,1676673879.728 [DataOverHttps](INFO): SBD MOMSN=17482719
2023-02-17T22:44:41.205Z,1676673881.205 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T22:44:41.205Z,1676673881.205 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T22:44:41.205Z,1676673881.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T22:49:41.777Z,1676674181.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T22:49:41.777Z,1676674181.777 [Default:CheckIn:C.Wait] Stopped
2023-02-17T22:49:41.777Z,1676674181.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T22:49:41.777Z,1676674181.777 [Default:CheckIn:D] Running Loop=1
2023-02-17T22:49:42.170Z,1676674182.170 [Default:CheckIn:D] Stopped
2023-02-17T22:49:42.170Z,1676674182.170 [Default:CheckIn:E] Running Loop=1
2023-02-17T22:49:42.574Z,1676674182.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.094548 min 
2023-02-17T22:49:42.574Z,1676674182.574 [Default:CheckIn:E] Stopped
2023-02-17T22:49:42.574Z,1676674182.574 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T22:49:42.575Z,1676674182.575 [Default:CheckIn] Stopped
2023-02-17T22:49:42.575Z,1676674182.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T22:49:42.575Z,1676674182.575 [Default:CheckIn](INFO): Running loop #10
2023-02-17T22:49:42.575Z,1676674182.575 [Default:CheckIn] Running Loop=10
2023-02-17T22:49:42.575Z,1676674182.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T22:49:42.575Z,1676674182.575 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T22:49:44.590Z,1676674184.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224943.00,A,3648.32348,N,12147.13095,W,2.099,179.73,170223,,,D*77
2023-02-17T22:49:44.592Z,1676674184.592 [NAL9602](INFO): GPS fix at 20230217T224943: (36.805391, -121.785516)
2023-02-17T22:49:44.604Z,1676674184.604 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T22:49:44.604Z,1676674184.604 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T22:49:51.877Z,1676674191.877 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230217T224346/Courier0003.lzma
2023-02-17T22:49:52.879Z,1676674192.879 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0003.lzma.bak
2023-02-17T22:49:52.879Z,1676674192.879 [DataOverHttps](INFO): SBD MOMSN=17482724
2023-02-17T22:50:09.365Z,1676674209.365 [DataOverHttps](INFO): Sending 395 bytes from file Logs/20230217T224346/Express0004.lzma
2023-02-17T22:50:10.367Z,1676674210.367 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0004.lzma.bak
2023-02-17T22:50:10.367Z,1676674210.367 [DataOverHttps](INFO): SBD MOMSN=17482728
2023-02-17T22:50:11.669Z,1676674211.669 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T22:50:11.669Z,1676674211.669 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T22:50:11.669Z,1676674211.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T22:50:21.350Z,1676674221.350 [NAL9602](INFO): SBD MO Status=2, MOMSN=22599, MT Status=2, MTMSN=0
2023-02-17T22:50:21.350Z,1676674221.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T22:50:52.856Z,1676674252.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=22599, MT Status=2, MTMSN=0
2023-02-17T22:50:52.856Z,1676674252.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T22:51:23.165Z,1676674283.165 [NAL9602](INFO): SBD MO Status=2, MOMSN=22599, MT Status=2, MTMSN=0
2023-02-17T22:51:23.166Z,1676674283.166 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T22:51:45.788Z,1676674305.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=22599, MT Status=2, MTMSN=0
2023-02-17T22:51:45.788Z,1676674305.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T22:51:59.520Z,1676674319.520 [NAL9602](INFO): SBD MO Status=0, MOMSN=22599, MT Status=0, MTMSN=0
2023-02-17T22:51:59.520Z,1676674319.520 [NAL9602](INFO): No messages in MT queue
2023-02-17T22:52:30.223Z,1676674350.223 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T22:52:59.311Z,1676674379.311 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2023-02-17T22:55:12.235Z,1676674512.235 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T22:55:12.235Z,1676674512.235 [Default:CheckIn:C.Wait] Stopped
2023-02-17T22:55:12.235Z,1676674512.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T22:55:12.235Z,1676674512.235 [Default:CheckIn:D] Running Loop=1
2023-02-17T22:55:12.645Z,1676674512.645 [Default:CheckIn:D] Stopped
2023-02-17T22:55:12.645Z,1676674512.645 [Default:CheckIn:E] Running Loop=1
2023-02-17T22:55:13.083Z,1676674513.083 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.602466 min 
2023-02-17T22:55:13.083Z,1676674513.083 [Default:CheckIn:E] Stopped
2023-02-17T22:55:13.083Z,1676674513.083 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T22:55:13.083Z,1676674513.083 [Default:CheckIn] Stopped
2023-02-17T22:55:13.083Z,1676674513.083 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T22:55:13.083Z,1676674513.083 [Default:CheckIn](INFO): Running loop #11
2023-02-17T22:55:13.084Z,1676674513.084 [Default:CheckIn] Running Loop=11
2023-02-17T22:55:13.084Z,1676674513.084 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T22:55:13.084Z,1676674513.084 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T22:55:15.058Z,1676674515.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225514.00,A,3648.14941,N,12147.22264,W,0.058,235.16,170223,,,D*76
2023-02-17T22:55:15.060Z,1676674515.060 [NAL9602](INFO): GPS fix at 20230217T225514: (36.802490, -121.787044)
2023-02-17T22:55:15.111Z,1676674515.111 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T22:55:15.111Z,1676674515.111 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T22:55:23.045Z,1676674523.045 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230217T224346/Courier0006.lzma
2023-02-17T22:55:24.043Z,1676674524.043 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0006.lzma.bak
2023-02-17T22:55:24.043Z,1676674524.043 [DataOverHttps](INFO): SBD MOMSN=17482760
2023-02-17T22:55:37.276Z,1676674537.276 [NAL9602](INFO): SBD MO Status=0, MOMSN=22600, MT Status=0, MTMSN=0
2023-02-17T22:55:37.276Z,1676674537.276 [NAL9602](INFO): No messages in MT queue
2023-02-17T22:55:40.641Z,1676674540.641 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230217T224346/Express0007.lzma
2023-02-17T22:55:41.643Z,1676674541.643 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0007.lzma.bak
2023-02-17T22:55:41.643Z,1676674541.643 [DataOverHttps](INFO): SBD MOMSN=17482764
2023-02-17T22:55:42.953Z,1676674542.953 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T22:55:42.953Z,1676674542.953 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T22:55:42.953Z,1676674542.953 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T22:56:07.974Z,1676674567.974 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T23:00:43.519Z,1676674843.519 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:00:43.519Z,1676674843.519 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:00:43.519Z,1676674843.519 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:00:43.520Z,1676674843.520 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:00:43.929Z,1676674843.929 [Default:CheckIn:D] Stopped
2023-02-17T23:00:43.929Z,1676674843.929 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:00:44.326Z,1676674844.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.123869 min 
2023-02-17T23:00:44.326Z,1676674844.326 [Default:CheckIn:E] Stopped
2023-02-17T23:00:44.326Z,1676674844.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:00:44.326Z,1676674844.326 [Default:CheckIn] Stopped
2023-02-17T23:00:44.326Z,1676674844.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:00:44.326Z,1676674844.326 [Default:CheckIn](INFO): Running loop #12
2023-02-17T23:00:44.327Z,1676674844.327 [Default:CheckIn] Running Loop=12
2023-02-17T23:00:44.327Z,1676674844.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:00:44.327Z,1676674844.327 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:00:46.333Z,1676674846.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230045.00,A,3648.14240,N,12147.22984,W,0.019,218.48,170223,,,D*7D
2023-02-17T23:00:46.335Z,1676674846.335 [NAL9602](INFO): GPS fix at 20230217T230045: (36.802373, -121.787164)
2023-02-17T23:00:46.361Z,1676674846.361 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:00:46.361Z,1676674846.361 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:00:54.529Z,1676674854.529 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230217T224346/Courier0009.lzma
2023-02-17T23:00:55.531Z,1676674855.531 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0009.lzma.bak
2023-02-17T23:00:55.531Z,1676674855.531 [DataOverHttps](INFO): SBD MOMSN=17482774
2023-02-17T23:00:58.451Z,1676674858.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=22601, MT Status=0, MTMSN=0
2023-02-17T23:00:58.451Z,1676674858.451 [NAL9602](INFO): No messages in MT queue
2023-02-17T23:01:11.733Z,1676674871.733 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230217T224346/Express0010.lzma
2023-02-17T23:01:12.735Z,1676674872.735 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0010.lzma.bak
2023-02-17T23:01:12.735Z,1676674872.735 [DataOverHttps](INFO): SBD MOMSN=17482777
2023-02-17T23:01:14.222Z,1676674874.222 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:01:14.222Z,1676674874.222 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:01:14.222Z,1676674874.222 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:01:29.159Z,1676674889.159 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T23:06:14.801Z,1676675174.801 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:06:14.801Z,1676675174.801 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:06:14.801Z,1676675174.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:06:14.801Z,1676675174.801 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:06:15.222Z,1676675175.222 [Default:CheckIn:D] Stopped
2023-02-17T23:06:15.222Z,1676675175.222 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.645426 min 
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn:E] Stopped
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn] Stopped
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn](INFO): Running loop #13
2023-02-17T23:06:15.603Z,1676675175.603 [Default:CheckIn] Running Loop=13
2023-02-17T23:06:15.604Z,1676675175.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:06:15.604Z,1676675175.604 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:06:17.616Z,1676675177.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230616.00,A,3648.17173,N,12147.27302,W,0.350,287.53,170223,,,D*7E
2023-02-17T23:06:17.623Z,1676675177.623 [NAL9602](INFO): GPS fix at 20230217T230616: (36.802862, -121.787884)
2023-02-17T23:06:17.635Z,1676675177.635 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:06:17.635Z,1676675177.635 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:06:25.185Z,1676675185.185 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230217T224346/Courier0012.lzma
2023-02-17T23:06:26.187Z,1676675186.187 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0012.lzma.bak
2023-02-17T23:06:26.187Z,1676675186.187 [DataOverHttps](INFO): SBD MOMSN=17482795
2023-02-17T23:06:42.561Z,1676675202.561 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230217T224346/Express0013.lzma
2023-02-17T23:06:43.563Z,1676675203.563 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0013.lzma.bak
2023-02-17T23:06:43.563Z,1676675203.563 [DataOverHttps](INFO): SBD MOMSN=17482798
2023-02-17T23:06:45.137Z,1676675205.137 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:06:45.137Z,1676675205.137 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:06:45.137Z,1676675205.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:09:19.076Z,1676675359.076 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2023-02-17T23:11:20.209Z,1676675480.209 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-17T23:11:45.692Z,1676675505.692 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:11:45.693Z,1676675505.693 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:11:45.693Z,1676675505.693 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:11:45.693Z,1676675505.693 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:11:46.077Z,1676675506.077 [Default:CheckIn:D] Stopped
2023-02-17T23:11:46.077Z,1676675506.077 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:11:46.478Z,1676675506.478 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.159676 min 
2023-02-17T23:11:46.478Z,1676675506.478 [Default:CheckIn:E] Stopped
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn] Stopped
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn](INFO): Running loop #14
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn] Running Loop=14
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:11:46.479Z,1676675506.479 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:11:48.488Z,1676675508.488 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231147.00,A,3648.17012,N,12147.28553,W,0.350,21.76,170223,,,A*4B
2023-02-17T23:11:48.490Z,1676675508.490 [NAL9602](INFO): GPS fix at 20230217T231147: (36.802835, -121.788092)
2023-02-17T23:11:48.517Z,1676675508.517 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:11:48.518Z,1676675508.518 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:11:56.741Z,1676675516.741 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230217T224346/Courier0015.lzma
2023-02-17T23:11:57.743Z,1676675517.743 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0015.lzma.bak
2023-02-17T23:11:57.743Z,1676675517.743 [DataOverHttps](INFO): SBD MOMSN=17482808
2023-02-17T23:12:14.313Z,1676675534.313 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20230217T224346/Express0016.lzma
2023-02-17T23:12:15.315Z,1676675535.315 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0016.lzma.bak
2023-02-17T23:12:15.315Z,1676675535.315 [DataOverHttps](INFO): SBD MOMSN=17482811
2023-02-17T23:12:16.787Z,1676675536.787 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:12:16.787Z,1676675536.787 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:12:16.787Z,1676675536.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:12:20.122Z,1676675540.122 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T23:13:05.275Z,1676675585.275 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2023-02-17T23:17:17.382Z,1676675837.382 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:17:17.383Z,1676675837.383 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:17:17.383Z,1676675837.383 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:17:17.383Z,1676675837.383 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:17:17.765Z,1676675837.765 [Default:CheckIn:D] Stopped
2023-02-17T23:17:17.765Z,1676675837.765 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:17:18.224Z,1676675838.224 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.687801 min 
2023-02-17T23:17:18.224Z,1676675838.224 [Default:CheckIn:E] Stopped
2023-02-17T23:17:18.224Z,1676675838.224 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:17:18.225Z,1676675838.225 [Default:CheckIn] Stopped
2023-02-17T23:17:18.225Z,1676675838.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:17:18.225Z,1676675838.225 [Default:CheckIn](INFO): Running loop #15
2023-02-17T23:17:18.225Z,1676675838.225 [Default:CheckIn] Running Loop=15
2023-02-17T23:17:18.225Z,1676675838.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:17:18.225Z,1676675838.225 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:17:18.564Z,1676675838.564 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2023-02-17T23:17:18.567Z,1676675838.567 [BPC1](INFO): Received data from all battery sticks.
2023-02-17T23:17:20.177Z,1676675840.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231719.00,A,3648.16715,N,12147.28217,W,0.311,21.76,170223,,,A*45
2023-02-17T23:17:20.179Z,1676675840.179 [NAL9602](INFO): GPS fix at 20230217T231719: (36.802786, -121.788036)
2023-02-17T23:17:20.233Z,1676675840.233 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:17:20.233Z,1676675840.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:17:27.910Z,1676675847.910 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20230217T224346/Courier0018.lzma
2023-02-17T23:17:28.911Z,1676675848.911 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0018.lzma.bak
2023-02-17T23:17:28.911Z,1676675848.911 [DataOverHttps](INFO): SBD MOMSN=17482829
2023-02-17T23:17:45.149Z,1676675865.149 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230217T224346/Express0019.lzma
2023-02-17T23:17:46.151Z,1676675866.151 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0019.lzma.bak
2023-02-17T23:17:46.151Z,1676675866.151 [DataOverHttps](INFO): SBD MOMSN=17482832
2023-02-17T23:17:47.671Z,1676675867.671 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:17:47.671Z,1676675867.671 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:17:47.671Z,1676675867.671 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:17:50.879Z,1676675870.879 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-02-17T23:17:50.953Z,1676675870.953 [NAL9602](ERROR): received: 
+CSQ:0

OK601, 0, 0, 0, 0

OK
2023-02-17T23:20:24.404Z,1676676024.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=22602, MT Status=2, MTMSN=0
2023-02-17T23:20:24.404Z,1676676024.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T23:22:09.852Z,1676676129.852 [NAL9602](INFO): SBD MO Status=2, MOMSN=22602, MT Status=2, MTMSN=0
2023-02-17T23:22:09.852Z,1676676129.852 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T23:22:22.375Z,1676676142.375 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-17T23:22:48.253Z,1676676168.253 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:22:48.254Z,1676676168.254 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:22:48.254Z,1676676168.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:22:48.254Z,1676676168.254 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:22:48.654Z,1676676168.654 [Default:CheckIn:D] Stopped
2023-02-17T23:22:48.654Z,1676676168.654 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:22:49.089Z,1676676169.089 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.202620 min 
2023-02-17T23:22:49.089Z,1676676169.089 [Default:CheckIn:E] Stopped
2023-02-17T23:22:49.089Z,1676676169.089 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:22:49.089Z,1676676169.089 [Default:CheckIn] Stopped
2023-02-17T23:22:49.090Z,1676676169.090 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:22:49.090Z,1676676169.090 [Default:CheckIn](INFO): Running loop #16
2023-02-17T23:22:49.090Z,1676676169.090 [Default:CheckIn] Running Loop=16
2023-02-17T23:22:49.090Z,1676676169.090 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:22:49.090Z,1676676169.090 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:22:51.062Z,1676676171.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232250.00,A,3648.16666,N,12147.28310,W,0.700,0.00,170223,,,A*7B
2023-02-17T23:22:51.064Z,1676676171.064 [NAL9602](INFO): GPS fix at 20230217T232250: (36.802778, -121.788052)
2023-02-17T23:22:51.123Z,1676676171.123 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:22:51.123Z,1676676171.123 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:22:52.268Z,1676676172.268 [NAL9602](FAULT): LCB fault:  Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2023-02-17T23:22:52.268Z,1676676172.268 [NAL9602] Hardware Fault, FailCount= 1
2023-02-17T23:22:52.268Z,1676676172.268 [NAL9602](ERROR): Hardware Fault
2023-02-17T23:22:52.340Z,1676676172.340 [CBIT](ERROR): Hardware Fault in component: NAL9602
2023-02-17T23:22:52.671Z,1676676172.671 [NAL9602](INFO): Powering down
2023-02-17T23:22:53.515Z,1676676173.515 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-17T23:22:53.515Z,1676676173.515 [NAL9602] No Fault, FailCount= 1
2023-02-17T23:22:58.853Z,1676676178.853 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230217T224346/Courier0021.lzma
2023-02-17T23:22:59.856Z,1676676179.856 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0021.lzma.bak
2023-02-17T23:22:59.856Z,1676676179.856 [DataOverHttps](INFO): SBD MOMSN=17482842
2023-02-17T23:23:15.961Z,1676676195.961 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230217T224346/Express0022.lzma
2023-02-17T23:23:16.966Z,1676676196.966 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0022.lzma.bak
2023-02-17T23:23:16.966Z,1676676196.966 [DataOverHttps](INFO): SBD MOMSN=17482845
2023-02-17T23:23:18.549Z,1676676198.549 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:23:18.549Z,1676676198.549 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:23:18.549Z,1676676198.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:23:22.971Z,1676676202.971 [NAL9602](INFO): Powering up NAL9602
2023-02-17T23:23:33.875Z,1676676213.875 [NAL9602](INFO): NAL9602 initialized
2023-02-17T23:24:04.988Z,1676676244.988 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T23:28:19.144Z,1676676499.144 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:28:19.145Z,1676676499.145 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:28:19.145Z,1676676499.145 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:28:19.145Z,1676676499.145 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:28:19.521Z,1676676499.521 [Default:CheckIn:D] Stopped
2023-02-17T23:28:19.521Z,1676676499.521 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:28:19.926Z,1676676499.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.717074 min 
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn:E] Stopped
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn] Stopped
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn](INFO): Running loop #17
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn] Running Loop=17
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:28:19.927Z,1676676499.927 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:28:21.927Z,1676676501.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232820.00,A,3648.16391,N,12147.28072,W,0.078,0.00,170223,,,A*74
2023-02-17T23:28:21.930Z,1676676501.930 [NAL9602](INFO): GPS fix at 20230217T232820: (36.802732, -121.788012)
2023-02-17T23:28:21.941Z,1676676501.941 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:28:21.941Z,1676676501.941 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:28:22.399Z,1676676502.399 [NAL9602](ERROR): parseGSV uart error: serial timeout
2023-02-17T23:28:29.681Z,1676676509.681 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230217T224346/Courier0024.lzma
2023-02-17T23:28:30.684Z,1676676510.684 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0024.lzma.bak
2023-02-17T23:28:30.684Z,1676676510.684 [DataOverHttps](INFO): SBD MOMSN=17482862
2023-02-17T23:28:46.953Z,1676676526.953 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230217T224346/Express0025.lzma
2023-02-17T23:28:47.955Z,1676676527.955 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0025.lzma.bak
2023-02-17T23:28:47.955Z,1676676527.955 [DataOverHttps](INFO): SBD MOMSN=17482865
2023-02-17T23:28:49.430Z,1676676529.430 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:28:49.430Z,1676676529.430 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:28:49.430Z,1676676529.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:29:51.220Z,1676676591.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=22602, MT Status=2, MTMSN=0
2023-02-17T23:29:51.221Z,1676676591.221 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T23:30:08.179Z,1676676608.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=22602, MT Status=2, MTMSN=0
2023-02-17T23:30:08.179Z,1676676608.179 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T23:30:17.880Z,1676676617.880 [NAL9602](INFO): SBD MO Status=0, MOMSN=22602, MT Status=0, MTMSN=0
2023-02-17T23:30:17.880Z,1676676617.880 [NAL9602](INFO): No messages in MT queue
2023-02-17T23:30:48.578Z,1676676648.578 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T23:33:49.992Z,1676676829.992 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:33:49.992Z,1676676829.992 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:33:49.993Z,1676676829.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:33:49.993Z,1676676829.993 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:33:50.405Z,1676676830.405 [Default:CheckIn:D] Stopped
2023-02-17T23:33:50.405Z,1676676830.405 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:33:50.810Z,1676676830.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.231803 min 
2023-02-17T23:33:50.810Z,1676676830.810 [Default:CheckIn:E] Stopped
2023-02-17T23:33:50.810Z,1676676830.810 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:33:50.810Z,1676676830.810 [Default:CheckIn] Stopped
2023-02-17T23:33:50.811Z,1676676830.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:33:50.811Z,1676676830.811 [Default:CheckIn](INFO): Running loop #18
2023-02-17T23:33:50.811Z,1676676830.811 [Default:CheckIn] Running Loop=18
2023-02-17T23:33:50.811Z,1676676830.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:33:50.811Z,1676676830.811 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:33:52.829Z,1676676832.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233352.00,A,3648.16268,N,12147.28121,W,0.583,102.92,170223,,,A*72
2023-02-17T23:33:52.840Z,1676676832.840 [NAL9602](INFO): GPS fix at 20230217T233352: (36.802711, -121.788020)
2023-02-17T23:33:52.856Z,1676676832.856 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:33:52.856Z,1676676832.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:34:00.825Z,1676676840.825 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230217T224346/Courier0027.lzma
2023-02-17T23:34:01.827Z,1676676841.827 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0027.lzma.bak
2023-02-17T23:34:01.827Z,1676676841.827 [DataOverHttps](INFO): SBD MOMSN=17482877
2023-02-17T23:34:18.581Z,1676676858.581 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230217T224346/Express0028.lzma
2023-02-17T23:34:19.583Z,1676676859.583 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0028.lzma.bak
2023-02-17T23:34:19.583Z,1676676859.583 [DataOverHttps](INFO): SBD MOMSN=17482880
2023-02-17T23:34:21.161Z,1676676861.161 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:34:21.161Z,1676676861.161 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:34:21.161Z,1676676861.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:38:08.141Z,1676677088.141 [NAL9602](INFO): SBD MO Status=2, MOMSN=22603, MT Status=2, MTMSN=0
2023-02-17T23:38:08.141Z,1676677088.141 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-02-17T23:38:55.411Z,1676677135.411 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-17T23:39:21.690Z,1676677161.690 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:39:21.690Z,1676677161.690 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:39:21.690Z,1676677161.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:39:21.690Z,1676677161.690 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:39:22.081Z,1676677162.081 [Default:CheckIn:D] Stopped
2023-02-17T23:39:22.081Z,1676677162.081 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:39:22.492Z,1676677162.492 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.759733 min 
2023-02-17T23:39:22.492Z,1676677162.492 [Default:CheckIn:E] Stopped
2023-02-17T23:39:22.492Z,1676677162.492 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:39:22.492Z,1676677162.492 [Default:CheckIn] Stopped
2023-02-17T23:39:22.492Z,1676677162.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:39:22.492Z,1676677162.492 [Default:CheckIn](INFO): Running loop #19
2023-02-17T23:39:22.493Z,1676677162.493 [Default:CheckIn] Running Loop=19
2023-02-17T23:39:22.493Z,1676677162.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:39:22.493Z,1676677162.493 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:39:24.501Z,1676677164.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233923.00,A,3648.16949,N,12147.28115,W,0.603,102.92,170223,,,A*7A
2023-02-17T23:39:24.504Z,1676677164.504 [NAL9602](INFO): GPS fix at 20230217T233923: (36.802825, -121.788019)
2023-02-17T23:39:24.515Z,1676677164.515 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:39:24.515Z,1676677164.515 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:39:32.317Z,1676677172.317 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230217T224346/Courier0030.lzma
2023-02-17T23:39:33.319Z,1676677173.319 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Courier0030.lzma.bak
2023-02-17T23:39:33.319Z,1676677173.319 [DataOverHttps](INFO): SBD MOMSN=17482896
2023-02-17T23:39:49.537Z,1676677189.537 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230217T224346/Express0031.lzma
2023-02-17T23:39:50.541Z,1676677190.541 [DataOverHttps](INFO): Moved sent file to Logs/20230217T224346/Express0031.lzma.bak
2023-02-17T23:39:50.541Z,1676677190.541 [DataOverHttps](INFO): SBD MOMSN=17482899
2023-02-17T23:39:51.982Z,1676677191.982 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:39:51.982Z,1676677191.982 [Default:CheckIn:C.Wait] Running Loop=1
2023-02-17T23:39:51.982Z,1676677191.982 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-02-17T23:39:56.427Z,1676677196.427 [NAL9602](INFO): Not Powering down - fast GPS
2023-02-17T23:44:01.241Z,1676677441.241 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-02-17T23:44:01.242Z,1676677441.242 [DropWeight] Hardware Fault, FailCount= 1
2023-02-17T23:44:01.242Z,1676677441.242 [DropWeight](ERROR): Hardware Fault
2023-02-17T23:44:01.311Z,1676677441.311 [CommandExec](FAULT): Scheduling is paused
2023-02-17T23:44:01.311Z,1676677441.311 [CBIT](INFO): Critical error at 20230217T234401
2023-02-17T23:44:01.318Z,1676677441.318 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-02-17T23:44:01.318Z,1676677441.318 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-02-17T23:44:01.669Z,1676677441.669 [CBIT](INFO): Critical error at 20230217T234401
2023-02-17T23:44:51.012Z,1676677491.012 [CommandExec](IMPORTANT): got command quit
2023-02-17T23:44:52.017Z,1676677492.017 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.018Z,1676677492.018 [CommandExec](INFO): Uninitializing the command executive.
2023-02-17T23:44:52.018Z,1676677492.018 [CommandExec](INFO): Uninitializing the command scheduler.
2023-02-17T23:44:52.018Z,1676677492.018 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.109Z,1676677492.109 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-02-17T23:44:52.109Z,1676677492.109 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-02-17T23:44:52.110Z,1676677492.110 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.110Z,1676677492.110 [NavChartDb](INFO): Join timeout helper Thread ID is 4063
2023-02-17T23:44:52.338Z,1676677492.338 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.338Z,1676677492.338 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.357Z,1676677492.357 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-02-17T23:44:52.357Z,1676677492.357 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.358Z,1676677492.358 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 4064
2023-02-17T23:44:52.394Z,1676677492.394 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.394Z,1676677492.394 [WetLabsUBAT](INFO): Powering down
2023-02-17T23:44:52.395Z,1676677492.395 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.401Z,1676677492.401 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-02-17T23:44:52.401Z,1676677492.401 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.402Z,1676677492.402 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4065
2023-02-17T23:44:52.410Z,1676677492.410 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.410Z,1676677492.410 [WetLabsBB2FL](INFO): Powering down
2023-02-17T23:44:52.411Z,1676677492.411 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.422Z,1676677492.422 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-02-17T23:44:52.422Z,1676677492.422 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.422Z,1676677492.422 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4066
2023-02-17T23:44:52.542Z,1676677492.542 [CTD_Seabird](INFO): Powering down
2023-02-17T23:44:52.553Z,1676677492.553 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.554Z,1676677492.554 [CTD_Seabird](INFO): Powering down
2023-02-17T23:44:52.565Z,1676677492.565 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.568Z,1676677492.568 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-02-17T23:44:52.568Z,1676677492.568 [Default:CheckIn:C.Wait] Stopped
2023-02-17T23:44:52.568Z,1676677492.568 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-02-17T23:44:52.568Z,1676677492.568 [Default:CheckIn:D] Running Loop=1
2023-02-17T23:44:52.573Z,1676677492.573 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-02-17T23:44:52.573Z,1676677492.573 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.574Z,1676677492.574 [DAT](INFO): Join timeout helper Thread ID is 4067
2023-02-17T23:44:52.594Z,1676677492.594 [DAT](INFO): Powering down
2023-02-17T23:44:52.666Z,1676677492.666 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.666Z,1676677492.666 [DAT](INFO): Powering down
2023-02-17T23:44:52.667Z,1676677492.667 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.671Z,1676677492.671 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-02-17T23:44:52.671Z,1676677492.671 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.671Z,1676677492.671 [Radio_Surface](INFO): Join timeout helper Thread ID is 4068
2023-02-17T23:44:52.698Z,1676677492.698 [Radio_Surface](INFO): Powering down
2023-02-17T23:44:52.698Z,1676677492.698 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:52.699Z,1676677492.699 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.706Z,1676677492.706 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-02-17T23:44:52.706Z,1676677492.706 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:52.707Z,1676677492.707 [Onboard](INFO): Join timeout helper Thread ID is 4069
2023-02-17T23:44:52.975Z,1676677492.975 [Default:CheckIn:D] Stopped
2023-02-17T23:44:52.975Z,1676677492.975 [Default:CheckIn:E] Running Loop=1
2023-02-17T23:44:53.254Z,1676677493.254 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-02-17T23:44:53.398Z,1676677493.398 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.274650 min 
2023-02-17T23:44:53.398Z,1676677493.398 [Default:CheckIn:E] Stopped
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn] Stopped
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn](INFO): Running loop #20
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn] Running Loop=20
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-17T23:44:53.399Z,1676677493.399 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-17T23:44:55.384Z,1676677495.384 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234454.00,A,3648.16688,N,12147.27720,W,0.855,205.62,170223,,,A*7B
2023-02-17T23:44:55.386Z,1676677495.386 [NAL9602](INFO): GPS fix at 20230217T234454: (36.802781, -121.787953)
2023-02-17T23:44:55.441Z,1676677495.441 [Default:CheckIn:Read_GPS] Stopped
2023-02-17T23:44:55.441Z,1676677495.441 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-17T23:44:56.601Z,1676677496.601 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:56.602Z,1676677496.602 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:56.606Z,1676677496.606 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-02-17T23:44:56.606Z,1676677496.606 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:56.607Z,1676677496.607 [DataOverHttps](INFO): Join timeout helper Thread ID is 4070
2023-02-17T23:44:57.269Z,1676677497.269 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:57.270Z,1676677497.270 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.274Z,1676677497.274 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-02-17T23:44:57.274Z,1676677497.274 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.275Z,1676677497.275 [BackseatComponent](INFO): Join timeout helper Thread ID is 4071
2023-02-17T23:44:57.322Z,1676677497.322 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:57.322Z,1676677497.322 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.323Z,1676677497.323 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-02-17T23:44:57.323Z,1676677497.323 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.324Z,1676677497.324 [logger](INFO): Join timeout helper Thread ID is 4072
2023-02-17T23:44:57.338Z,1676677497.338 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:57.338Z,1676677497.338 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.350Z,1676677497.350 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-02-17T23:44:57.350Z,1676677497.350 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.351Z,1676677497.351 [CommandLine](INFO): Join timeout helper Thread ID is 4073
2023-02-17T23:44:57.382Z,1676677497.382 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:57.382Z,1676677497.382 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.402Z,1676677497.402 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-02-17T23:44:57.402Z,1676677497.402 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.403Z,1676677497.403 [CommandExec](INFO): Join timeout helper Thread ID is 4074
2023-02-17T23:44:57.410Z,1676677497.410 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-02-17T23:44:57.410Z,1676677497.410 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.411Z,1676677497.411 [controlThread](INFO): Join timeout helper Thread ID is 4075
2023-02-17T23:44:57.443Z,1676677497.443 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-17T23:44:57.443Z,1676677497.443 [controlThread](DEBUG): Uninitializing ControlThread
2023-02-17T23:44:57.443Z,1676677497.443 [AHRS_M2](INFO): Powering down
2023-02-17T23:44:57.514Z,1676677497.514 [MultiRay](INFO): Powering down.
2023-02-17T23:44:57.515Z,1676677497.515 [NAL9602](INFO): Powering down
2023-02-17T23:44:57.586Z,1676677497.586 [RDI_Pathfinder](INFO): Powering down
2023-02-17T23:44:57.588Z,1676677497.588 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-02-17T23:44:57.588Z,1676677497.588 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-02-17T23:44:57.589Z,1676677497.589 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-02-17T23:44:57.590Z,1676677497.590 [MissionManager](INFO): Uninitializing Mission Default
2023-02-17T23:44:57.590Z,1676677497.590 [Default] Stopped
2023-02-17T23:44:57.590Z,1676677497.590 [Default](DEBUG): Aggregate::uninitialize Default
2023-02-17T23:44:57.590Z,1676677497.590 [Default:B.GoToSurface] Stopped
2023-02-17T23:44:57.590Z,1676677497.590 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-17T23:44:57.590Z,1676677497.590 [Default:CheckIn] Stopped
2023-02-17T23:44:57.590Z,1676677497.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-17T23:44:57.590Z,1676677497.590 [Default:CheckIn:Read_Iridium] Stopped
2023-02-17T23:44:57.593Z,1676677497.593 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-02-17T23:44:57.593Z,1676677497.593 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-02-17T23:44:57.593Z,1676677497.593 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-02-17T23:44:57.594Z,1676677497.594 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-02-17T23:44:57.594Z,1676677497.594 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-02-17T23:44:57.594Z,1676677497.594 [BuoyancyServo](INFO): Powering down
2023-02-17T23:44:57.605Z,1676677497.605 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-02-17T23:44:57.606Z,1676677497.606 [ElevatorServo](INFO): Powering down
2023-02-17T23:44:57.606Z,1676677497.606 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-02-17T23:44:57.607Z,1676677497.607 [MassServo](INFO): Powering down
2023-02-17T23:44:57.607Z,1676677497.607 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-17T23:44:57.608Z,1676677497.608 [RudderServo](INFO): Powering down
2023-02-17T23:44:57.608Z,1676677497.608 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-02-17T23:44:57.608Z,1676677497.608 [ThrusterServo](INFO): Powering down
2023-02-17T23:44:57.609Z,1676677497.609 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-02-17T23:44:57.610Z,1676677497.610 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-02-17T23:44:57.610Z,1676677497.610 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-02-17T23:44:57.610Z,1676677497.610 [CBIT](DEBUG): Powering off loads.
2023-02-17T23:44:57.621Z,1676677497.621 [CBIT](DEBUG): Disabling WDT.
2023-02-17T23:44:57.633Z,1676677497.633 [CBIT](DEBUG): Opening all GF detection circuits.
2023-02-17T23:44:57.634Z,1676677497.634 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.685Z,1676677497.685 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.692Z,1676677497.692 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.694Z,1676677497.694 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.701Z,1676677497.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.743Z,1676677497.743 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.809Z,1676677497.809 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.811Z,1676677497.811 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.815Z,1676677497.815 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.823Z,1676677497.823 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-17T23:44:57.904Z,1676677497.904 [logger ThreadHandler](INFO): Thread cancelled.