2023-04-24T18:21:15.129Z,1682360475.129 [CommandExec](IMPORTANT): got command restart logs
2023-04-24T18:21:24.661Z,1682360484.661 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T18:23:11.352Z,1682360591.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:23:21.026Z,1682360601.026 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T18:23:21.026Z,1682360601.026 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+0.0,1489.0,000
2023-04-24T18:23:42.484Z,1682360622.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:24:13.608Z,1682360653.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:24:44.748Z,1682360684.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:25:15.872Z,1682360715.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:25:32.941Z,1682360732.941 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743332
2023-04-24T18:25:54.545Z,1682360754.545 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T18:25:54.545Z,1682360754.545 [Default:CheckIn:C.Wait] Stopped
2023-04-24T18:25:54.545Z,1682360754.545 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T18:25:54.546Z,1682360754.546 [Default:CheckIn:D] Running Loop=1
2023-04-24T18:25:54.946Z,1682360754.946 [Default:CheckIn:D] Stopped
2023-04-24T18:25:54.947Z,1682360754.947 [Default:CheckIn:E] Running Loop=1
2023-04-24T18:25:55.360Z,1682360755.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.253491 min 
2023-04-24T18:25:55.361Z,1682360755.361 [Default:CheckIn:E] Stopped
2023-04-24T18:25:55.361Z,1682360755.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T18:25:55.361Z,1682360755.361 [Default:CheckIn] Stopped
2023-04-24T18:25:55.362Z,1682360755.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T18:25:55.362Z,1682360755.362 [Default:CheckIn](INFO): Running loop #3
2023-04-24T18:25:55.363Z,1682360755.363 [Default:CheckIn] Running Loop=3
2023-04-24T18:25:55.363Z,1682360755.363 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T18:25:55.363Z,1682360755.363 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T18:25:57.366Z,1682360757.366 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182551.00,A,3650.40818,N,12155.14690,W,5.637,104.93,240423,,,D*75
2023-04-24T18:25:57.369Z,1682360757.369 [NAL9602](INFO): GPS fix at 20230424T182551: (36.840136, -121.919115)
2023-04-24T18:25:57.399Z,1682360757.399 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T18:25:57.399Z,1682360757.399 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T18:26:17.940Z,1682360777.940 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-04-24T18:26:23.321Z,1682360783.321 [DVL_micro](ERROR): Failed to parse:786,-01316,-00528,+00000,I
2023-04-24T18:26:26.510Z,1682360786.510 [NAL9602](INFO): SBD MO Status=2, MOMSN=3516, MT Status=2, MTMSN=0
2023-04-24T18:26:26.510Z,1682360786.510 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:26:48.634Z,1682360808.634 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002971
2023-04-24T18:26:58.089Z,1682360818.089 [NAL9602](INFO): SBD MO Status=1, MOMSN=3516, MT Status=0, MTMSN=0
2023-04-24T18:26:58.140Z,1682360818.140 [NAL9602](INFO): Sent 49 bytes from file Logs/20230424T093304/Courier0052.lzma
2023-04-24T18:26:58.140Z,1682360818.140 [NAL9602](INFO): Packets left to send: 0
2023-04-24T18:27:08.175Z,1682360828.175 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20230424T182115/Courier0000.lzma
2023-04-24T18:27:20.228Z,1682360840.228 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-04-24T18:27:28.791Z,1682360848.791 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T18:28:22.533Z,1682360902.533 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T18:28:22.533Z,1682360902.533 [DVL_micro](ERROR): Failed to parse:
:BI,-03942,-05985,-00526I
2023-04-24T18:28:22.816Z,1682360902.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:28:53.948Z,1682360933.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:29:25.080Z,1682360965.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:29:33.638Z,1682360973.638 [DVL_micro](ERROR): only read 0 of 4 data items
2023-04-24T18:29:33.638Z,1682360973.638 [DVL_micro](ERROR): Failed to parse:
:RD,++9999.99,+9999.99,+9999.99
2023-04-24T18:29:49.177Z,1682360989.177 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.544937
2023-04-24T18:30:20.304Z,1682361020.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:30:27.803Z,1682361027.803 [NAL9602](INFO): SBD MO Status=2, MOMSN=3517, MT Status=2, MTMSN=0
2023-04-24T18:30:27.803Z,1682361027.803 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:30:51.428Z,1682361051.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:31:05.030Z,1682361065.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=3517, MT Status=2, MTMSN=0
2023-04-24T18:31:05.030Z,1682361065.030 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:31:22.568Z,1682361082.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:31:31.327Z,1682361091.327 [NAL9602](INFO): SBD MO Status=1, MOMSN=3517, MT Status=0, MTMSN=0
2023-04-24T18:31:31.382Z,1682361091.382 [NAL9602](INFO): Sent 267 bytes from file Logs/20230424T182115/Courier0000.lzma
2023-04-24T18:31:31.382Z,1682361091.382 [NAL9602](INFO): Packets left to send: 0
2023-04-24T18:31:39.851Z,1682361099.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-04-24T18:31:39.851Z,1682361099.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:31:53.692Z,1682361113.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:31:55.666Z,1682361115.666 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T18:31:55.667Z,1682361115.667 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2023-04-24T18:31:58.489Z,1682361118.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-04-24T18:31:58.490Z,1682361118.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:32:24.493Z,1682361144.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-04-24T18:32:24.494Z,1682361144.494 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:32:24.817Z,1682361144.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:32:43.896Z,1682361163.896 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T18:32:43.896Z,1682361163.896 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.7,000.0,000
2023-04-24T18:32:55.940Z,1682361175.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:33:07.377Z,1682361187.377 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-04-24T18:33:07.378Z,1682361187.378 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:33:27.069Z,1682361207.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-24T18:33:47.868Z,1682361227.868 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002994
2023-04-24T18:33:56.339Z,1682361236.339 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230424T182115/Courier0003.lzma
2023-04-24T18:33:57.342Z,1682361237.342 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0003.lzma.bak
2023-04-24T18:33:57.342Z,1682361237.342 [DataOverHttps](INFO): SBD MOMSN=18259780
2023-04-24T18:34:01.731Z,1682361241.731 [NAL9602](INFO): SBD MO Status=2, MOMSN=3518, MT Status=2, MTMSN=0
2023-04-24T18:34:01.731Z,1682361241.731 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:34:13.448Z,1682361253.448 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20230424T093304/Express0053.lzma
2023-04-24T18:34:14.450Z,1682361254.450 [DataOverHttps](INFO): Moved sent file to Logs/20230424T093304/Express0053.lzma.bak
2023-04-24T18:34:14.450Z,1682361254.450 [DataOverHttps](INFO): SBD MOMSN=18259782
2023-04-24T18:34:30.417Z,1682361270.417 [DataOverHttps](INFO): Sending 395 bytes from file Logs/20230424T182115/Express0001.lzma
2023-04-24T18:34:31.414Z,1682361271.414 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0001.lzma.bak
2023-04-24T18:34:31.414Z,1682361271.414 [DataOverHttps](INFO): SBD MOMSN=18259786
2023-04-24T18:34:32.481Z,1682361272.481 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T18:34:51.527Z,1682361291.527 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20230424T182115/Express0004.lzma
2023-04-24T18:34:52.530Z,1682361292.530 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0004.lzma.bak
2023-04-24T18:34:52.530Z,1682361292.530 [DataOverHttps](INFO): SBD MOMSN=18259790
2023-04-24T18:34:54.302Z,1682361294.302 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T18:34:54.302Z,1682361294.302 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T18:34:54.303Z,1682361294.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T18:39:03.979Z,1682361543.979 [DVL_micro](ERROR): Failed to parse:
0000000000,35.0,+12.7,0000.0,1489.0,000
2023-04-24T18:39:54.899Z,1682361594.899 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T18:39:54.899Z,1682361594.899 [Default:CheckIn:C.Wait] Stopped
2023-04-24T18:39:54.899Z,1682361594.899 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T18:39:54.899Z,1682361594.899 [Default:CheckIn:D] Running Loop=1
2023-04-24T18:39:55.273Z,1682361595.273 [Default:CheckIn:D] Stopped
2023-04-24T18:39:55.273Z,1682361595.273 [Default:CheckIn:E] Running Loop=1
2023-04-24T18:39:55.688Z,1682361595.688 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.258936 min 
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn:E] Stopped
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn] Stopped
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn](INFO): Running loop #4
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn] Running Loop=4
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T18:39:55.689Z,1682361595.689 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T18:39:57.691Z,1682361597.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183952.00,A,3649.43142,N,12151.12155,W,15.434,109.07,240423,,,A*4F
2023-04-24T18:39:57.693Z,1682361597.693 [NAL9602](INFO): GPS fix at 20230424T183952: (36.823857, -121.852026)
2023-04-24T18:39:57.703Z,1682361597.703 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T18:39:57.703Z,1682361597.703 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T18:40:05.479Z,1682361605.479 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0006.lzma
2023-04-24T18:40:06.482Z,1682361606.482 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0006.lzma.bak
2023-04-24T18:40:06.482Z,1682361606.482 [DataOverHttps](INFO): SBD MOMSN=18259808
2023-04-24T18:40:15.466Z,1682361615.466 [NAL9602](INFO): SBD MO Status=1, MOMSN=3518, MT Status=0, MTMSN=0
2023-04-24T18:40:15.466Z,1682361615.466 [NAL9602](INFO): No messages in MT queue
2023-04-24T18:40:22.380Z,1682361622.380 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230424T182115/Express0007.lzma
2023-04-24T18:40:23.382Z,1682361623.382 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0007.lzma.bak
2023-04-24T18:40:23.382Z,1682361623.382 [DataOverHttps](INFO): SBD MOMSN=18259811
2023-04-24T18:40:24.778Z,1682361624.778 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T18:40:24.778Z,1682361624.778 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T18:40:24.778Z,1682361624.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T18:40:46.168Z,1682361646.168 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T18:42:04.150Z,1682361724.150 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T18:42:04.150Z,1682361724.150 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2023-04-24T18:42:14.242Z,1682361734.242 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+12.8,089.0,000
2023-04-24T18:45:14.440Z,1682361914.440 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T18:45:14.441Z,1682361914.441 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.9,0000.,000
2023-04-24T18:45:24.535Z,1682361924.535 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T18:45:24.536Z,1682361924.536 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+1.0,1489.0I,+0518,+00169,+00000,A
2023-04-24T18:45:25.346Z,1682361925.346 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T18:45:25.347Z,1682361925.347 [Default:CheckIn:C.Wait] Stopped
2023-04-24T18:45:25.347Z,1682361925.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T18:45:25.347Z,1682361925.347 [Default:CheckIn:D] Running Loop=1
2023-04-24T18:45:25.750Z,1682361925.750 [Default:CheckIn:D] Stopped
2023-04-24T18:45:25.750Z,1682361925.750 [Default:CheckIn:E] Running Loop=1
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.766878 min 
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn:E] Stopped
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn] Stopped
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn](INFO): Running loop #5
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn] Running Loop=5
2023-04-24T18:45:26.151Z,1682361926.151 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T18:45:26.152Z,1682361926.152 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T18:45:28.169Z,1682361928.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184522.00,A,3649.03146,N,12149.55244,W,13.082,112.42,240423,,,A*4E
2023-04-24T18:45:28.171Z,1682361928.171 [NAL9602](INFO): GPS fix at 20230424T184522: (36.817191, -121.825874)
2023-04-24T18:45:28.182Z,1682361928.182 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T18:45:28.182Z,1682361928.182 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T18:45:35.603Z,1682361935.603 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0009.lzma
2023-04-24T18:45:36.606Z,1682361936.606 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0009.lzma.bak
2023-04-24T18:45:36.606Z,1682361936.606 [DataOverHttps](INFO): SBD MOMSN=18259818
2023-04-24T18:45:42.370Z,1682361942.370 [NAL9602](INFO): SBD MO Status=1, MOMSN=3519, MT Status=0, MTMSN=0
2023-04-24T18:45:42.370Z,1682361942.370 [NAL9602](INFO): No messages in MT queue
2023-04-24T18:45:55.419Z,1682361955.419 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230424T182115/Express0010.lzma
2023-04-24T18:45:56.422Z,1682361956.422 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0010.lzma.bak
2023-04-24T18:45:56.422Z,1682361956.422 [DataOverHttps](INFO): SBD MOMSN=18259821
2023-04-24T18:45:58.144Z,1682361958.144 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T18:45:58.144Z,1682361958.144 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T18:45:58.144Z,1682361958.144 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T18:46:13.075Z,1682361973.075 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T18:48:34.489Z,1682362114.489 [DVL_micro](ERROR): only read 2 of 4 data items
2023-04-24T18:48:34.490Z,1682362114.490 [DVL_micro](ERROR): Failed to parse:
:BI,-00851,-04065+00000,I
2023-04-24T18:50:58.734Z,1682362258.734 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T18:50:58.734Z,1682362258.734 [Default:CheckIn:C.Wait] Stopped
2023-04-24T18:50:58.734Z,1682362258.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T18:50:58.735Z,1682362258.735 [Default:CheckIn:D] Running Loop=1
2023-04-24T18:50:59.153Z,1682362259.153 [Default:CheckIn:D] Stopped
2023-04-24T18:50:59.153Z,1682362259.153 [Default:CheckIn:E] Running Loop=1
2023-04-24T18:50:59.566Z,1682362259.566 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.323600 min 
2023-04-24T18:50:59.566Z,1682362259.566 [Default:CheckIn:E] Stopped
2023-04-24T18:50:59.566Z,1682362259.566 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T18:50:59.567Z,1682362259.567 [Default:CheckIn] Stopped
2023-04-24T18:50:59.567Z,1682362259.567 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T18:50:59.567Z,1682362259.567 [Default:CheckIn](INFO): Running loop #6
2023-04-24T18:50:59.567Z,1682362259.567 [Default:CheckIn] Running Loop=6
2023-04-24T18:50:59.567Z,1682362259.567 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T18:50:59.567Z,1682362259.567 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T18:51:01.565Z,1682362261.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185056.00,A,3648.47433,N,12148.06578,W,13.160,117.15,240423,,,A*4A
2023-04-24T18:51:01.567Z,1682362261.567 [NAL9602](INFO): GPS fix at 20230424T185056: (36.807905, -121.801096)
2023-04-24T18:51:01.593Z,1682362261.593 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T18:51:01.593Z,1682362261.593 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T18:51:08.969Z,1682362268.969 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0012.lzma
2023-04-24T18:51:09.890Z,1682362269.890 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0012.lzma.bak
2023-04-24T18:51:09.890Z,1682362269.890 [DataOverHttps](INFO): SBD MOMSN=18259826
2023-04-24T18:51:25.815Z,1682362285.815 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230424T182115/Express0013.lzma
2023-04-24T18:51:26.818Z,1682362286.818 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0013.lzma.bak
2023-04-24T18:51:26.818Z,1682362286.818 [DataOverHttps](INFO): SBD MOMSN=18259829
2023-04-24T18:51:28.231Z,1682362288.231 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T18:51:28.231Z,1682362288.231 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T18:51:28.231Z,1682362288.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T18:53:13.687Z,1682362393.687 [NAL9602](INFO): SBD MO Status=1, MOMSN=3520, MT Status=0, MTMSN=0
2023-04-24T18:53:13.687Z,1682362393.687 [NAL9602](INFO): No messages in MT queue
2023-04-24T18:53:44.373Z,1682362424.373 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T18:56:28.849Z,1682362588.849 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T18:56:28.849Z,1682362588.849 [Default:CheckIn:C.Wait] Stopped
2023-04-24T18:56:28.849Z,1682362588.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T18:56:28.849Z,1682362588.849 [Default:CheckIn:D] Running Loop=1
2023-04-24T18:56:29.225Z,1682362589.225 [Default:CheckIn:D] Stopped
2023-04-24T18:56:29.226Z,1682362589.226 [Default:CheckIn:E] Running Loop=1
2023-04-24T18:56:29.639Z,1682362589.639 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.824805 min 
2023-04-24T18:56:29.639Z,1682362589.639 [Default:CheckIn:E] Stopped
2023-04-24T18:56:29.639Z,1682362589.639 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T18:56:29.639Z,1682362589.639 [Default:CheckIn] Stopped
2023-04-24T18:56:29.640Z,1682362589.640 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T18:56:29.640Z,1682362589.640 [Default:CheckIn](INFO): Running loop #7
2023-04-24T18:56:29.640Z,1682362589.640 [Default:CheckIn] Running Loop=7
2023-04-24T18:56:29.640Z,1682362589.640 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T18:56:29.640Z,1682362589.640 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T18:56:31.647Z,1682362591.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185626.00,A,3648.48654,N,12147.11930,W,3.810,169.94,240423,,,D*74
2023-04-24T18:56:31.650Z,1682362591.650 [NAL9602](INFO): GPS fix at 20230424T185626: (36.808109, -121.785322)
2023-04-24T18:56:31.660Z,1682362591.660 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T18:56:31.660Z,1682362591.660 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T18:56:41.227Z,1682362601.227 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0015.lzma
2023-04-24T18:56:42.230Z,1682362602.230 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0015.lzma.bak
2023-04-24T18:56:42.230Z,1682362602.230 [DataOverHttps](INFO): SBD MOMSN=18259834
2023-04-24T18:57:01.075Z,1682362621.075 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20230424T182115/Express0016.lzma
2023-04-24T18:57:02.078Z,1682362622.078 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0016.lzma.bak
2023-04-24T18:57:02.078Z,1682362622.078 [DataOverHttps](INFO): SBD MOMSN=18259837
2023-04-24T18:57:03.654Z,1682362623.654 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T18:57:03.654Z,1682362623.654 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T18:57:03.654Z,1682362623.654 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T18:57:27.831Z,1682362647.831 [NAL9602](INFO): SBD MO Status=2, MOMSN=3521, MT Status=2, MTMSN=0
2023-04-24T18:57:27.831Z,1682362647.831 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T18:58:13.115Z,1682362693.115 [NAL9602](INFO): SBD MO Status=1, MOMSN=3521, MT Status=0, MTMSN=0
2023-04-24T18:58:13.115Z,1682362693.115 [NAL9602](INFO): No messages in MT queue
2023-04-24T18:58:43.861Z,1682362723.861 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:01:15.112Z,1682362875.112 [DVL_micro](ERROR): Failed to parse:
53,-04593+00000,I
2023-04-24T19:02:04.030Z,1682362924.030 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:02:04.030Z,1682362924.030 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:02:04.030Z,1682362924.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:02:04.030Z,1682362924.030 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:02:04.427Z,1682362924.427 [Default:CheckIn:D] Stopped
2023-04-24T19:02:04.427Z,1682362924.427 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:02:04.834Z,1682362924.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.411499 min 
2023-04-24T19:02:04.834Z,1682362924.834 [Default:CheckIn:E] Stopped
2023-04-24T19:02:04.834Z,1682362924.834 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:02:04.834Z,1682362924.834 [Default:CheckIn] Stopped
2023-04-24T19:02:04.834Z,1682362924.834 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:02:04.835Z,1682362924.835 [Default:CheckIn](INFO): Running loop #8
2023-04-24T19:02:04.835Z,1682362924.835 [Default:CheckIn] Running Loop=8
2023-04-24T19:02:04.835Z,1682362924.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:02:04.835Z,1682362924.835 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:02:06.847Z,1682362926.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190201.00,A,3648.13595,N,12147.20619,W,0.175,218.14,240423,,,D*73
2023-04-24T19:02:06.850Z,1682362926.850 [NAL9602](INFO): GPS fix at 20230424T190201: (36.802266, -121.786770)
2023-04-24T19:02:06.860Z,1682362926.860 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:02:06.860Z,1682362926.860 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:02:14.575Z,1682362934.575 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0018.lzma
2023-04-24T19:02:15.578Z,1682362935.578 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0018.lzma.bak
2023-04-24T19:02:15.578Z,1682362935.578 [DataOverHttps](INFO): SBD MOMSN=18259842
2023-04-24T19:02:31.451Z,1682362951.451 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20230424T182115/Express0019.lzma
2023-04-24T19:02:32.454Z,1682362952.454 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0019.lzma.bak
2023-04-24T19:02:32.454Z,1682362952.454 [DataOverHttps](INFO): SBD MOMSN=18259845
2023-04-24T19:02:33.936Z,1682362953.936 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:02:33.936Z,1682362953.936 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:02:33.937Z,1682362953.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:02:37.967Z,1682362957.967 [NAL9602](INFO): SBD MO Status=1, MOMSN=3522, MT Status=0, MTMSN=0
2023-04-24T19:02:37.967Z,1682362957.967 [NAL9602](INFO): No messages in MT queue
2023-04-24T19:03:08.673Z,1682362988.673 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:04:25.503Z,1682363065.503 [DVL_micro](ERROR): only read 2 of 4 data items
2023-04-24T19:04:25.503Z,1682363065.503 [DVL_micro](ERROR): Failed to parse:
:BI,+00698,-00899+00000,I
2023-04-24T19:07:34.339Z,1682363254.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:07:34.339Z,1682363254.339 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:07:34.340Z,1682363254.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:07:34.340Z,1682363254.340 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:07:34.733Z,1682363254.733 [Default:CheckIn:D] Stopped
2023-04-24T19:07:34.734Z,1682363254.734 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.916610 min 
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn:E] Stopped
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn] Stopped
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn](INFO): Running loop #9
2023-04-24T19:07:35.138Z,1682363255.138 [Default:CheckIn] Running Loop=9
2023-04-24T19:07:35.139Z,1682363255.139 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:07:35.139Z,1682363255.139 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:07:37.154Z,1682363257.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190731.00,A,3648.13814,N,12147.21092,W,0.019,281.63,240423,,,D*7E
2023-04-24T19:07:37.157Z,1682363257.157 [NAL9602](INFO): GPS fix at 20230424T190731: (36.802302, -121.786849)
2023-04-24T19:07:37.177Z,1682363257.177 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:07:37.177Z,1682363257.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:07:40.513Z,1682363260.513 [DVL_micro](ERROR): Failed to parse:
:WI,+06140,-02889,-01586,+00000,A
2023-04-24T19:07:47.919Z,1682363267.919 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0021.lzma
2023-04-24T19:07:48.922Z,1682363268.922 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0021.lzma.bak
2023-04-24T19:07:48.922Z,1682363268.922 [DataOverHttps](INFO): SBD MOMSN=18259850
2023-04-24T19:08:04.851Z,1682363284.851 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230424T182115/Express0022.lzma
2023-04-24T19:08:05.854Z,1682363285.854 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0022.lzma.bak
2023-04-24T19:08:05.854Z,1682363285.854 [DataOverHttps](INFO): SBD MOMSN=18259853
2023-04-24T19:08:07.207Z,1682363287.207 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:08:07.207Z,1682363287.207 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:08:07.207Z,1682363287.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:08:30.205Z,1682363310.205 [NAL9602](INFO): SBD MO Status=1, MOMSN=3523, MT Status=0, MTMSN=0
2023-04-24T19:08:30.205Z,1682363310.205 [NAL9602](INFO): No messages in MT queue
2023-04-24T19:09:00.657Z,1682363340.657 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:11:08.449Z,1682363468.449 [DVL_micro](ERROR): Failed to parse:
:SA,-02.86,+03.78,009.6
2023-04-24T19:13:07.688Z,1682363587.688 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:13:07.688Z,1682363587.688 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:13:07.688Z,1682363587.688 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:13:07.689Z,1682363587.689 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:13:08.103Z,1682363588.103 [Default:CheckIn:D] Stopped
2023-04-24T19:13:08.103Z,1682363588.103 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.472754 min 
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn:E] Stopped
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn] Stopped
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn](INFO): Running loop #10
2023-04-24T19:13:08.494Z,1682363588.494 [Default:CheckIn] Running Loop=10
2023-04-24T19:13:08.495Z,1682363588.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:13:08.495Z,1682363588.495 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:13:10.506Z,1682363590.506 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191305.00,A,3648.13581,N,12147.20852,W,0.019,281.63,240423,,,D*78
2023-04-24T19:13:10.509Z,1682363590.509 [NAL9602](INFO): GPS fix at 20230424T191305: (36.802264, -121.786809)
2023-04-24T19:13:10.519Z,1682363590.519 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:13:10.519Z,1682363590.519 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:13:18.259Z,1682363598.259 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0024.lzma
2023-04-24T19:13:19.262Z,1682363599.262 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0024.lzma.bak
2023-04-24T19:13:19.262Z,1682363599.262 [DataOverHttps](INFO): SBD MOMSN=18259861
2023-04-24T19:13:35.427Z,1682363615.427 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230424T182115/Express0025.lzma
2023-04-24T19:13:36.430Z,1682363616.430 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0025.lzma.bak
2023-04-24T19:13:36.430Z,1682363616.430 [DataOverHttps](INFO): SBD MOMSN=18259864
2023-04-24T19:13:38.045Z,1682363618.045 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:13:38.045Z,1682363618.045 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:13:38.046Z,1682363618.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:14:26.129Z,1682363666.129 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T19:14:26.130Z,1682363666.130 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+14.1,0000.0,00
2023-04-24T19:14:29.750Z,1682363669.750 [NAL9602](INFO): SBD MO Status=2, MOMSN=3524, MT Status=2, MTMSN=0
2023-04-24T19:14:29.750Z,1682363669.750 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T19:14:46.450Z,1682363686.450 [NAL9602](INFO): SBD MO Status=2, MOMSN=3524, MT Status=2, MTMSN=0
2023-04-24T19:14:46.451Z,1682363686.451 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T19:15:07.378Z,1682363707.378 [NAL9602](INFO): SBD MO Status=2, MOMSN=3524, MT Status=2, MTMSN=0
2023-04-24T19:15:07.378Z,1682363707.378 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-24T19:15:14.249Z,1682363714.249 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:15:14.250Z,1682363714.250 [DVL_micro](ERROR): Failed to parse:
:BI,+0545,-02283,+00000,I
2023-04-24T19:18:13.286Z,1682363893.286 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-24T19:18:38.778Z,1682363918.778 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:18:38.778Z,1682363918.778 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:18:38.778Z,1682363918.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:18:38.778Z,1682363918.778 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:18:39.171Z,1682363919.171 [Default:CheckIn:D] Stopped
2023-04-24T19:18:39.171Z,1682363919.171 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:18:39.598Z,1682363919.598 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.990568 min 
2023-04-24T19:18:39.598Z,1682363919.598 [Default:CheckIn:E] Stopped
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn] Stopped
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn](INFO): Running loop #11
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn] Running Loop=11
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:18:39.599Z,1682363919.599 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:18:41.578Z,1682363921.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191836.00,A,3648.17249,N,12147.28482,W,0.778,9.42,240423,,,D*7C
2023-04-24T19:18:41.588Z,1682363921.588 [NAL9602](INFO): GPS fix at 20230424T191836: (36.802875, -121.788080)
2023-04-24T19:18:41.626Z,1682363921.626 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:18:41.626Z,1682363921.626 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:18:51.207Z,1682363931.207 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0027.lzma
2023-04-24T19:18:52.210Z,1682363932.210 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0027.lzma.bak
2023-04-24T19:18:52.210Z,1682363932.210 [DataOverHttps](INFO): SBD MOMSN=18259875
2023-04-24T19:19:08.107Z,1682363948.107 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20230424T182115/Express0028.lzma
2023-04-24T19:19:09.214Z,1682363949.214 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0028.lzma.bak
2023-04-24T19:19:09.214Z,1682363949.214 [DataOverHttps](INFO): SBD MOMSN=18259878
2023-04-24T19:19:10.583Z,1682363950.583 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:19:10.583Z,1682363950.583 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:19:10.583Z,1682363950.583 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:19:14.213Z,1682363954.213 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:21:06.201Z,1682364066.201 [DVL_micro](ERROR): Failed to parse:
95,+09692,-01009,+00000,I
2023-04-24T19:23:46.165Z,1682364226.165 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:23:46.166Z,1682364226.166 [DVL_micro](ERROR): Failed to parse:
:BI,+0578,+00088,+00000,I
2023-04-24T19:24:11.211Z,1682364251.211 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:24:11.211Z,1682364251.211 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:24:11.211Z,1682364251.211 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:24:11.212Z,1682364251.212 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:24:11.603Z,1682364251.603 [Default:CheckIn:D] Stopped
2023-04-24T19:24:11.603Z,1682364251.603 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:24:12.022Z,1682364252.022 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.531104 min 
2023-04-24T19:24:12.022Z,1682364252.022 [Default:CheckIn:E] Stopped
2023-04-24T19:24:12.022Z,1682364252.022 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:24:12.022Z,1682364252.022 [Default:CheckIn] Stopped
2023-04-24T19:24:12.022Z,1682364252.022 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:24:12.023Z,1682364252.023 [Default:CheckIn](INFO): Running loop #12
2023-04-24T19:24:12.023Z,1682364252.023 [Default:CheckIn] Running Loop=12
2023-04-24T19:24:12.023Z,1682364252.023 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:24:12.023Z,1682364252.023 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:24:14.037Z,1682364254.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192408.00,A,3648.17018,N,12147.28554,W,0.311,71.07,240423,,,A*42
2023-04-24T19:24:14.039Z,1682364254.039 [NAL9602](INFO): GPS fix at 20230424T192408: (36.802836, -121.788092)
2023-04-24T19:24:14.050Z,1682364254.050 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:24:14.050Z,1682364254.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:24:21.815Z,1682364261.815 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230424T182115/Courier0030.lzma
2023-04-24T19:24:22.818Z,1682364262.818 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0030.lzma.bak
2023-04-24T19:24:22.818Z,1682364262.818 [DataOverHttps](INFO): SBD MOMSN=18259892
2023-04-24T19:24:40.339Z,1682364280.339 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230424T182115/Express0031.lzma
2023-04-24T19:24:41.343Z,1682364281.343 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0031.lzma.bak
2023-04-24T19:24:41.343Z,1682364281.343 [DataOverHttps](INFO): SBD MOMSN=18259895
2023-04-24T19:24:43.170Z,1682364283.170 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:24:43.171Z,1682364283.171 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:24:43.171Z,1682364283.171 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:24:44.801Z,1682364284.801 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-24T19:24:44.884Z,1682364284.884 [NAL9602](ERROR): received: 
+CSQ:0

OK24, 2, 0, 0, 0

OK
2023-04-24T19:27:41.719Z,1682364461.719 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T19:27:41.719Z,1682364461.719 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+14.9,0000.0,1489.0,000
2023-04-24T19:28:36.260Z,1682364516.260 [BPC1](ERROR): Battery stick #57 (s/n: 0460) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0BD0.
2023-04-24T19:28:36.260Z,1682364516.260 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2023-04-24T19:28:36.263Z,1682364516.263 [BPC1](INFO): Received data from all battery sticks.
2023-04-24T19:29:16.244Z,1682364556.244 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-24T19:29:43.825Z,1682364583.825 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:29:43.825Z,1682364583.825 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:29:43.826Z,1682364583.826 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:29:43.826Z,1682364583.826 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:29:44.143Z,1682364584.143 [Default:CheckIn:D] Stopped
2023-04-24T19:29:44.143Z,1682364584.143 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:29:44.550Z,1682364584.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.073429 min 
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn:E] Stopped
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn] Stopped
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn](INFO): Running loop #13
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn] Running Loop=13
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:29:44.551Z,1682364584.551 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:29:46.542Z,1682364586.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192941.00,A,3648.16864,N,12147.28589,W,0.253,140.75,240423,,,D*74
2023-04-24T19:29:46.545Z,1682364586.545 [NAL9602](INFO): GPS fix at 20230424T192941: (36.802811, -121.788098)
2023-04-24T19:29:46.555Z,1682364586.555 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:29:46.555Z,1682364586.555 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:29:53.559Z,1682364593.559 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230424T182115/Courier0033.lzma
2023-04-24T19:29:54.562Z,1682364594.562 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0033.lzma.bak
2023-04-24T19:29:54.562Z,1682364594.562 [DataOverHttps](INFO): SBD MOMSN=18259901
2023-04-24T19:30:10.419Z,1682364610.419 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230424T182115/Express0034.lzma
2023-04-24T19:30:11.422Z,1682364611.422 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0034.lzma.bak
2023-04-24T19:30:11.422Z,1682364611.422 [DataOverHttps](INFO): SBD MOMSN=18259904
2023-04-24T19:30:12.827Z,1682364612.827 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:30:12.827Z,1682364612.827 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:30:12.827Z,1682364612.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:30:18.999Z,1682364618.999 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:30:51.622Z,1682364651.622 [DVL_micro](ERROR): Failed to parse:
:SA,-03.62,+04.28,191.6
2023-04-24T19:30:56.861Z,1682364656.861 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:30:56.861Z,1682364656.861 [DVL_micro](ERROR): Failed to parse:
:BI,+0088,-02523,+00000,I
2023-04-24T19:34:09.589Z,1682364849.589 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:34:09.589Z,1682364849.589 [DVL_micro](ERROR): Failed to parse:
:BI,-01472,-00328,-03109I
2023-04-24T19:34:16.859Z,1682364856.859 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-24T19:34:16.859Z,1682364856.859 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.2,0000.0,1489.0,,-01799,-03109,-02232,+00000,A
2023-04-24T19:35:13.433Z,1682364913.433 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:35:13.433Z,1682364913.433 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:35:13.433Z,1682364913.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:35:13.433Z,1682364913.433 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:35:14.013Z,1682364914.013 [Default:CheckIn:D] Stopped
2023-04-24T19:35:14.013Z,1682364914.013 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.571240 min 
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn:E] Stopped
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn] Stopped
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn](INFO): Running loop #14
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn] Running Loop=14
2023-04-24T19:35:14.339Z,1682364914.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:35:14.340Z,1682364914.340 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:35:16.251Z,1682364916.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193510.00,A,3648.16875,N,12147.28589,W,0.428,42.23,240423,,,A*42
2023-04-24T19:35:16.253Z,1682364916.253 [NAL9602](INFO): GPS fix at 20230424T193510: (36.802813, -121.788098)
2023-04-24T19:35:16.283Z,1682364916.283 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:35:16.283Z,1682364916.283 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:35:20.384Z,1682364920.384 [DVL_micro](ERROR): DVL uart error: serial timeout
2023-04-24T19:35:20.384Z,1682364920.384 [DVL_micro] Communications Fault, FailCount= 1
2023-04-24T19:35:20.384Z,1682364920.384 [DVL_micro](ERROR): Communications Fault
2023-04-24T19:35:20.384Z,1682364920.384 [DVL_micro](ERROR): Failed to parse:
2023-04-24T19:35:20.417Z,1682364920.417 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-24T19:35:20.760Z,1682364920.760 [DVL_micro](INFO): Powering down
2023-04-24T19:35:21.554Z,1682364921.554 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-24T19:35:21.554Z,1682364921.554 [DVL_micro] No Fault, FailCount= 1
2023-04-24T19:35:24.355Z,1682364924.355 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0036.lzma
2023-04-24T19:35:25.358Z,1682364925.358 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0036.lzma.bak
2023-04-24T19:35:25.358Z,1682364925.358 [DataOverHttps](INFO): SBD MOMSN=18259929
2023-04-24T19:35:25.563Z,1682364925.563 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-24T19:35:36.514Z,1682364936.514 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.016116
CHAN A1 (24V): 0.021756
CHAN A2 (12V): -0.015495
CHAN A3 (5V): -0.028853
CHAN B0 (3.3V): -0.009907
CHAN B1 (3.15aV): -0.004360
CHAN B2 (3.15bV): -0.002821
CHAN B3 (GND): -0.012152
OPEN: 0.009458
Full Scale: +/- 1 mA
2023-04-24T19:35:46.335Z,1682364946.335 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230424T182115/Express0037.lzma
2023-04-24T19:35:47.028Z,1682364947.028 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-24T19:35:47.112Z,1682364947.112 [NAL9602](ERROR): received: 
+CSQ:0

OK24, 2, 0, 0, 0

OK
2023-04-24T19:35:47.338Z,1682364947.338 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0037.lzma.bak
2023-04-24T19:35:47.338Z,1682364947.338 [DataOverHttps](INFO): SBD MOMSN=18259933
2023-04-24T19:35:49.523Z,1682364949.523 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:35:49.523Z,1682364949.523 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:35:49.523Z,1682364949.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:37:02.983Z,1682365022.983 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:37:02.988Z,1682365022.988 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2023-04-24T19:37:51.467Z,1682365071.467 [DVL_micro](ERROR): Failed to parse:094,+00963,-02388,+00000,I
2023-04-24T19:39:07.421Z,1682365147.421 [DVL_micro](ERROR): Failed to parse:
:WI,-01927,+08273,-01402,+00000,A
2023-04-24T19:40:18.122Z,1682365218.122 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-24T19:40:48.824Z,1682365248.824 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:40:50.055Z,1682365250.055 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:40:50.056Z,1682365250.056 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:40:50.056Z,1682365250.056 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:40:50.056Z,1682365250.056 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:40:50.441Z,1682365250.441 [Default:CheckIn:D] Stopped
2023-04-24T19:40:50.441Z,1682365250.441 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:40:50.846Z,1682365250.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.178402 min 
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn:E] Stopped
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn] Stopped
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn](INFO): Running loop #15
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn] Running Loop=15
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:40:50.847Z,1682365250.847 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:40:52.865Z,1682365252.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194047.00,A,3648.16656,N,12147.28087,W,0.428,333.81,240423,,,A*7B
2023-04-24T19:40:52.867Z,1682365252.867 [NAL9602](INFO): GPS fix at 20230424T194047: (36.802776, -121.788015)
2023-04-24T19:40:52.878Z,1682365252.878 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:40:52.878Z,1682365252.878 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:41:00.619Z,1682365260.619 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0039.lzma
2023-04-24T19:41:01.622Z,1682365261.622 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0039.lzma.bak
2023-04-24T19:41:01.622Z,1682365261.622 [DataOverHttps](INFO): SBD MOMSN=18259937
2023-04-24T19:41:17.581Z,1682365277.581 [DataOverHttps](INFO): Sending 403 bytes from file Logs/20230424T182115/Express0040.lzma
2023-04-24T19:41:18.582Z,1682365278.582 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0040.lzma.bak
2023-04-24T19:41:18.582Z,1682365278.582 [DataOverHttps](INFO): SBD MOMSN=18259940
2023-04-24T19:41:19.943Z,1682365279.943 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:41:19.943Z,1682365279.943 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:41:19.943Z,1682365279.943 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:41:23.562Z,1682365283.562 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-24T19:41:23.640Z,1682365283.640 [NAL9602](ERROR): received: 
+CSQ:0

OK24, 2, 0, 0, 0

OK
2023-04-24T19:42:24.977Z,1682365344.977 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:42:24.978Z,1682365344.978 [DVL_micro](ERROR): Failed to parse:
:BI,00101,-03048,+00000,I
2023-04-24T19:42:27.398Z,1682365347.398 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-24T19:42:27.399Z,1682365347.399 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2023-04-24T19:45:55.049Z,1682365555.049 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-24T19:46:20.516Z,1682365580.516 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-24T19:46:20.516Z,1682365580.516 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:46:20.516Z,1682365580.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:46:20.516Z,1682365580.516 [Default:CheckIn:D] Running Loop=1
2023-04-24T19:46:20.913Z,1682365580.913 [Default:CheckIn:D] Stopped
2023-04-24T19:46:20.914Z,1682365580.914 [Default:CheckIn:E] Running Loop=1
2023-04-24T19:46:21.321Z,1682365581.321 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.686271 min 
2023-04-24T19:46:21.321Z,1682365581.321 [Default:CheckIn:E] Stopped
2023-04-24T19:46:21.321Z,1682365581.321 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-24T19:46:21.321Z,1682365581.321 [Default:CheckIn] Stopped
2023-04-24T19:46:21.321Z,1682365581.321 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:46:21.322Z,1682365581.322 [Default:CheckIn](INFO): Running loop #16
2023-04-24T19:46:21.322Z,1682365581.322 [Default:CheckIn] Running Loop=16
2023-04-24T19:46:21.322Z,1682365581.322 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-24T19:46:21.322Z,1682365581.322 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-24T19:46:23.335Z,1682365583.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194617.00,A,3648.16442,N,12147.27975,W,1.633,329.28,240423,,,A*75
2023-04-24T19:46:23.337Z,1682365583.337 [NAL9602](INFO): GPS fix at 20230424T194617: (36.802740, -121.787996)
2023-04-24T19:46:23.352Z,1682365583.352 [Default:CheckIn:Read_GPS] Stopped
2023-04-24T19:46:23.352Z,1682365583.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-24T19:46:24.984Z,1682365584.984 [DVL_micro](ERROR): Failed to parse:
:WI,-00150,01598,+00000,A
2023-04-24T19:46:31.167Z,1682365591.167 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230424T182115/Courier0042.lzma
2023-04-24T19:46:32.170Z,1682365592.170 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Courier0042.lzma.bak
2023-04-24T19:46:32.170Z,1682365592.170 [DataOverHttps](INFO): SBD MOMSN=18259961
2023-04-24T19:46:52.164Z,1682365612.164 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230424T182115/Express0043.lzma
2023-04-24T19:46:53.166Z,1682365613.166 [DataOverHttps](INFO): Moved sent file to Logs/20230424T182115/Express0043.lzma.bak
2023-04-24T19:46:53.166Z,1682365613.166 [DataOverHttps](INFO): SBD MOMSN=18259964
2023-04-24T19:46:54.545Z,1682365614.545 [Default:CheckIn:Read_Iridium] Stopped
2023-04-24T19:46:54.545Z,1682365614.545 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-24T19:46:54.545Z,1682365614.545 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-24T19:46:55.350Z,1682365615.350 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-24T19:48:04.034Z,1682365684.034 [DVL_micro](ERROR): Failed to parse:154,+00006,-01552,+00000,I
2023-04-24T19:50:25.164Z,1682365825.164 [CommandExec](IMPORTANT): got command quit
2023-04-24T19:50:26.168Z,1682365826.168 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:26.168Z,1682365826.168 [CommandExec](INFO): Uninitializing the command executive.
2023-04-24T19:50:26.168Z,1682365826.168 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-24T19:50:26.168Z,1682365826.168 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.248Z,1682365826.248 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-24T19:50:26.248Z,1682365826.248 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-24T19:50:26.248Z,1682365826.248 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.249Z,1682365826.249 [NavChartDb](INFO): Join timeout helper Thread ID is 1453
2023-04-24T19:50:26.428Z,1682365826.428 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:26.428Z,1682365826.428 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.436Z,1682365826.436 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-04-24T19:50:26.436Z,1682365826.436 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.437Z,1682365826.437 [DAT](INFO): Join timeout helper Thread ID is 1454
2023-04-24T19:50:26.620Z,1682365826.620 [DAT](INFO): Powering down
2023-04-24T19:50:26.693Z,1682365826.693 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:26.693Z,1682365826.693 [DAT](INFO): Powering down
2023-04-24T19:50:26.694Z,1682365826.694 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.712Z,1682365826.712 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-24T19:50:26.712Z,1682365826.712 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.713Z,1682365826.713 [Radio_Surface](INFO): Join timeout helper Thread ID is 1458
2023-04-24T19:50:26.784Z,1682365826.784 [Radio_Surface](INFO): Powering down
2023-04-24T19:50:26.785Z,1682365826.785 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:26.785Z,1682365826.785 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.796Z,1682365826.796 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-24T19:50:26.796Z,1682365826.796 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:26.797Z,1682365826.797 [Onboard](INFO): Join timeout helper Thread ID is 1459
2023-04-24T19:50:27.140Z,1682365827.140 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-04-24T19:50:27.604Z,1682365827.604 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:27.604Z,1682365827.604 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:27.616Z,1682365827.616 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-24T19:50:27.616Z,1682365827.616 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:27.616Z,1682365827.616 [DataOverHttps](INFO): Join timeout helper Thread ID is 1460
2023-04-24T19:50:28.144Z,1682365828.144 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:28.144Z,1682365828.144 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.165Z,1682365828.165 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-24T19:50:28.165Z,1682365828.165 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.165Z,1682365828.165 [BackseatComponent](INFO): Join timeout helper Thread ID is 1464
2023-04-24T19:50:28.332Z,1682365828.332 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:28.332Z,1682365828.332 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.340Z,1682365828.340 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-24T19:50:28.341Z,1682365828.341 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.341Z,1682365828.341 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1465
2023-04-24T19:50:28.380Z,1682365828.380 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:28.380Z,1682365828.380 [WetLabsBB2FL](INFO): Powering down
2023-04-24T19:50:28.381Z,1682365828.381 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.382Z,1682365828.382 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-24T19:50:28.382Z,1682365828.382 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.383Z,1682365828.383 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1466
2023-04-24T19:50:28.908Z,1682365828.908 [CTD_Seabird](INFO): Powering down
2023-04-24T19:50:28.920Z,1682365828.920 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:28.920Z,1682365828.920 [CTD_Seabird](INFO): Powering down
2023-04-24T19:50:28.932Z,1682365828.932 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.950Z,1682365828.950 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-24T19:50:28.950Z,1682365828.950 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.952Z,1682365828.952 [logger](INFO): Join timeout helper Thread ID is 1470
2023-04-24T19:50:28.970Z,1682365828.970 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:28.970Z,1682365828.970 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.984Z,1682365828.984 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-24T19:50:28.985Z,1682365828.985 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:28.985Z,1682365828.985 [CommandLine](INFO): Join timeout helper Thread ID is 1471
2023-04-24T19:50:29.080Z,1682365829.080 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:29.081Z,1682365829.081 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.093Z,1682365829.093 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-24T19:50:29.093Z,1682365829.093 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.093Z,1682365829.093 [CommandExec](INFO): Join timeout helper Thread ID is 1472
2023-04-24T19:50:29.094Z,1682365829.094 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-24T19:50:29.094Z,1682365829.094 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.095Z,1682365829.095 [controlThread](INFO): Join timeout helper Thread ID is 1473
2023-04-24T19:50:29.146Z,1682365829.146 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-24T19:50:29.146Z,1682365829.146 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-24T19:50:29.147Z,1682365829.147 [AHRS_M2](INFO): Powering down
2023-04-24T19:50:29.292Z,1682365829.292 [DVL_micro](INFO): Powering down
2023-04-24T19:50:29.293Z,1682365829.293 [NAL9602](INFO): Powering down
2023-04-24T19:50:29.295Z,1682365829.295 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-24T19:50:29.296Z,1682365829.296 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-24T19:50:29.296Z,1682365829.296 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-24T19:50:29.297Z,1682365829.297 [MissionManager](INFO): Uninitializing Mission Default
2023-04-24T19:50:29.297Z,1682365829.297 [Default] Stopped
2023-04-24T19:50:29.297Z,1682365829.297 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-24T19:50:29.297Z,1682365829.297 [Default:B.GoToSurface] Stopped
2023-04-24T19:50:29.297Z,1682365829.297 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-24T19:50:29.297Z,1682365829.297 [Default:CheckIn] Stopped
2023-04-24T19:50:29.297Z,1682365829.297 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-24T19:50:29.297Z,1682365829.297 [Default:CheckIn:C.Wait] Stopped
2023-04-24T19:50:29.298Z,1682365829.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-24T19:50:29.300Z,1682365829.300 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-24T19:50:29.301Z,1682365829.301 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-24T19:50:29.301Z,1682365829.301 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-24T19:50:29.301Z,1682365829.301 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-24T19:50:29.301Z,1682365829.301 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-24T19:50:29.302Z,1682365829.302 [BuoyancyServo](INFO): Powering down
2023-04-24T19:50:29.316Z,1682365829.316 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-24T19:50:29.316Z,1682365829.316 [ElevatorServo](INFO): Powering down
2023-04-24T19:50:29.317Z,1682365829.317 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-24T19:50:29.317Z,1682365829.317 [MassServo](INFO): Powering down
2023-04-24T19:50:29.318Z,1682365829.318 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-24T19:50:29.318Z,1682365829.318 [RudderServo](INFO): Powering down
2023-04-24T19:50:29.319Z,1682365829.319 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-24T19:50:29.319Z,1682365829.319 [ThrusterHE](INFO): Powering down
2023-04-24T19:50:29.320Z,1682365829.320 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-24T19:50:29.320Z,1682365829.320 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-24T19:50:29.321Z,1682365829.321 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-24T19:50:29.321Z,1682365829.321 [CBIT](DEBUG): Powering off loads.
2023-04-24T19:50:29.332Z,1682365829.332 [CBIT](DEBUG): Disabling WDT.
2023-04-24T19:50:29.344Z,1682365829.344 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-24T19:50:29.345Z,1682365829.345 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.385Z,1682365829.385 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.473Z,1682365829.473 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.504Z,1682365829.504 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.508Z,1682365829.508 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.510Z,1682365829.510 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.517Z,1682365829.517 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.559Z,1682365829.559 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.611Z,1682365829.611 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-24T19:50:29.689Z,1682365829.689 [logger ThreadHandler](INFO): Thread cancelled.