2022-10-17T19:31:37.675Z,1666035097.675 [DataOverHttps](INFO): Received command: restart logs
2022-10-17T19:31:37.679Z,1666035097.679 [CommandExec](IMPORTANT): got command restart logs
2022-10-17T19:31:52.333Z,1666035112.333 [DVL_micro](ERROR): only read 0 of 4 data items
2022-10-17T19:31:52.333Z,1666035112.333 [DVL_micro](ERROR): Failed to parse:
:RD,++0000.60,++0000.68
2022-10-17T19:31:57.230Z,1666035117.230 [DVL_micro](ERROR): Failed to parse:
:T00000000,35.0,+15.8,0000.0,1489.0,000
2022-10-17T19:32:30.928Z,1666035150.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-17T19:34:02.475Z,1666035242.475 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:34:02.476Z,1666035242.476 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,1489.0,000
2022-10-17T19:34:27.940Z,1666035267.940 [DVL_micro](ERROR): Failed to parse:
:B,-00022,-00005,+00000,A
2022-10-17T19:35:56.462Z,1666035356.462 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T19:35:59.681Z,1666035359.681 [DVL_micro](ERROR): Failed to parse:0
2022-10-17T19:36:03.718Z,1666035363.718 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193559.00,A,3648.34372,N,12147.35516,W,0.447,214.95,171022,,,D*7D
2022-10-17T19:36:03.721Z,1666035363.721 [NAL9602](INFO): GPS fix at 20221017T193559: (36.805729, -121.789253)
2022-10-17T19:36:03.746Z,1666035363.746 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T19:36:03.746Z,1666035363.746 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T19:36:13.444Z,1666035373.444 [DVL_micro](ERROR): Failed to parse:
:SA,-03.21,+13.10,069.5
2022-10-17T19:36:15.751Z,1666035375.751 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T154829/Courier0058.lzma
2022-10-17T19:36:16.754Z,1666035376.754 [DataOverHttps](INFO): Moved sent file to Logs/20221017T154829/Courier0058.lzma.bak
2022-10-17T19:36:16.754Z,1666035376.754 [DataOverHttps](IMPORTANT): SBD MOMSN=17247077, MTMSN=20221017T193612
2022-10-17T19:36:26.407Z,1666035386.407 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0001.10,000.00
2022-10-17T19:36:36.048Z,1666035396.048 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T19:36:58.028Z,1666035418.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-17T19:37:08.095Z,1666035428.095 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T19:37:08.095Z,1666035428.095 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.38,+0001.32,2
2022-10-17T19:37:16.651Z,1666035436.651 [DataOverHttps](INFO): Received command: strobe off
2022-10-17T19:37:16.749Z,1666035436.749 [CommandExec](IMPORTANT): got command strobe off
2022-10-17T19:37:16.750Z,1666035436.750 [CommandExec](IMPORTANT): Deactivating strobe
2022-10-17T19:37:48.780Z,1666035468.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-17T19:38:19.904Z,1666035499.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-10-17T19:38:30.527Z,1666035510.527 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20221017T193137/Courier0000.lzma
2022-10-17T19:38:31.530Z,1666035511.530 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0000.lzma.bak
2022-10-17T19:38:31.530Z,1666035511.530 [DataOverHttps](INFO): SBD MOMSN=17247088
2022-10-17T19:38:40.261Z,1666035520.261 [DVL_micro](ERROR): only read 2 of 4 data items
2022-10-17T19:38:40.261Z,1666035520.261 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0001..82,+0001.88
2022-10-17T19:39:00.119Z,1666035540.119 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221017T193137/Courier0006.lzma
2022-10-17T19:39:01.122Z,1666035541.122 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0006.lzma.bak
2022-10-17T19:39:01.122Z,1666035541.122 [DataOverHttps](INFO): SBD MOMSN=17247096
2022-10-17T19:39:14.324Z,1666035554.324 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:39:14.325Z,1666035554.325 [DVL_micro](ERROR): Failed to parse::TS,000000035.0,+15.9,0000.0,1489.0,000
2022-10-17T19:39:29.285Z,1666035569.285 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:39:29.285Z,1666035569.285 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+15.9,0000.0,1489.0,000
2022-10-17T19:39:43.459Z,1666035583.459 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20221017T154829/Express0059.lzma
2022-10-17T19:39:44.462Z,1666035584.462 [DataOverHttps](INFO): Moved sent file to Logs/20221017T154829/Express0059.lzma.bak
2022-10-17T19:39:44.462Z,1666035584.462 [DataOverHttps](INFO): SBD MOMSN=17247100
2022-10-17T19:39:53.922Z,1666035593.922 [DVL_micro](ERROR): Failed to parse:00.34,+0001.74,+0000.27,+0002.04
2022-10-17T19:40:13.069Z,1666035613.069 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20221017T193137/Express0001.lzma
2022-10-17T19:40:14.070Z,1666035614.070 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0001.lzma.bak
2022-10-17T19:40:14.070Z,1666035614.070 [DataOverHttps](INFO): SBD MOMSN=17247104
2022-10-17T19:40:21.811Z,1666035621.811 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:40:21.811Z,1666035621.811 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.9,0000.0,1489:WI,+00000,+00000,+00000,+00000,I
2022-10-17T19:40:37.558Z,1666035637.558 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T19:40:37.558Z,1666035637.558 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0001.22,+0001..24
2022-10-17T19:40:38.399Z,1666035638.399 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20221017T193137/Express0004.lzma
2022-10-17T19:40:39.407Z,1666035639.407 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0004.lzma.bak
2022-10-17T19:40:39.407Z,1666035639.407 [DataOverHttps](INFO): SBD MOMSN=17247108
2022-10-17T19:40:47.687Z,1666035647.687 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:40:47.745Z,1666035647.745 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+15.9,0000.0,1489.0,000
2022-10-17T19:41:12.004Z,1666035672.004 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20221017T193137/Express0007.lzma
2022-10-17T19:41:13.006Z,1666035673.006 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0007.lzma.bak
2022-10-17T19:41:13.006Z,1666035673.006 [DataOverHttps](INFO): SBD MOMSN=17247110
2022-10-17T19:41:21.628Z,1666035681.628 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T19:41:21.628Z,1666035681.628 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T19:41:21.628Z,1666035681.628 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T19:41:33.342Z,1666035693.342 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00000,+00000,+00000,I
2022-10-17T19:41:35.356Z,1666035695.356 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T19:41:35.356Z,1666035695.356 [DVL_micro](ERROR): Failed to parse:
:BI,00009,+00004,+00000,A
2022-10-17T19:42:47.679Z,1666035767.679 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T19:42:47.680Z,1666035767.680 [DVL_micro](ERROR): Failed to parse:
:BI,-00132,+00213,+00079,
2022-10-17T19:42:52.562Z,1666035772.562 [DVL_micro](ERROR): only read 2 of 4 data items
2022-10-17T19:42:52.562Z,1666035772.562 [DVL_micro](ERROR): Failed to parse:
:RD,+99900.69,+00000.69
2022-10-17T19:46:22.205Z,1666035982.205 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T19:46:22.205Z,1666035982.205 [Default:CheckIn:C.Wait] Stopped
2022-10-17T19:46:22.205Z,1666035982.205 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T19:46:22.205Z,1666035982.205 [Default:CheckIn:D] Running Loop=1
2022-10-17T19:46:22.623Z,1666035982.623 [Default:CheckIn:D] Stopped
2022-10-17T19:46:22.623Z,1666035982.623 [Default:CheckIn:E] Running Loop=1
2022-10-17T19:46:23.024Z,1666035983.024 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.468817 min 
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn:E] Stopped
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn] Stopped
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn](INFO): Running loop #2
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn] Running Loop=2
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T19:46:23.025Z,1666035983.025 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T19:46:25.031Z,1666035985.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194620.00,A,3648.34916,N,12147.34822,W,0.097,41.54,171022,,,D*42
2022-10-17T19:46:25.033Z,1666035985.033 [NAL9602](INFO): GPS fix at 20221017T194620: (36.805819, -121.789137)
2022-10-17T19:46:25.085Z,1666035985.085 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T19:46:25.085Z,1666035985.085 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T19:46:37.023Z,1666035997.023 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221017T193137/Courier0009.lzma
2022-10-17T19:46:38.026Z,1666035998.026 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0009.lzma.bak
2022-10-17T19:46:38.026Z,1666035998.026 [DataOverHttps](INFO): SBD MOMSN=17247123
2022-10-17T19:46:40.381Z,1666036000.381 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:46:40.381Z,1666036000.381 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.9,00000,000
2022-10-17T19:46:45.646Z,1666036005.646 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:46:45.646Z,1666036005.646 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.9,0000.0,1489.0I,+02046,+01589,-02310,+00000,A
2022-10-17T19:46:55.759Z,1666036015.759 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T19:46:55.836Z,1666036015.836 [NAL9602](ERROR): received: 
+CSQ:0

OKúÇcáÜ
Wm®¶ï%rˆ3“
BüœhÖ59¼eztÿÌÝÛé”: $}&ssú’<hóÁÁxCÂo	¬5¼c.F�O8¶g¾ªÿ
2022-10-17T19:47:08.832Z,1666036028.832 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20221017T193137/Express0010.lzma
2022-10-17T19:47:09.834Z,1666036029.834 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0010.lzma.bak
2022-10-17T19:47:09.834Z,1666036029.834 [DataOverHttps](INFO): SBD MOMSN=17247126
2022-10-17T19:47:20.467Z,1666036040.467 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T19:47:20.467Z,1666036040.467 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T19:47:20.467Z,1666036040.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T19:47:43.881Z,1666036063.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=47338, MT Status=2, MTMSN=0
2022-10-17T19:47:43.881Z,1666036063.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-17T19:47:52.353Z,1666036072.353 [NAL9602](INFO): SBD MO Status=2, MOMSN=47338, MT Status=2, MTMSN=0
2022-10-17T19:47:52.354Z,1666036072.354 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-17T19:50:12.959Z,1666036212.959 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:50:12.959Z,1666036212.959 [DVL_micro](ERROR): Failed to parse:
:TS,000000035.0,+16.1,0000.0,1489.0,000
2022-10-17T19:50:35.169Z,1666036235.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=47338, MT Status=2, MTMSN=0
2022-10-17T19:50:35.169Z,1666036235.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-17T19:51:27.279Z,1666036287.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T19:51:58.017Z,1666036318.017 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T19:52:21.025Z,1666036341.025 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T19:52:21.025Z,1666036341.025 [Default:CheckIn:C.Wait] Stopped
2022-10-17T19:52:21.025Z,1666036341.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T19:52:21.026Z,1666036341.026 [Default:CheckIn:D] Running Loop=1
2022-10-17T19:52:21.427Z,1666036341.427 [Default:CheckIn:D] Stopped
2022-10-17T19:52:21.428Z,1666036341.428 [Default:CheckIn:E] Running Loop=1
2022-10-17T19:52:21.839Z,1666036341.839 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.448887 min 
2022-10-17T19:52:21.839Z,1666036341.839 [Default:CheckIn:E] Stopped
2022-10-17T19:52:21.839Z,1666036341.839 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T19:52:21.839Z,1666036341.839 [Default:CheckIn] Stopped
2022-10-17T19:52:21.839Z,1666036341.839 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T19:52:21.840Z,1666036341.840 [Default:CheckIn](INFO): Running loop #3
2022-10-17T19:52:21.840Z,1666036341.840 [Default:CheckIn] Running Loop=3
2022-10-17T19:52:21.840Z,1666036341.840 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T19:52:21.840Z,1666036341.840 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T19:52:23.847Z,1666036343.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195219.00,A,3648.35286,N,12147.34392,W,0.019,52.21,171022,,,D*48
2022-10-17T19:52:23.850Z,1666036343.850 [NAL9602](INFO): GPS fix at 20221017T195219: (36.805881, -121.789065)
2022-10-17T19:52:23.884Z,1666036343.884 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T19:52:23.884Z,1666036343.884 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T19:52:35.379Z,1666036355.379 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0012.lzma
2022-10-17T19:52:36.382Z,1666036356.382 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0012.lzma.bak
2022-10-17T19:52:36.382Z,1666036356.382 [DataOverHttps](INFO): SBD MOMSN=17247139
2022-10-17T19:52:54.638Z,1666036374.638 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T19:52:54.720Z,1666036374.720 [NAL9602](ERROR): received: 
+CSQ:0

OK338, 2, 0, 0, 0

OK
2022-10-17T19:53:10.181Z,1666036390.181 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20221017T193137/Express0013.lzma
2022-10-17T19:53:11.182Z,1666036391.182 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0013.lzma.bak
2022-10-17T19:53:11.182Z,1666036391.182 [DataOverHttps](INFO): SBD MOMSN=17247142
2022-10-17T19:53:20.180Z,1666036400.180 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T19:53:20.180Z,1666036400.180 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T19:53:20.180Z,1666036400.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T19:53:48.444Z,1666036428.444 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:53:48.444Z,1666036428.444 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,1489.0,0+04397,+0065,+00000,A
2022-10-17T19:54:26.418Z,1666036466.418 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:54:26.418Z,1666036466.418 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+16.4,0000.0,1489.0,000
2022-10-17T19:57:26.213Z,1666036646.213 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T19:57:28.633Z,1666036648.633 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T19:57:28.633Z,1666036648.633 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2022-10-17T19:57:56.907Z,1666036676.907 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T19:58:20.760Z,1666036700.760 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T19:58:20.760Z,1666036700.760 [Default:CheckIn:C.Wait] Stopped
2022-10-17T19:58:20.760Z,1666036700.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T19:58:20.760Z,1666036700.760 [Default:CheckIn:D] Running Loop=1
2022-10-17T19:58:21.246Z,1666036701.246 [Default:CheckIn:D] Stopped
2022-10-17T19:58:21.246Z,1666036701.246 [Default:CheckIn:E] Running Loop=1
2022-10-17T19:58:21.592Z,1666036701.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.445870 min 
2022-10-17T19:58:21.592Z,1666036701.592 [Default:CheckIn:E] Stopped
2022-10-17T19:58:21.592Z,1666036701.592 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T19:58:21.592Z,1666036701.592 [Default:CheckIn] Stopped
2022-10-17T19:58:21.592Z,1666036701.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T19:58:21.593Z,1666036701.593 [Default:CheckIn](INFO): Running loop #4
2022-10-17T19:58:21.593Z,1666036701.593 [Default:CheckIn] Running Loop=4
2022-10-17T19:58:21.593Z,1666036701.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T19:58:21.593Z,1666036701.593 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T19:58:23.576Z,1666036703.576 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195819.00,A,3648.35218,N,12147.34422,W,0.019,108.49,171022,,,D*79
2022-10-17T19:58:23.579Z,1666036703.579 [NAL9602](INFO): GPS fix at 20221017T195819: (36.805870, -121.789070)
2022-10-17T19:58:23.588Z,1666036703.588 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T19:58:23.588Z,1666036703.588 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T19:58:35.339Z,1666036715.339 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221017T193137/Courier0015.lzma
2022-10-17T19:58:36.342Z,1666036716.342 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0015.lzma.bak
2022-10-17T19:58:36.342Z,1666036716.342 [DataOverHttps](INFO): SBD MOMSN=17247159
2022-10-17T19:58:41.863Z,1666036721.863 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T19:58:41.863Z,1666036721.863 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2022-10-17T19:58:54.395Z,1666036734.395 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T19:58:54.472Z,1666036734.472 [NAL9602](ERROR): received: 
+CSQ:0

OK338, 2, 0, 0, 0

OK
2022-10-17T19:59:00.540Z,1666036740.540 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20221017T193137/Express0016.lzma
2022-10-17T19:59:01.542Z,1666036741.542 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0016.lzma.bak
2022-10-17T19:59:01.542Z,1666036741.542 [DataOverHttps](INFO): SBD MOMSN=17247162
2022-10-17T19:59:20.146Z,1666036760.146 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T19:59:20.146Z,1666036760.146 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T19:59:20.146Z,1666036760.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T19:59:47.615Z,1666036787.615 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T19:59:47.632Z,1666036787.632 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+16.8,489.0,000
2022-10-17T20:03:26.175Z,1666037006.175 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:03:56.883Z,1666037036.883 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:04:20.734Z,1666037060.734 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:04:20.734Z,1666037060.734 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:04:20.734Z,1666037060.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:04:20.734Z,1666037060.734 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:04:21.128Z,1666037061.128 [Default:CheckIn:D] Stopped
2022-10-17T20:04:21.128Z,1666037061.128 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:04:21.535Z,1666037061.535 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.443903 min 
2022-10-17T20:04:21.535Z,1666037061.535 [Default:CheckIn:E] Stopped
2022-10-17T20:04:21.535Z,1666037061.535 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:04:21.535Z,1666037061.535 [Default:CheckIn] Stopped
2022-10-17T20:04:21.536Z,1666037061.536 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:04:21.536Z,1666037061.536 [Default:CheckIn](INFO): Running loop #5
2022-10-17T20:04:21.536Z,1666037061.536 [Default:CheckIn] Running Loop=5
2022-10-17T20:04:21.536Z,1666037061.536 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:04:21.536Z,1666037061.536 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:04:23.553Z,1666037063.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200419.00,A,3648.35161,N,12147.34412,W,0.019,108.49,171022,,,D*74
2022-10-17T20:04:23.555Z,1666037063.555 [NAL9602](INFO): GPS fix at 20221017T200419: (36.805860, -121.789069)
2022-10-17T20:04:23.581Z,1666037063.581 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:04:23.581Z,1666037063.581 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:04:35.223Z,1666037075.223 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221017T193137/Courier0018.lzma
2022-10-17T20:04:36.227Z,1666037076.227 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0018.lzma.bak
2022-10-17T20:04:36.228Z,1666037076.228 [DataOverHttps](INFO): SBD MOMSN=17247176
2022-10-17T20:04:54.252Z,1666037094.252 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:04:54.336Z,1666037094.336 [NAL9602](ERROR): received: 
+CSQ:0

OK338, 2, 0, 0, 0

OK
2022-10-17T20:05:00.396Z,1666037100.396 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20221017T193137/Express0019.lzma
2022-10-17T20:05:01.398Z,1666037101.398 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0019.lzma.bak
2022-10-17T20:05:01.398Z,1666037101.398 [DataOverHttps](INFO): SBD MOMSN=17247179
2022-10-17T20:05:22.283Z,1666037122.283 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:05:22.283Z,1666037122.283 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:05:22.283Z,1666037122.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:09:26.263Z,1666037366.263 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:09:56.986Z,1666037396.986 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:10:22.829Z,1666037422.829 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:10:22.829Z,1666037422.829 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:10:22.829Z,1666037422.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:10:22.830Z,1666037422.830 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:10:23.232Z,1666037423.232 [Default:CheckIn:D] Stopped
2022-10-17T20:10:23.233Z,1666037423.233 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:10:23.643Z,1666037423.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.478959 min 
2022-10-17T20:10:23.643Z,1666037423.643 [Default:CheckIn:E] Stopped
2022-10-17T20:10:23.643Z,1666037423.643 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:10:23.643Z,1666037423.643 [Default:CheckIn] Stopped
2022-10-17T20:10:23.643Z,1666037423.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:10:23.643Z,1666037423.643 [Default:CheckIn](INFO): Running loop #6
2022-10-17T20:10:23.644Z,1666037423.644 [Default:CheckIn] Running Loop=6
2022-10-17T20:10:23.644Z,1666037423.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:10:23.644Z,1666037423.644 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:10:25.657Z,1666037425.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201021.00,A,3648.34769,N,12147.34040,W,2.002,157.36,171022,,,D*7C
2022-10-17T20:10:25.659Z,1666037425.659 [NAL9602](INFO): GPS fix at 20221017T201021: (36.805795, -121.789007)
2022-10-17T20:10:25.674Z,1666037425.674 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:10:25.674Z,1666037425.674 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:10:36.971Z,1666037436.971 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0021.lzma
2022-10-17T20:10:37.974Z,1666037437.974 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0021.lzma.bak
2022-10-17T20:10:37.974Z,1666037437.974 [DataOverHttps](INFO): SBD MOMSN=17247193
2022-10-17T20:10:45.051Z,1666037445.051 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:10:45.051Z,1666037445.051 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+17.5,0000.0000
2022-10-17T20:10:56.371Z,1666037456.371 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:10:56.448Z,1666037456.448 [NAL9602](ERROR): received: 
+CSQ:0

OK338, 2, 0, 0, 0

OK
2022-10-17T20:11:07.680Z,1666037467.680 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221017T193137/Express0022.lzma
2022-10-17T20:11:08.682Z,1666037468.682 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0022.lzma.bak
2022-10-17T20:11:08.682Z,1666037468.682 [DataOverHttps](INFO): SBD MOMSN=17247196
2022-10-17T20:11:21.191Z,1666037481.191 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:11:21.191Z,1666037481.191 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:11:21.191Z,1666037481.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:13:24.305Z,1666037604.305 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:13:24.305Z,1666037604.305 [DVL_micro](ERROR): Failed to parse:
:TS0000000,38,0000.0,14
2022-10-17T20:15:28.341Z,1666037728.341 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:15:59.046Z,1666037759.046 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:16:21.701Z,1666037781.701 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:16:21.701Z,1666037781.701 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:16:21.701Z,1666037781.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:16:21.701Z,1666037781.701 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:16:22.081Z,1666037782.081 [Default:CheckIn:D] Stopped
2022-10-17T20:16:22.081Z,1666037782.081 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:16:22.486Z,1666037782.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.459778 min 
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn:E] Stopped
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn] Stopped
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn](INFO): Running loop #7
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn] Running Loop=7
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:16:22.487Z,1666037782.487 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:16:24.503Z,1666037784.503 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201620.00,A,3648.38948,N,12147.29094,W,1.924,83.12,171022,,,D*4F
2022-10-17T20:16:24.506Z,1666037784.506 [NAL9602](INFO): GPS fix at 20221017T201620: (36.806491, -121.788182)
2022-10-17T20:16:24.516Z,1666037784.516 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:16:24.516Z,1666037784.516 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:16:35.951Z,1666037795.951 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20221017T193137/Courier0024.lzma
2022-10-17T20:16:36.954Z,1666037796.954 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0024.lzma.bak
2022-10-17T20:16:36.954Z,1666037796.954 [DataOverHttps](INFO): SBD MOMSN=17247209
2022-10-17T20:16:55.286Z,1666037815.286 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:16:55.364Z,1666037815.364 [NAL9602](ERROR): received: 
+CSQ:0

OK338, 2, 0, 0, 0

OK
2022-10-17T20:17:09.568Z,1666037829.568 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20221017T193137/Express0025.lzma
2022-10-17T20:17:10.570Z,1666037830.570 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0025.lzma.bak
2022-10-17T20:17:10.570Z,1666037830.570 [DataOverHttps](INFO): SBD MOMSN=17247217
2022-10-17T20:17:22.875Z,1666037842.875 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:17:22.875Z,1666037842.875 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:17:22.875Z,1666037842.875 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:17:24.458Z,1666037844.458 [DVL_micro](ERROR): Failed to parse:
:WI,+01190,+01342,-01644,+00000,A
2022-10-17T20:17:29.297Z,1666037849.297 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:17:29.297Z,1666037849.297 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000008.0,0000.0,1489.0,000
2022-10-17T20:17:34.608Z,1666037854.608 [DVL_micro](ERROR): only read 0 of 4 data items
2022-10-17T20:17:34.608Z,1666037854.608 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999.99,+9999.99,+9999.99
2022-10-17T20:21:26.449Z,1666038086.449 [NAL9602](INFO): SBD MO Status=2, MOMSN=47338, MT Status=2, MTMSN=0
2022-10-17T20:21:26.449Z,1666038086.449 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-17T20:21:27.263Z,1666038087.263 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:21:57.959Z,1666038117.959 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:22:23.443Z,1666038143.443 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:22:23.443Z,1666038143.443 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:22:23.443Z,1666038143.443 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:22:23.443Z,1666038143.443 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:22:23.851Z,1666038143.851 [Default:CheckIn:D] Stopped
2022-10-17T20:22:23.851Z,1666038143.851 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:22:24.239Z,1666038144.239 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.489278 min 
2022-10-17T20:22:24.239Z,1666038144.239 [Default:CheckIn:E] Stopped
2022-10-17T20:22:24.239Z,1666038144.239 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:22:24.239Z,1666038144.239 [Default:CheckIn] Stopped
2022-10-17T20:22:24.239Z,1666038144.239 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:22:24.260Z,1666038144.260 [Default:CheckIn](INFO): Running loop #8
2022-10-17T20:22:24.260Z,1666038144.260 [Default:CheckIn] Running Loop=8
2022-10-17T20:22:24.260Z,1666038144.260 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:22:24.260Z,1666038144.260 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:22:26.243Z,1666038146.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202221.00,A,3648.28594,N,12147.22485,W,2.488,172.42,171022,,,D*78
2022-10-17T20:22:26.246Z,1666038146.246 [NAL9602](INFO): GPS fix at 20221017T202221: (36.804766, -121.787081)
2022-10-17T20:22:26.263Z,1666038146.263 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:22:26.263Z,1666038146.263 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:22:31.283Z,1666038151.283 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0027.lzma
2022-10-17T20:22:32.290Z,1666038152.290 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0027.lzma.bak
2022-10-17T20:22:32.290Z,1666038152.290 [DataOverHttps](INFO): SBD MOMSN=17247257
2022-10-17T20:22:35.173Z,1666038155.173 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T20:22:35.173Z,1666038155.173 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999..99
2022-10-17T20:22:56.949Z,1666038176.949 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:22:57.032Z,1666038177.032 [NAL9602](ERROR): received: 
+SBDI: 2, 47338, 2, 0, 0, 0

OK
2022-10-17T20:22:57.656Z,1666038177.656 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20221017T193137/Express0028.lzma
2022-10-17T20:22:58.658Z,1666038178.658 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0028.lzma.bak
2022-10-17T20:22:58.658Z,1666038178.658 [DataOverHttps](INFO): SBD MOMSN=17247260
2022-10-17T20:23:07.476Z,1666038187.476 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:23:07.476Z,1666038187.476 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:23:07.477Z,1666038187.477 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:23:35.817Z,1666038215.817 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=47338, MT Status=1, MTMSN=3527
2022-10-17T20:23:35.817Z,1666038215.817 [NAL9602](INFO): Data available in MT queue
2022-10-17T20:23:36.342Z,1666038216.342 [NAL9602](INFO): Received command: sched asap "set keepstation.Speed 0.8 m/s;set keepstation.MaxDepth 25 m;run" 48whu 2 2
2022-10-17T20:23:36.403Z,1666038216.403 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.Speed 0.8 m/s;set keepstation.MaxDepth 25 m;run" 48whu 2 2.000000
2022-10-17T20:23:36.404Z,1666038216.404 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=48whu
2022-10-17T20:23:36.405Z,1666038216.405 [CommandExec](IMPORTANT): Scheduled #9 (#2 of 2 with id='48whu'): "set keepstation.Speed 0.8 m/s;set keepstation.MaxDepth 25 m;run", ASAP
2022-10-17T20:23:36.518Z,1666038216.518 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2022-10-17T20:23:36.519Z,1666038216.519 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2022-10-17T20:23:36.519Z,1666038216.519 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Transport/keepstation.tx
2022-10-17T20:23:36.728Z,1666038216.728 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2022-10-17T20:23:36.730Z,1666038216.730 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2022-10-17T20:23:36.733Z,1666038216.733 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2022-10-17T20:23:36.735Z,1666038216.735 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2022-10-17T20:23:36.737Z,1666038216.737 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2022-10-17T20:23:36.739Z,1666038216.739 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2022-10-17T20:23:36.741Z,1666038216.741 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2022-10-17T20:23:36.743Z,1666038216.743 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2022-10-17T20:23:36.791Z,1666038216.791 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2022-10-17T20:23:36.825Z,1666038216.825 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2022-10-17T20:23:36.827Z,1666038216.827 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2022-10-17T20:23:36.827Z,1666038216.827 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.xml
2022-10-17T20:23:36.932Z,1666038216.932 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 3.000000 h
2022-10-17T20:23:36.934Z,1666038216.934 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2022-10-17T20:23:36.945Z,1666038216.945 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2022-10-17T20:23:36.947Z,1666038216.947 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2022-10-17T20:23:36.950Z,1666038216.950 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2022-10-17T20:23:36.952Z,1666038216.952 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2022-10-17T20:23:36.954Z,1666038216.954 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2022-10-17T20:23:36.956Z,1666038216.956 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2022-10-17T20:23:37.046Z,1666038217.046 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-10-17T20:23:37.103Z,1666038217.103 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.xml
2022-10-17T20:23:37.260Z,1666038217.260 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2022-10-17T20:23:37.263Z,1666038217.263 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2022-10-17T20:23:37.269Z,1666038217.269 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2022-10-17T20:23:37.270Z,1666038217.270 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2022-10-17T20:23:37.312Z,1666038217.312 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2022-10-17T20:23:37.342Z,1666038217.342 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2022-10-17T20:23:37.366Z,1666038217.366 [keepstation:F.Pitch](DEBUG): Construct.
2022-10-17T20:23:37.429Z,1666038217.429 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2022-10-17T20:23:37.431Z,1666038217.431 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2022-10-17T20:23:37.460Z,1666038217.460 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2022-10-17T20:23:37.462Z,1666038217.462 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2022-10-17T20:23:37.502Z,1666038217.502 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2022-10-17T20:23:37.510Z,1666038217.510 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2022-10-17T20:23:37.526Z,1666038217.526 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2022-10-17T20:23:37.554Z,1666038217.554 [MissionManager](DEBUG): 
mission keepstation {
  """
  Vehicle transits to desired waypoint (or stays put if none is commanded)
  and stays within the specified radius.
  """

  arguments {
    MissionTimeout = 4 hour
      """
      Maximum duration of mission
      """

    NeedCommsTime = 45 minute
      """
      How often to surface for communications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to hold. If NaN, hold the latitude at start of
      mission.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to hold. If NaN, hold the longitude at start of
      mission.
      """

    Depth = 30 meter
      """
      Depth held during drift mode
      """

    ApproachDepth = 15 meter
      """
      Depth for initial approach to station.
      """

    DepthDeadband = 4 meter
      """
      How much vertical drift from the specified depth is allowed in drift
      mode
      """

    Speed = 0.75 meter_per_second
      """
      Vehicle transit speed.
      """

    Radius = 200 meter
      """
      Radius of circle around waypoint to hold.
      """

    MaxDepth = 45 meter
      """
      Maximum allowable depth during the mission.
      """

    MinOffshore = 2 kilometer
      """
      Minimum distance from the shoreline to maintain
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.xml

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.xml

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

  call id="StartingMission" refId="NeedComms"

  aggregate TransitToStation {
    """
    Need a separate aggregate for transit if we want to specify a
    different depth for the approach.
    """

    run in sequence

    behavior Guidance:Buoyancy {
      run in parallel

      set position = Control:VerticalControl.buoyancyNeutral
    }

    behavior Guidance:Pitch {
      run in parallel

      set depth = ApproachDepth
    }

    behavior Guidance:SetSpeed {
      run in parallel

      set speed = Speed
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
    }
  }

  call id="OnStation" refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

    behavior Guidance:Pitch {
      run in parallel

      set depth = Depth
    }

    behavior Guidance:KeepStation {
      run in parallel

      set latitude = Latitude
      set longitude = Longitude
      set radius = Radius
      set speed = Speed
    }

    behavior Guidance:Wait {
      """
      Due to the way the KeepStation behavior is currently
      implemented, we must run it in parallel and use a Wait to keep
      from completing the mission before the timeout expires. However,
      this new version of the mission uses a top-level timeout so that
      the entire mission will not run for longer then MissionTimeout.
      """

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2022-10-17T20:23:37.554Z,1666038217.554 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl
2022-10-17T20:23:57.739Z,1666038237.739 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 6 hour
2022-10-17T20:23:57.744Z,1666038237.744 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 30 minute
2022-10-17T20:23:57.747Z,1666038237.747 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.806966 degree
2022-10-17T20:23:57.756Z,1666038237.756 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.824326 degree
2022-10-17T20:23:57.757Z,1666038237.757 [CommandExec](IMPORTANT): got command set keepstation.Depth 10 meter
2022-10-17T20:24:03.518Z,1666038243.518 [CommandExec](IMPORTANT): got command set keepstation.Speed 0.8 meter_per_second
2022-10-17T20:24:03.518Z,1666038243.518 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 25 meter
2022-10-17T20:24:03.519Z,1666038243.519 [CommandExec](IMPORTANT): got command run
2022-10-17T20:24:03.525Z,1666038243.525 [CommandExec](IMPORTANT): Running 
2022-10-17T20:24:03.643Z,1666038243.643 [Default] Stopped
2022-10-17T20:24:03.664Z,1666038243.664 [Default](DEBUG): Aggregate::uninitialize Default
2022-10-17T20:24:03.664Z,1666038243.664 [Default:B.GoToSurface] Stopped
2022-10-17T20:24:03.664Z,1666038243.664 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-10-17T20:24:03.664Z,1666038243.664 [Default:CheckIn] Stopped
2022-10-17T20:24:03.664Z,1666038243.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:24:03.664Z,1666038243.664 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:24:03.664Z,1666038243.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:24:03.664Z,1666038243.664 [MissionManager](IMPORTANT): Started mission keepstation
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation] Running Loop=1
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation](DEBUG): Aggregate::initialize keepstation
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation:StandardEnvelopes] Running Loop=1
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2022-10-17T20:24:03.665Z,1666038243.665 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2022-10-17T20:24:03.666Z,1666038243.666 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2022-10-17T20:24:03.666Z,1666038243.666 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2022-10-17T20:24:03.666Z,1666038243.666 [keepstation:F.Pitch] Running Loop=1
2022-10-17T20:24:03.666Z,1666038243.666 [keepstation:F.Pitch](DEBUG): Initialize.
2022-10-17T20:24:03.666Z,1666038243.666 [keepstation:B] Running Loop=1
2022-10-17T20:24:03.667Z,1666038243.667 [keepstation:F.Pitch] Running Loop=1
2022-10-17T20:24:03.667Z,1666038243.667 [keepstation:StandardEnvelopes] Running Loop=1
2022-10-17T20:24:03.667Z,1666038243.667 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2022-10-17T20:24:03.687Z,1666038243.687 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2022-10-17T20:24:03.687Z,1666038243.687 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2022-10-17T20:24:03.696Z,1666038243.696 [keepstation:B] Stopped
2022-10-17T20:24:03.696Z,1666038243.696 [keepstation:D] Running Loop=1
2022-10-17T20:24:04.042Z,1666038244.042 [keepstation:D] Stopped
2022-10-17T20:24:04.042Z,1666038244.042 [keepstation:E] Running Loop=1
2022-10-17T20:24:04.423Z,1666038244.423 [keepstation:E] Stopped
2022-10-17T20:24:04.423Z,1666038244.423 [keepstation:StartingMission] Running Loop=1
2022-10-17T20:24:04.423Z,1666038244.423 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2022-10-17T20:24:04.836Z,1666038244.836 [keepstation:NeedComms] Running Loop=1
2022-10-17T20:24:04.836Z,1666038244.836 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2022-10-17T20:24:04.837Z,1666038244.837 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2022-10-17T20:24:04.837Z,1666038244.837 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-10-17T20:24:04.837Z,1666038244.837 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2022-10-17T20:24:04.837Z,1666038244.837 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2022-10-17T20:24:04.838Z,1666038244.838 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2022-10-17T20:24:04.838Z,1666038244.838 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2022-10-17T20:24:04.838Z,1666038244.838 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-10-17T20:24:04.839Z,1666038244.839 [keepstation:NeedComms:A] Running Loop=1
2022-10-17T20:24:04.840Z,1666038244.840 [keepstation:NeedComms:A](INFO): last time_fix was: 1666038141.000000 second since 1970/01/01T00:00:00Z 
2022-10-17T20:24:04.840Z,1666038244.840 [keepstation:NeedComms:A] Stopped
2022-10-17T20:24:05.246Z,1666038245.246 [keepstation:NeedComms:C] Running Loop=1
2022-10-17T20:24:05.654Z,1666038245.654 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-10-17T20:24:07.242Z,1666038247.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202402.00,A,3648.20488,N,12147.22277,W,3.071,184.12,171022,,,D*7F
2022-10-17T20:24:07.244Z,1666038247.244 [NAL9602](INFO): GPS fix at 20221017T202402: (36.803415, -121.787046)
2022-10-17T20:24:07.280Z,1666038247.280 [keepstation:NeedComms:C] Stopped
2022-10-17T20:24:07.280Z,1666038247.280 [keepstation:NeedComms:D] Running Loop=1
2022-10-17T20:24:07.689Z,1666038247.689 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-10-17T20:24:18.679Z,1666038258.679 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0030.lzma
2022-10-17T20:24:19.683Z,1666038259.683 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0030.lzma.bak
2022-10-17T20:24:19.683Z,1666038259.683 [DataOverHttps](INFO): SBD MOMSN=17247264
2022-10-17T20:24:39.253Z,1666038279.253 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:24:41.295Z,1666038281.295 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T20:24:41.295Z,1666038281.295 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2022-10-17T20:24:49.437Z,1666038289.437 [DataOverHttps](INFO): Sending 574 bytes from file Logs/20221017T193137/Express0031.lzma
2022-10-17T20:24:50.434Z,1666038290.434 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0031.lzma.bak
2022-10-17T20:24:50.434Z,1666038290.434 [DataOverHttps](INFO): SBD MOMSN=17247267
2022-10-17T20:24:56.630Z,1666038296.630 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2022-10-17T20:24:59.511Z,1666038299.511 [keepstation:NeedComms:D] Stopped
2022-10-17T20:24:59.516Z,1666038299.516 [keepstation:NeedComms:E] Running Loop=1
2022-10-17T20:24:59.927Z,1666038299.927 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-10-17T20:25:01.524Z,1666038301.524 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202457.00,A,3648.16787,N,12147.23488,W,1.924,226.13,171022,,,D*70
2022-10-17T20:25:01.527Z,1666038301.527 [NAL9602](INFO): GPS fix at 20221017T202457: (36.802798, -121.787248)
2022-10-17T20:25:01.579Z,1666038301.579 [keepstation:NeedComms:E] Stopped
2022-10-17T20:25:01.588Z,1666038301.588 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2022-10-17T20:25:01.588Z,1666038301.588 [keepstation:NeedComms] Stopped
2022-10-17T20:25:01.589Z,1666038301.589 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2022-10-17T20:25:01.589Z,1666038301.589 [keepstation:NeedComms:B.GoToSurface] Stopped
2022-10-17T20:25:01.589Z,1666038301.589 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-10-17T20:25:01.928Z,1666038301.928 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2022-10-17T20:25:01.928Z,1666038301.928 [keepstation:StartingMission] Stopped
2022-10-17T20:25:01.928Z,1666038301.928 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2022-10-17T20:25:01.928Z,1666038301.928 [keepstation:TransitToStation] Running Loop=1
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2022-10-17T20:25:01.929Z,1666038301.929 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2022-10-17T20:25:01.930Z,1666038301.930 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.806965,-121.824326
2022-10-17T20:25:02.302Z,1666038302.302 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2022-10-17T20:25:02.303Z,1666038302.303 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2022-10-17T20:25:02.309Z,1666038302.309 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2022-10-17T20:25:08.596Z,1666038308.596 [ThrusterHE](IMPORTANT): Status lock Detected.
2022-10-17T20:25:08.880Z,1666038308.880 [ThrusterHE](IMPORTANT): Status lock Detected.
2022-10-17T20:25:09.304Z,1666038309.304 [ThrusterHE](IMPORTANT): Status lock Detected.
2022-10-17T20:25:09.668Z,1666038309.668 [ThrusterHE](IMPORTANT): Status lock Detected.
2022-10-17T20:25:10.076Z,1666038310.076 [ThrusterHE](IMPORTANT): Status lock Detected.
2022-10-17T20:25:10.493Z,1666038310.493 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-10-17T20:25:10.493Z,1666038310.493 [ThrusterHE](INFO): Powering down
2022-10-17T20:25:11.393Z,1666038311.393 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-10-17T20:25:11.513Z,1666038311.513 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-10-17T20:26:25.105Z,1666038385.105 [NAL9602](INFO): SBD MO Status=2, MOMSN=47339, MT Status=2, MTMSN=0
2022-10-17T20:26:25.105Z,1666038385.105 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-10-17T20:26:57.953Z,1666038417.953 [DataOverHttps](IMPORTANT): SBD MTMSN=20221017T202653
2022-10-17T20:26:59.851Z,1666038419.851 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T20:26:59.852Z,1666038419.852 [DVL_micro](ERROR): Failed to parse:
:BI,-00204,+00122,-0094,I
2022-10-17T20:27:05.651Z,1666038425.651 [DataOverHttps](INFO): Received command: stop
2022-10-17T20:27:05.700Z,1666038425.700 [CommandExec](IMPORTANT): got command stop
2022-10-17T20:27:05.701Z,1666038425.701 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2022-10-17T20:27:05.943Z,1666038425.943 [MissionManager](INFO): MissionManager is completed.
2022-10-17T20:27:05.948Z,1666038425.948 [MissionManager](INFO): Uninitializing Mission keepstation
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation] Stopped
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation:StandardEnvelopes] Stopped
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2022-10-17T20:27:05.948Z,1666038425.948 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:F.Pitch] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:A.Buoyancy] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:B.Pitch] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:C.SetSpeed] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2022-10-17T20:27:05.949Z,1666038425.949 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2022-10-17T20:27:06.316Z,1666038426.316 [MissionManager](IMPORTANT): Started mission Default
2022-10-17T20:27:06.316Z,1666038426.316 [Default] Running Loop=1
2022-10-17T20:27:06.317Z,1666038426.317 [Default](DEBUG): Aggregate::initialize Default
2022-10-17T20:27:06.317Z,1666038426.317 [Default:B.GoToSurface] Running Loop=1
2022-10-17T20:27:06.317Z,1666038426.317 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-10-17T20:27:06.317Z,1666038426.317 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-10-17T20:27:06.317Z,1666038426.317 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-10-17T20:27:06.318Z,1666038426.318 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-10-17T20:27:06.318Z,1666038426.318 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-10-17T20:27:06.318Z,1666038426.318 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-10-17T20:27:06.319Z,1666038426.319 [Default:A.Wait] Running Loop=1
2022-10-17T20:27:06.319Z,1666038426.319 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:27:19.654Z,1666038439.654 [Default:A.Wait](INFO): Done Waiting.
2022-10-17T20:27:19.654Z,1666038439.654 [Default:A.Wait] Stopped
2022-10-17T20:27:19.654Z,1666038439.654 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:27:20.069Z,1666038440.069 [Default:CheckIn] Running Loop=1
2022-10-17T20:27:20.069Z,1666038440.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:27:20.069Z,1666038440.069 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:30:04.107Z,1666038604.107 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:30:14.609Z,1666038614.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203010.00,A,3648.17092,N,12147.28133,W,0.350,292.40,171022,,,D*7B
2022-10-17T20:30:14.612Z,1666038614.612 [NAL9602](INFO): GPS fix at 20221017T203010: (36.802849, -121.788022)
2022-10-17T20:30:14.638Z,1666038614.638 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:30:14.638Z,1666038614.638 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:30:30.851Z,1666038630.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0033.lzma
2022-10-17T20:30:31.915Z,1666038631.915 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0033.lzma.bak
2022-10-17T20:30:31.915Z,1666038631.915 [DataOverHttps](INFO): SBD MOMSN=17247289
2022-10-17T20:30:46.931Z,1666038646.931 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+19.0,0000.0,1489.0,000
2022-10-17T20:30:47.338Z,1666038647.338 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:30:56.277Z,1666038656.277 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20221017T193137/Express0034.lzma
2022-10-17T20:30:57.278Z,1666038657.278 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0034.lzma.bak
2022-10-17T20:30:57.278Z,1666038657.278 [DataOverHttps](INFO): SBD MOMSN=17247292
2022-10-17T20:31:04.304Z,1666038664.304 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:31:04.304Z,1666038664.304 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+19.1,0000.0,1489.0,000
2022-10-17T20:31:27.555Z,1666038687.555 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20221017T193137/Express0037.lzma
2022-10-17T20:31:28.558Z,1666038688.558 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0037.lzma.bak
2022-10-17T20:31:28.558Z,1666038688.558 [DataOverHttps](INFO): SBD MOMSN=17247304
2022-10-17T20:31:39.135Z,1666038699.135 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:31:39.135Z,1666038699.135 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:31:39.135Z,1666038699.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:33:05.231Z,1666038785.231 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:33:05.231Z,1666038785.231 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,14
2022-10-17T20:34:51.080Z,1666038891.080 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:34:51.080Z,1666038891.080 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.2,0000.0,1489.WI,-00086,+00118,-00818,+00000,A
2022-10-17T20:36:39.767Z,1666038999.767 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:36:39.767Z,1666038999.767 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:36:39.767Z,1666038999.767 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:36:39.767Z,1666038999.767 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:36:40.174Z,1666039000.174 [Default:CheckIn:D] Stopped
2022-10-17T20:36:40.174Z,1666039000.174 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:36:40.594Z,1666039000.594 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.564296 min 
2022-10-17T20:36:40.594Z,1666039000.594 [Default:CheckIn:E] Stopped
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn] Stopped
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn](INFO): Running loop #2
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn] Running Loop=2
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:36:40.595Z,1666039000.595 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:36:42.589Z,1666039002.589 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203638.00,A,3648.16611,N,12147.28294,W,1.089,334.73,171022,,,D*7E
2022-10-17T20:36:42.591Z,1666039002.591 [NAL9602](INFO): GPS fix at 20221017T203638: (36.802768, -121.788049)
2022-10-17T20:36:42.601Z,1666039002.601 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:36:42.601Z,1666039002.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:36:53.871Z,1666039013.871 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0039.lzma
2022-10-17T20:36:54.874Z,1666039014.874 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0039.lzma.bak
2022-10-17T20:36:54.874Z,1666039014.874 [DataOverHttps](INFO): SBD MOMSN=17247306
2022-10-17T20:37:01.984Z,1666039021.984 [DVL_micro](ERROR): Failed to parse:
:WI,+00002,+00093,-00799,+00000,A
2022-10-17T20:37:13.287Z,1666039033.287 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:37:13.360Z,1666039033.360 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T20:37:19.051Z,1666039039.051 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20221017T193137/Express0040.lzma
2022-10-17T20:37:20.054Z,1666039040.054 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0040.lzma.bak
2022-10-17T20:37:20.054Z,1666039040.054 [DataOverHttps](INFO): SBD MOMSN=17247309
2022-10-17T20:37:34.763Z,1666039054.763 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:37:34.763Z,1666039054.763 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:37:34.763Z,1666039054.763 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:40:00.647Z,1666039200.647 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:40:00.647Z,1666039200.647 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+190,1489.0,000
2022-10-17T20:40:08.329Z,1666039208.329 [DVL_micro](ERROR): Failed to parse:
:SA,-03.74,+11.48,259.3
2022-10-17T20:41:45.317Z,1666039305.317 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:42:16.019Z,1666039336.019 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:42:35.421Z,1666039355.421 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:42:35.421Z,1666039355.421 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:42:35.421Z,1666039355.421 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:42:35.421Z,1666039355.421 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:42:35.829Z,1666039355.829 [Default:CheckIn:D] Stopped
2022-10-17T20:42:35.829Z,1666039355.829 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:42:36.294Z,1666039356.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.491882 min 
2022-10-17T20:42:36.294Z,1666039356.294 [Default:CheckIn:E] Stopped
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn] Stopped
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn](INFO): Running loop #3
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn] Running Loop=3
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:42:36.295Z,1666039356.295 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:42:38.245Z,1666039358.245 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204233.00,A,3648.16915,N,12147.28696,W,0.214,334.73,171022,,,A*79
2022-10-17T20:42:38.247Z,1666039358.247 [NAL9602](INFO): GPS fix at 20221017T204233: (36.802819, -121.788116)
2022-10-17T20:42:38.289Z,1666039358.289 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:42:38.289Z,1666039358.289 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:42:43.697Z,1666039363.697 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0042.lzma
2022-10-17T20:42:44.702Z,1666039364.702 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0042.lzma.bak
2022-10-17T20:42:44.702Z,1666039364.702 [DataOverHttps](INFO): SBD MOMSN=17247312
2022-10-17T20:42:46.429Z,1666039366.429 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:42:46.429Z,1666039366.429 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.5,0000.0000
2022-10-17T20:42:51.696Z,1666039371.696 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T20:42:51.696Z,1666039371.696 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2022-10-17T20:43:18.964Z,1666039398.964 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221017T193137/Express0043.lzma
2022-10-17T20:43:19.966Z,1666039399.966 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0043.lzma.bak
2022-10-17T20:43:19.967Z,1666039399.967 [DataOverHttps](INFO): SBD MOMSN=17247315
2022-10-17T20:43:26.934Z,1666039406.934 [DVL_micro](ERROR): Failed to parse:
:BD,+000,+00000000.00,+00000000.00,9999.99,000.00
2022-10-17T20:43:29.377Z,1666039409.377 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:43:29.377Z,1666039409.377 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:43:29.377Z,1666039409.377 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:43:31.800Z,1666039411.800 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,999.00
2022-10-17T20:43:52.010Z,1666039432.010 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T20:43:52.010Z,1666039432.010 [DVL_micro](ERROR): Failed to parse:
:BI,-00080,+00178,-0000,I
2022-10-17T20:47:10.387Z,1666039630.387 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:47:10.387Z,1666039630.387 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,36,0000.0,1489.0,000
2022-10-17T20:47:40.254Z,1666039660.254 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:48:10.963Z,1666039690.963 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:48:29.954Z,1666039709.954 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:48:29.954Z,1666039709.954 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:48:29.954Z,1666039709.954 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:48:29.955Z,1666039709.955 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:48:30.360Z,1666039710.360 [Default:CheckIn:D] Stopped
2022-10-17T20:48:30.360Z,1666039710.360 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:48:30.758Z,1666039710.758 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.400728 min 
2022-10-17T20:48:30.758Z,1666039710.758 [Default:CheckIn:E] Stopped
2022-10-17T20:48:30.758Z,1666039710.758 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:48:30.759Z,1666039710.759 [Default:CheckIn] Stopped
2022-10-17T20:48:30.759Z,1666039710.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:48:30.759Z,1666039710.759 [Default:CheckIn](INFO): Running loop #4
2022-10-17T20:48:30.759Z,1666039710.759 [Default:CheckIn] Running Loop=4
2022-10-17T20:48:30.759Z,1666039710.759 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:48:30.759Z,1666039710.759 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:48:32.773Z,1666039712.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204828.00,A,3648.15964,N,12147.27565,W,1.652,111.00,171022,,,A*7A
2022-10-17T20:48:32.777Z,1666039712.777 [NAL9602](INFO): GPS fix at 20221017T204828: (36.802661, -121.787927)
2022-10-17T20:48:32.787Z,1666039712.787 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:48:32.787Z,1666039712.787 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:48:38.440Z,1666039718.440 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,+9999.99,+9999.99
2022-10-17T20:48:44.647Z,1666039724.647 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221017T193137/Courier0045.lzma
2022-10-17T20:48:45.650Z,1666039725.650 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0045.lzma.bak
2022-10-17T20:48:45.650Z,1666039725.650 [DataOverHttps](INFO): SBD MOMSN=17247326
2022-10-17T20:48:53.399Z,1666039733.399 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 76
2022-10-17T20:48:53.403Z,1666039733.403 [DVL_micro](ERROR): Failed to parse:
:BD,+0000000000000.00,+00000000.00,9999.99,000.00
2022-10-17T20:48:56.214Z,1666039736.214 [DVL_micro](ERROR): only read 2 of 4 data items
2022-10-17T20:48:56.214Z,1666039736.214 [DVL_micro](ERROR): Failed to parse:
:BI,-0008,-00712,
2022-10-17T20:49:01.049Z,1666039741.049 [DVL_micro](ERROR): Failed to parse:
:000000000,.7,0000.0,1489.0,000
2022-10-17T20:49:03.493Z,1666039743.493 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:49:03.568Z,1666039743.568 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T20:49:08.765Z,1666039748.765 [DVL_micro](ERROR): Failed to parse:
:R99,+9999.99,+9999.99,+9999.99
2022-10-17T20:49:14.208Z,1666039754.208 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221017T193137/Express0046.lzma
2022-10-17T20:49:15.210Z,1666039755.210 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0046.lzma.bak
2022-10-17T20:49:15.210Z,1666039755.210 [DataOverHttps](INFO): SBD MOMSN=17247329
2022-10-17T20:49:16.013Z,1666039756.013 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:49:16.014Z,1666039756.014 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+1.0,1489.0,000
2022-10-17T20:49:21.253Z,1666039761.253 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:49:21.253Z,1666039761.253 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.7,0000.0,1489.0,,-00172,+00009,-00731,+00000,A
2022-10-17T20:49:24.108Z,1666039764.108 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:49:24.108Z,1666039764.108 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:49:24.108Z,1666039764.108 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:53:35.006Z,1666040015.006 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T20:54:05.707Z,1666040045.707 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T20:54:24.726Z,1666040064.726 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T20:54:24.726Z,1666040064.726 [Default:CheckIn:C.Wait] Stopped
2022-10-17T20:54:24.726Z,1666040064.726 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T20:54:24.726Z,1666040064.726 [Default:CheckIn:D] Running Loop=1
2022-10-17T20:54:25.104Z,1666040065.104 [Default:CheckIn:D] Stopped
2022-10-17T20:54:25.104Z,1666040065.104 [Default:CheckIn:E] Running Loop=1
2022-10-17T20:54:25.515Z,1666040065.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.313121 min 
2022-10-17T20:54:25.515Z,1666040065.515 [Default:CheckIn:E] Stopped
2022-10-17T20:54:25.515Z,1666040065.515 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T20:54:25.515Z,1666040065.515 [Default:CheckIn] Stopped
2022-10-17T20:54:25.516Z,1666040065.516 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T20:54:25.516Z,1666040065.516 [Default:CheckIn](INFO): Running loop #5
2022-10-17T20:54:25.516Z,1666040065.516 [Default:CheckIn] Running Loop=5
2022-10-17T20:54:25.516Z,1666040065.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T20:54:25.516Z,1666040065.516 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T20:54:27.528Z,1666040067.528 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205423.00,A,3648.16903,N,12147.28405,W,0.194,261.65,171022,,,A*7D
2022-10-17T20:54:27.530Z,1666040067.530 [NAL9602](INFO): GPS fix at 20221017T205423: (36.802817, -121.788067)
2022-10-17T20:54:27.541Z,1666040067.541 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T20:54:27.541Z,1666040067.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T20:54:39.468Z,1666040079.468 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0048.lzma
2022-10-17T20:54:40.470Z,1666040080.470 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0048.lzma.bak
2022-10-17T20:54:40.470Z,1666040080.470 [DataOverHttps](INFO): SBD MOMSN=17247333
2022-10-17T20:54:52.202Z,1666040092.202 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:54:52.202Z,1666040092.202 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.8,000.0,000
2022-10-17T20:54:57.087Z,1666040097.087 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T20:54:57.087Z,1666040097.087 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.81489.0,000
2022-10-17T20:54:58.238Z,1666040098.238 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T20:54:58.312Z,1666040098.312 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T20:55:09.276Z,1666040109.276 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20221017T193137/Express0049.lzma
2022-10-17T20:55:10.278Z,1666040110.278 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0049.lzma.bak
2022-10-17T20:55:10.278Z,1666040110.278 [DataOverHttps](INFO): SBD MOMSN=17247336
2022-10-17T20:55:23.296Z,1666040123.296 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T20:55:23.296Z,1666040123.296 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T20:55:23.296Z,1666040123.296 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T20:59:30.156Z,1666040370.156 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:00:00.863Z,1666040400.863 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:00:23.896Z,1666040423.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:00:23.896Z,1666040423.896 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:00:23.896Z,1666040423.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:00:23.896Z,1666040423.897 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:00:24.302Z,1666040424.302 [Default:CheckIn:D] Stopped
2022-10-17T21:00:24.302Z,1666040424.302 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:00:24.712Z,1666040424.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.299760 min 
2022-10-17T21:00:24.712Z,1666040424.712 [Default:CheckIn:E] Stopped
2022-10-17T21:00:24.712Z,1666040424.712 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:00:24.713Z,1666040424.713 [Default:CheckIn] Stopped
2022-10-17T21:00:24.713Z,1666040424.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:00:24.713Z,1666040424.713 [Default:CheckIn](INFO): Running loop #6
2022-10-17T21:00:24.713Z,1666040424.713 [Default:CheckIn] Running Loop=6
2022-10-17T21:00:24.713Z,1666040424.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:00:24.713Z,1666040424.713 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:00:26.719Z,1666040426.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210022.00,A,3648.16877,N,12147.29007,W,0.369,155.80,171022,,,A*76
2022-10-17T21:00:26.722Z,1666040426.722 [NAL9602](INFO): GPS fix at 20221017T210022: (36.802813, -121.788168)
2022-10-17T21:00:26.750Z,1666040426.750 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:00:26.750Z,1666040426.750 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:00:37.743Z,1666040437.743 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221017T193137/Courier0051.lzma
2022-10-17T21:00:38.746Z,1666040438.746 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0051.lzma.bak
2022-10-17T21:00:38.746Z,1666040438.746 [DataOverHttps](INFO): SBD MOMSN=17247340
2022-10-17T21:00:47.870Z,1666040447.870 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:00:47.871Z,1666040447.871 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,39,0000.0,1489.0,000
2022-10-17T21:00:57.160Z,1666040457.160 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:00:57.240Z,1666040457.240 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:01:05.504Z,1666040465.504 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221017T193137/Express0052.lzma
2022-10-17T21:01:06.506Z,1666040466.506 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0052.lzma.bak
2022-10-17T21:01:06.506Z,1666040466.506 [DataOverHttps](INFO): SBD MOMSN=17247343
2022-10-17T21:01:12.942Z,1666040472.942 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:01:12.942Z,1666040472.942 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.9,009.0,000
2022-10-17T21:01:19.013Z,1666040479.013 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:01:19.013Z,1666040479.013 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:01:19.013Z,1666040479.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:01:23.032Z,1666040483.032 [DVL_micro](ERROR): Failed to parse:
:000000000,35.0,+19.9,0000.0,1489.0,000
2022-10-17T21:01:52.985Z,1666040512.985 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:01:52.985Z,1666040512.985 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.9,0000.0,1489.0I,-00101,+00140,-00698,+00000,A
2022-10-17T21:05:29.176Z,1666040729.176 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:05:59.906Z,1666040759.906 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:06:19.714Z,1666040779.714 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:06:19.714Z,1666040779.714 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:06:19.714Z,1666040779.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:06:19.714Z,1666040779.714 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:06:20.122Z,1666040780.122 [Default:CheckIn:D] Stopped
2022-10-17T21:06:20.122Z,1666040780.122 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:06:20.522Z,1666040780.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.230086 min 
2022-10-17T21:06:20.522Z,1666040780.522 [Default:CheckIn:E] Stopped
2022-10-17T21:06:20.522Z,1666040780.522 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:06:20.522Z,1666040780.522 [Default:CheckIn] Stopped
2022-10-17T21:06:20.522Z,1666040780.522 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:06:20.523Z,1666040780.523 [Default:CheckIn](INFO): Running loop #7
2022-10-17T21:06:20.523Z,1666040780.523 [Default:CheckIn] Running Loop=7
2022-10-17T21:06:20.523Z,1666040780.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:06:20.523Z,1666040780.523 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:06:22.541Z,1666040782.541 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210618.00,A,3648.16871,N,12147.28427,W,0.486,320.87,171022,,,A*79
2022-10-17T21:06:22.543Z,1666040782.543 [NAL9602](INFO): GPS fix at 20221017T210618: (36.802812, -121.788071)
2022-10-17T21:06:22.553Z,1666040782.553 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:06:22.553Z,1666040782.553 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:06:36.775Z,1666040796.775 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0054.lzma
2022-10-17T21:06:37.778Z,1666040797.778 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0054.lzma.bak
2022-10-17T21:06:37.778Z,1666040797.778 [DataOverHttps](INFO): SBD MOMSN=17247347
2022-10-17T21:06:53.255Z,1666040813.255 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:06:53.336Z,1666040813.336 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:07:03.776Z,1666040823.776 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20221017T193137/Express0055.lzma
2022-10-17T21:07:04.778Z,1666040824.778 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0055.lzma.bak
2022-10-17T21:07:04.778Z,1666040824.778 [DataOverHttps](INFO): SBD MOMSN=17247351
2022-10-17T21:07:13.882Z,1666040833.882 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:07:13.882Z,1666040833.882 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:07:13.882Z,1666040833.882 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:10:03.175Z,1666041003.175 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T21:10:03.175Z,1666041003.175 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+99999.99
2022-10-17T21:11:25.176Z,1666041085.176 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:11:55.924Z,1666041115.924 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:12:14.533Z,1666041134.533 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:12:14.533Z,1666041134.533 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:12:14.533Z,1666041134.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:12:14.534Z,1666041134.534 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:12:14.925Z,1666041134.925 [Default:CheckIn:D] Stopped
2022-10-17T21:12:14.925Z,1666041134.925 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:12:15.342Z,1666041135.342 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.143477 min 
2022-10-17T21:12:15.342Z,1666041135.342 [Default:CheckIn:E] Stopped
2022-10-17T21:12:15.342Z,1666041135.342 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:12:15.342Z,1666041135.342 [Default:CheckIn] Stopped
2022-10-17T21:12:15.343Z,1666041135.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:12:15.343Z,1666041135.343 [Default:CheckIn](INFO): Running loop #8
2022-10-17T21:12:15.343Z,1666041135.343 [Default:CheckIn] Running Loop=8
2022-10-17T21:12:15.343Z,1666041135.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:12:15.343Z,1666041135.343 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:12:17.345Z,1666041137.345 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211212.00,A,3648.16349,N,12147.27710,W,0.389,191.24,171022,,,A*77
2022-10-17T21:12:17.347Z,1666041137.347 [NAL9602](INFO): GPS fix at 20221017T211212: (36.802725, -121.787952)
2022-10-17T21:12:17.356Z,1666041137.356 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:12:17.356Z,1666041137.356 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:12:28.563Z,1666041148.563 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20221017T193137/Courier0057.lzma
2022-10-17T21:12:29.566Z,1666041149.566 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0057.lzma.bak
2022-10-17T21:12:29.566Z,1666041149.566 [DataOverHttps](INFO): SBD MOMSN=17247362
2022-10-17T21:12:32.791Z,1666041152.791 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T21:12:32.791Z,1666041152.791 [DVL_micro](ERROR): Failed to parse:
:BI,00042,-00627,+00000,I
2022-10-17T21:12:48.138Z,1666041168.138 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:12:48.216Z,1666041168.216 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:13:00.776Z,1666041180.776 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20221017T193137/Express0058.lzma
2022-10-17T21:13:01.778Z,1666041181.778 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0058.lzma.bak
2022-10-17T21:13:01.778Z,1666041181.778 [DataOverHttps](INFO): SBD MOMSN=17247365
2022-10-17T21:13:11.171Z,1666041191.171 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:13:11.176Z,1666041191.176 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:13:11.176Z,1666041191.176 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:16:12.616Z,1666041372.616 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 4
2022-10-17T21:16:12.618Z,1666041372.618 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2022-10-17T21:16:25.138Z,1666041385.138 [DVL_micro](ERROR): only read 2 of 4 data items
2022-10-17T21:16:25.138Z,1666041385.138 [DVL_micro](ERROR): Failed to parse:
:BI,-00026,+02773+00000,I
2022-10-17T21:17:20.076Z,1666041440.076 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:17:24.935Z,1666041444.935 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T21:17:24.935Z,1666041444.935 [DVL_micro](ERROR): Failed to parse:
:BI,+01553,-0924,+00000,I
2022-10-17T21:17:50.823Z,1666041470.823 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:18:11.851Z,1666041491.851 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:18:11.852Z,1666041491.852 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:18:11.852Z,1666041491.852 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:18:11.852Z,1666041491.852 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:18:12.274Z,1666041492.274 [Default:CheckIn:D] Stopped
2022-10-17T21:18:12.274Z,1666041492.274 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:18:12.649Z,1666041492.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.099292 min 
2022-10-17T21:18:12.649Z,1666041492.649 [Default:CheckIn:E] Stopped
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn] Stopped
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn](INFO): Running loop #9
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn] Running Loop=9
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:18:12.650Z,1666041492.650 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:18:14.673Z,1666041494.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211810.00,A,3648.16958,N,12147.28446,W,1.244,166.86,171022,,,A*7B
2022-10-17T21:18:14.675Z,1666041494.675 [NAL9602](INFO): GPS fix at 20221017T211810: (36.802826, -121.788074)
2022-10-17T21:18:14.684Z,1666041494.684 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:18:14.684Z,1666041494.684 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:18:25.967Z,1666041505.967 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0060.lzma
2022-10-17T21:18:26.970Z,1666041506.970 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0060.lzma.bak
2022-10-17T21:18:26.970Z,1666041506.970 [DataOverHttps](INFO): SBD MOMSN=17247378
2022-10-17T21:18:29.614Z,1666041509.614 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:18:29.614Z,1666041509.614 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,.2,0000.0,1489.0,000
2022-10-17T21:18:45.365Z,1666041525.365 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:18:45.448Z,1666041525.448 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:18:54.670Z,1666041534.670 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T21:18:54.670Z,1666041534.670 [DVL_micro](ERROR): Failed to parse:
:BI,+01127,-0015,+00000,I
2022-10-17T21:18:56.525Z,1666041536.525 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20221017T193137/Express0061.lzma
2022-10-17T21:18:57.485Z,1666041537.485 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-10-17T21:18:57.486Z,1666041537.486 [DropWeight] Hardware Fault, FailCount= 1
2022-10-17T21:18:57.486Z,1666041537.486 [DropWeight](ERROR): Hardware Fault
2022-10-17T21:18:57.552Z,1666041537.552 [CommandExec](FAULT): Scheduling is paused
2022-10-17T21:18:57.553Z,1666041537.553 [CBIT](INFO): Critical error at 20221017T211857
2022-10-17T21:18:57.555Z,1666041537.555 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-10-17T21:18:57.555Z,1666041537.555 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-10-17T21:18:57.857Z,1666041537.857 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0061.lzma.bak
2022-10-17T21:18:57.858Z,1666041537.858 [DataOverHttps](INFO): SBD MOMSN=17247381
2022-10-17T21:18:57.922Z,1666041537.922 [CBIT](INFO): Critical error at 20221017T211857
2022-10-17T21:19:07.604Z,1666041547.604 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:19:07.604Z,1666041547.604 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:19:07.605Z,1666041547.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:23:17.332Z,1666041797.332 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:23:48.035Z,1666041828.035 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:24:08.254Z,1666041848.254 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:24:08.254Z,1666041848.254 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:24:08.254Z,1666041848.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:24:08.255Z,1666041848.255 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:24:08.649Z,1666041848.649 [Default:CheckIn:D] Stopped
2022-10-17T21:24:08.650Z,1666041848.650 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:24:09.058Z,1666041849.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.038883 min 
2022-10-17T21:24:09.058Z,1666041849.058 [Default:CheckIn:E] Stopped
2022-10-17T21:24:09.058Z,1666041849.058 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:24:09.058Z,1666041849.058 [Default:CheckIn] Stopped
2022-10-17T21:24:09.058Z,1666041849.058 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:24:09.059Z,1666041849.059 [Default:CheckIn](INFO): Running loop #10
2022-10-17T21:24:09.059Z,1666041849.059 [Default:CheckIn] Running Loop=10
2022-10-17T21:24:09.059Z,1666041849.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:24:09.059Z,1666041849.059 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:24:11.075Z,1666041851.075 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212406.00,A,3648.16732,N,12147.28044,W,0.816,275.77,171022,,,A*74
2022-10-17T21:24:11.079Z,1666041851.079 [NAL9602](INFO): GPS fix at 20221017T212406: (36.802789, -121.788007)
2022-10-17T21:24:11.088Z,1666041851.088 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:24:11.088Z,1666041851.088 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:24:22.835Z,1666041862.835 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20221017T193137/Courier0063.lzma
2022-10-17T21:24:23.838Z,1666041863.838 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0063.lzma.bak
2022-10-17T21:24:23.838Z,1666041863.838 [DataOverHttps](INFO): SBD MOMSN=17247392
2022-10-17T21:24:29.686Z,1666041869.686 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T21:24:29.687Z,1666041869.687 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.999,+9999.99
2022-10-17T21:24:34.533Z,1666041874.533 [DVL_micro](ERROR): only read 3 of 4 data items
2022-10-17T21:24:34.533Z,1666041874.533 [DVL_micro](ERROR): Failed to parse:
:BI,-00126,+00485,-0000,I
2022-10-17T21:24:41.767Z,1666041881.767 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:24:41.840Z,1666041881.840 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:24:53.830Z,1666041893.830 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20221017T193137/Express0064.lzma
2022-10-17T21:24:54.558Z,1666041894.558 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0064.lzma.bak
2022-10-17T21:24:54.558Z,1666041894.558 [DataOverHttps](INFO): SBD MOMSN=17247397
2022-10-17T21:24:59.551Z,1666041899.551 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:24:59.551Z,1666041899.551 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.1,0000.0,1489:WI,-00053,-01827,-00340,+00000,A
2022-10-17T21:25:04.456Z,1666041904.456 [DVL_micro](ERROR): Failed to parse:
:.99,+9999.99,+9999.99,+9999.99
2022-10-17T21:25:07.340Z,1666041907.340 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:25:07.341Z,1666041907.341 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:25:07.341Z,1666041907.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:25:09.406Z,1666041909.406 [DVL_micro](ERROR): only read 0 of 4 data items
2022-10-17T21:25:09.406Z,1666041909.406 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999.99,+9999.99,+9999.99
2022-10-17T21:29:12.975Z,1666042152.975 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:29:43.727Z,1666042183.727 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:30:08.029Z,1666042208.029 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:30:08.029Z,1666042208.029 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:30:08.030Z,1666042208.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:30:08.030Z,1666042208.030 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:30:08.396Z,1666042208.396 [Default:CheckIn:D] Stopped
2022-10-17T21:30:08.396Z,1666042208.396 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:30:08.807Z,1666042208.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.034660 min 
2022-10-17T21:30:08.807Z,1666042208.807 [Default:CheckIn:E] Stopped
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn] Stopped
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn](INFO): Running loop #11
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn] Running Loop=11
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:30:08.812Z,1666042208.812 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:30:10.797Z,1666042210.797 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213006.00,A,3648.16721,N,12147.28175,W,1.205,110.71,171022,,,A*7F
2022-10-17T21:30:10.799Z,1666042210.799 [NAL9602](INFO): GPS fix at 20221017T213006: (36.802787, -121.788029)
2022-10-17T21:30:10.809Z,1666042210.809 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:30:10.809Z,1666042210.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:30:22.103Z,1666042222.103 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0066.lzma
2022-10-17T21:30:23.106Z,1666042223.106 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0066.lzma.bak
2022-10-17T21:30:23.106Z,1666042223.106 [DataOverHttps](INFO): SBD MOMSN=17247426
2022-10-17T21:30:26.979Z,1666042226.979 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:30:26.979Z,1666042226.979 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000+20.1,0000.0,1489.0,000
2022-10-17T21:30:41.525Z,1666042241.525 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:30:41.604Z,1666042241.604 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:30:47.936Z,1666042247.936 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20221017T193137/Express0067.lzma
2022-10-17T21:30:48.938Z,1666042248.938 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0067.lzma.bak
2022-10-17T21:30:48.938Z,1666042248.938 [DataOverHttps](INFO): SBD MOMSN=17247429
2022-10-17T21:31:03.791Z,1666042263.791 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:31:03.791Z,1666042263.791 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:31:03.791Z,1666042263.791 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:31:32.434Z,1666042292.434 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:31:32.434Z,1666042292.434 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.1,00000,000
2022-10-17T21:33:57.509Z,1666042437.509 [CBIT](INFO): Clearing failed state for component DropWeight
2022-10-17T21:33:57.509Z,1666042437.509 [DropWeight] No Fault, FailCount= 1
2022-10-17T21:34:27.771Z,1666042467.771 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:34:27.776Z,1666042467.776 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,.0,0000.0,1489.0,000
2022-10-17T21:35:13.022Z,1666042513.022 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:35:43.733Z,1666042543.733 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:36:04.325Z,1666042564.325 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:36:04.325Z,1666042564.325 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:36:04.325Z,1666042564.325 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:36:04.326Z,1666042564.326 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:36:04.735Z,1666042564.735 [Default:CheckIn:D] Stopped
2022-10-17T21:36:04.735Z,1666042564.735 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.973641 min 
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn:E] Stopped
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn] Stopped
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn](INFO): Running loop #12
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn] Running Loop=12
2022-10-17T21:36:05.137Z,1666042565.137 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:36:05.138Z,1666042565.138 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:36:07.161Z,1666042567.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213602.00,A,3648.16607,N,12147.28002,W,2.877,331.05,171022,,,A*77
2022-10-17T21:36:07.163Z,1666042567.163 [NAL9602](INFO): GPS fix at 20221017T213602: (36.802768, -121.788000)
2022-10-17T21:36:07.172Z,1666042567.172 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:36:07.172Z,1666042567.172 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-10-17T21:36:18.143Z,1666042578.143 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221017T193137/Courier0069.lzma
2022-10-17T21:36:19.146Z,1666042579.146 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Courier0069.lzma.bak
2022-10-17T21:36:19.146Z,1666042579.146 [DataOverHttps](INFO): SBD MOMSN=17247433
2022-10-17T21:36:30.996Z,1666042590.996 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:36:30.996Z,1666042590.996 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.0,0000.0,148

2022-10-17T21:36:37.848Z,1666042597.848 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:36:37.932Z,1666042597.932 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:36:43.495Z,1666042603.495 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221017T193137/Express0070.lzma
2022-10-17T21:36:44.498Z,1666042604.498 [DataOverHttps](INFO): Moved sent file to Logs/20221017T193137/Express0070.lzma.bak
2022-10-17T21:36:44.498Z,1666042604.498 [DataOverHttps](INFO): SBD MOMSN=17247436
2022-10-17T21:36:59.700Z,1666042619.700 [Default:CheckIn:Read_Iridium] Stopped
2022-10-17T21:36:59.700Z,1666042619.700 [Default:CheckIn:C.Wait] Running Loop=1
2022-10-17T21:36:59.700Z,1666042619.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-10-17T21:39:03.698Z,1666042743.698 [DVL_micro](ERROR): Failed to parse:
:SA,-03.59,+00.78,141.5
2022-10-17T21:39:26.325Z,1666042766.325 [DVL_micro](ERROR): Failed to parse:
:WI,+00009,-0696,+00000,A
2022-10-17T21:39:28.747Z,1666042768.747 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2022-10-17T21:39:28.747Z,1666042768.747 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+190,1489.0,000
2022-10-17T21:39:31.163Z,1666042771.163 [DVL_micro](ERROR): No DVL communication! Re-initializing
2022-10-17T21:39:31.163Z,1666042771.163 [DVL_micro] Communications Fault, FailCount= 1
2022-10-17T21:39:31.163Z,1666042771.163 [DVL_micro](ERROR): Communications Fault
2022-10-17T21:39:31.221Z,1666042771.221 [CBIT](ERROR): Communications Fault in component: DVL_micro
2022-10-17T21:39:31.644Z,1666042771.644 [DVL_micro](INFO): Powering down
2022-10-17T21:39:32.401Z,1666042772.401 [CBIT](INFO): Clearing failed state for component DVL_micro
2022-10-17T21:39:32.401Z,1666042772.401 [DVL_micro] No Fault, FailCount= 1
2022-10-17T21:41:08.949Z,1666042868.949 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-10-17T21:41:39.652Z,1666042899.652 [NAL9602](INFO): Not Powering down - fast GPS
2022-10-17T21:42:00.267Z,1666042920.267 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-10-17T21:42:00.267Z,1666042920.267 [Default:CheckIn:C.Wait] Stopped
2022-10-17T21:42:00.267Z,1666042920.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-10-17T21:42:00.269Z,1666042920.269 [Default:CheckIn:D] Running Loop=1
2022-10-17T21:42:00.691Z,1666042920.691 [Default:CheckIn:D] Stopped
2022-10-17T21:42:00.691Z,1666042920.691 [Default:CheckIn:E] Running Loop=1
2022-10-17T21:42:01.090Z,1666042921.090 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.906242 min 
2022-10-17T21:42:01.090Z,1666042921.090 [Default:CheckIn:E] Stopped
2022-10-17T21:42:01.090Z,1666042921.090 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-10-17T21:42:01.090Z,1666042921.090 [Default:CheckIn] Stopped
2022-10-17T21:42:01.090Z,1666042921.090 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:42:01.091Z,1666042921.091 [Default:CheckIn](INFO): Running loop #13
2022-10-17T21:42:01.091Z,1666042921.091 [Default:CheckIn] Running Loop=13
2022-10-17T21:42:01.091Z,1666042921.091 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-10-17T21:42:01.091Z,1666042921.091 [Default:CheckIn:Read_GPS] Running Loop=1
2022-10-17T21:42:33.806Z,1666042953.806 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-10-17T21:42:33.880Z,1666042953.880 [NAL9602](ERROR): received: 
+CSQ:0

OK339, 2, 0, 0, 0

OK
2022-10-17T21:45:30.608Z,1666043130.608 [CommandExec](IMPORTANT): got command quit
2022-10-17T21:45:31.612Z,1666043131.612 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:31.612Z,1666043131.612 [CommandExec](INFO): Uninitializing the command executive.
2022-10-17T21:45:31.612Z,1666043131.612 [CommandExec](INFO): Uninitializing the command scheduler.
2022-10-17T21:45:31.612Z,1666043131.612 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:31.616Z,1666043131.616 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-10-17T21:45:31.616Z,1666043131.616 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-10-17T21:45:31.616Z,1666043131.616 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:31.617Z,1666043131.617 [NavChartDb](INFO): Join timeout helper Thread ID is 6734
2022-10-17T21:45:31.740Z,1666043131.740 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:31.740Z,1666043131.740 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:31.760Z,1666043131.760 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-10-17T21:45:31.760Z,1666043131.760 [DAT ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:31.761Z,1666043131.761 [DAT](INFO): Join timeout helper Thread ID is 6735
2022-10-17T21:45:31.761Z,1666043131.761 [DAT](INFO): Powering down
2022-10-17T21:45:31.832Z,1666043131.832 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:31.833Z,1666043131.833 [DAT](INFO): Powering down
2022-10-17T21:45:31.833Z,1666043131.833 [DAT ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:31.840Z,1666043131.840 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-10-17T21:45:31.840Z,1666043131.840 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:31.840Z,1666043131.840 [Radio_Surface](INFO): Join timeout helper Thread ID is 6736
2022-10-17T21:45:32.184Z,1666043132.184 [Radio_Surface](INFO): Powering down
2022-10-17T21:45:32.185Z,1666043132.185 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:32.185Z,1666043132.185 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:32.188Z,1666043132.188 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-10-17T21:45:32.188Z,1666043132.188 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:32.188Z,1666043132.188 [Onboard](INFO): Join timeout helper Thread ID is 6737
2022-10-17T21:45:33.721Z,1666043133.721 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:33.722Z,1666043133.722 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:33.732Z,1666043133.732 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-10-17T21:45:33.732Z,1666043133.732 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:33.732Z,1666043133.732 [DataOverHttps](INFO): Join timeout helper Thread ID is 6738
2022-10-17T21:45:34.388Z,1666043134.388 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:34.391Z,1666043134.391 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:34.397Z,1666043134.397 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-10-17T21:45:34.397Z,1666043134.397 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:34.397Z,1666043134.397 [BackseatComponent](INFO): Join timeout helper Thread ID is 6739
2022-10-17T21:45:34.472Z,1666043134.472 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:34.472Z,1666043134.472 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:34.485Z,1666043134.485 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-10-17T21:45:34.485Z,1666043134.485 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:34.485Z,1666043134.485 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6740
2022-10-17T21:45:34.568Z,1666043134.568 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:34.568Z,1666043134.568 [WetLabsBB2FL](INFO): Powering down
2022-10-17T21:45:34.569Z,1666043134.569 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:34.589Z,1666043134.589 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-10-17T21:45:34.589Z,1666043134.589 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:34.589Z,1666043134.589 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6741
2022-10-17T21:45:35.408Z,1666043135.408 [CTD_Seabird](INFO): Powering down
2022-10-17T21:45:35.422Z,1666043135.422 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:35.431Z,1666043135.431 [CTD_Seabird](INFO): Powering down
2022-10-17T21:45:35.444Z,1666043135.444 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.461Z,1666043135.461 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-10-17T21:45:35.461Z,1666043135.461 [logger ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.461Z,1666043135.461 [logger](INFO): Join timeout helper Thread ID is 6742
2022-10-17T21:45:35.468Z,1666043135.468 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:35.468Z,1666043135.468 [logger ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.481Z,1666043135.481 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-10-17T21:45:35.481Z,1666043135.481 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.481Z,1666043135.481 [CommandLine](INFO): Join timeout helper Thread ID is 6743
2022-10-17T21:45:35.540Z,1666043135.540 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:35.540Z,1666043135.540 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.561Z,1666043135.561 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-10-17T21:45:35.561Z,1666043135.561 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.561Z,1666043135.561 [CommandExec](INFO): Join timeout helper Thread ID is 6744
2022-10-17T21:45:35.562Z,1666043135.562 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-10-17T21:45:35.562Z,1666043135.562 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.563Z,1666043135.563 [controlThread](INFO): Join timeout helper Thread ID is 6745
2022-10-17T21:45:35.588Z,1666043135.588 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-10-17T21:45:35.588Z,1666043135.588 [controlThread](DEBUG): Uninitializing ControlThread
2022-10-17T21:45:35.591Z,1666043135.591 [AHRS_M2](INFO): Powering down
2022-10-17T21:45:35.732Z,1666043135.732 [DVL_micro](INFO): Powering down
2022-10-17T21:45:35.733Z,1666043135.733 [NAL9602](INFO): Powering down
2022-10-17T21:45:35.734Z,1666043135.734 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-10-17T21:45:35.735Z,1666043135.735 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-10-17T21:45:35.736Z,1666043135.736 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-10-17T21:45:35.736Z,1666043135.736 [MissionManager](INFO): Uninitializing Mission Default
2022-10-17T21:45:35.737Z,1666043135.737 [Default] Stopped
2022-10-17T21:45:35.737Z,1666043135.737 [Default](DEBUG): Aggregate::uninitialize Default
2022-10-17T21:45:35.737Z,1666043135.737 [Default:B.GoToSurface] Stopped
2022-10-17T21:45:35.737Z,1666043135.737 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-10-17T21:45:35.737Z,1666043135.737 [Default:CheckIn] Stopped
2022-10-17T21:45:35.737Z,1666043135.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-10-17T21:45:35.737Z,1666043135.737 [Default:CheckIn:Read_GPS] Stopped
2022-10-17T21:45:35.740Z,1666043135.740 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-10-17T21:45:35.740Z,1666043135.740 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-10-17T21:45:35.741Z,1666043135.741 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-10-17T21:45:35.741Z,1666043135.741 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-10-17T21:45:35.741Z,1666043135.741 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-10-17T21:45:35.741Z,1666043135.741 [BuoyancyServo](INFO): Powering down
2022-10-17T21:45:35.756Z,1666043135.756 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-10-17T21:45:35.756Z,1666043135.756 [ElevatorServo](INFO): Powering down
2022-10-17T21:45:35.757Z,1666043135.757 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-10-17T21:45:35.757Z,1666043135.757 [MassServo](INFO): Powering down
2022-10-17T21:45:35.758Z,1666043135.758 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-10-17T21:45:35.758Z,1666043135.758 [RudderServo](INFO): Powering down
2022-10-17T21:45:35.758Z,1666043135.759 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-10-17T21:45:35.759Z,1666043135.759 [ThrusterHE](INFO): Powering down
2022-10-17T21:45:35.759Z,1666043135.759 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-10-17T21:45:35.760Z,1666043135.760 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-10-17T21:45:35.760Z,1666043135.760 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-10-17T21:45:35.760Z,1666043135.760 [CBIT](DEBUG): Powering off loads.
2022-10-17T21:45:35.772Z,1666043135.772 [CBIT](DEBUG): Disabling WDT.
2022-10-17T21:45:35.784Z,1666043135.784 [CBIT](DEBUG): Opening all GF detection circuits.
2022-10-17T21:45:35.784Z,1666043135.784 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.830Z,1666043135.830 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.835Z,1666043135.835 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.864Z,1666043135.864 [DAT ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.868Z,1666043135.868 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.870Z,1666043135.870 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.877Z,1666043135.877 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.884Z,1666043135.884 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.931Z,1666043135.931 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-10-17T21:45:35.997Z,1666043135.997 [logger ThreadHandler](INFO): Thread cancelled.