2025-03-14T17:35:54.261Z,1741973754.261 [CommandExec](IMPORTANT): got command restart logs 2025-03-14T17:36:28.811Z,1741973788.811 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T17:36:28.811Z,1741973788.811 [Default:CheckIn:C.Wait] Stopped 2025-03-14T17:36:28.811Z,1741973788.811 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T17:36:28.812Z,1741973788.812 [Default:CheckIn:D] Running Loop=1 2025-03-14T17:36:29.239Z,1741973789.239 [Default:CheckIn:D] Stopped 2025-03-14T17:36:29.239Z,1741973789.239 [Default:CheckIn:E] Running Loop=1 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.274193 min 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn:E] Stopped 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn] Stopped 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn](INFO): Running loop #3 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn] Running Loop=3 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T17:36:29.619Z,1741973789.619 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T17:36:31.634Z,1741973791.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173625.00,A,3648.96963,N,12148.25674,W,3.460,129.14,140325,,,A*71 2025-03-14T17:36:31.636Z,1741973791.636 [NAL9602](INFO): GPS fix at 20250314T173625: (36.816161, -121.804279) 2025-03-14T17:36:31.648Z,1741973791.648 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T17:36:31.648Z,1741973791.648 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T17:36:40.200Z,1741973800.200 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20250314T133305/Courier0048.lzma 2025-03-14T17:36:41.194Z,1741973801.194 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0048.lzma.bak 2025-03-14T17:36:41.194Z,1741973801.194 [DataOverHttps](INFO): SBD MOMSN=24229291 2025-03-14T17:36:58.556Z,1741973818.556 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20250314T173554/Courier0000.lzma 2025-03-14T17:36:59.134Z,1741973819.134 [NAL9602](INFO): SBD MO Status=2, MOMSN=19197, MT Status=2, MTMSN=0 2025-03-14T17:36:59.134Z,1741973819.134 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T17:36:59.558Z,1741973819.558 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0000.lzma.bak 2025-03-14T17:36:59.558Z,1741973819.558 [DataOverHttps](INFO): SBD MOMSN=24229293 2025-03-14T17:37:12.867Z,1741973832.867 [NAL9602](INFO): SBD MO Status=0, MOMSN=19197, MT Status=0, MTMSN=0 2025-03-14T17:37:12.890Z,1741973832.890 [NAL9602](INFO): No messages in MT queue 2025-03-14T17:37:17.228Z,1741973837.228 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250314T133305/Express0049.lzma 2025-03-14T17:37:18.230Z,1741973838.230 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0049.lzma.bak 2025-03-14T17:37:18.230Z,1741973838.230 [DataOverHttps](INFO): SBD MOMSN=24229297 2025-03-14T17:37:34.597Z,1741973854.597 [DataOverHttps](INFO): Sending 453 bytes from file Logs/20250314T173554/Express0001.lzma 2025-03-14T17:37:35.598Z,1741973855.598 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0001.lzma.bak 2025-03-14T17:37:35.598Z,1741973855.598 [DataOverHttps](INFO): SBD MOMSN=24229301 2025-03-14T17:37:36.697Z,1741973856.697 [DVL_micro](ERROR): Failed to parse: :RD,+0021.33,+0017.58,+9999.99,+9999.99 2025-03-14T17:37:37.146Z,1741973857.146 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T17:37:37.147Z,1741973857.147 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T17:37:37.147Z,1741973857.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T17:37:43.584Z,1741973863.584 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T17:40:04.555Z,1741974004.555 [DVL_micro](ERROR): Failed to parse: :RD,+0017.93,+0016.62,+0021.43,+0023.08 2025-03-14T17:42:37.740Z,1741974157.740 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T17:42:37.740Z,1741974157.740 [Default:CheckIn:C.Wait] Stopped 2025-03-14T17:42:37.740Z,1741974157.740 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T17:42:37.741Z,1741974157.741 [Default:CheckIn:D] Running Loop=1 2025-03-14T17:42:38.109Z,1741974158.109 [Default:CheckIn:D] Stopped 2025-03-14T17:42:38.109Z,1741974158.109 [Default:CheckIn:E] Running Loop=1 2025-03-14T17:42:38.515Z,1741974158.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.422030 min 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn:E] Stopped 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn] Stopped 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn](INFO): Running loop #4 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn] Running Loop=4 2025-03-14T17:42:38.516Z,1741974158.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T17:42:38.517Z,1741974158.517 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T17:42:40.515Z,1741974160.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174234.00,A,3648.63282,N,12147.86452,W,4.879,118.14,140325,,,A*7D 2025-03-14T17:42:40.517Z,1741974160.517 [NAL9602](INFO): GPS fix at 20250314T174234: (36.810547, -121.797742) 2025-03-14T17:42:40.529Z,1741974160.529 [UniversalFixResidualReporter](INFO): Fix residual: 54.6 %DT, over the last 730.5 m. Residual distance 399.0 m at bearing -45.3 degrees. Fix at (36.8105, -121.7977) with 853.4 m made good. 2025-03-14T17:42:40.530Z,1741974160.530 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T17:42:40.530Z,1741974160.530 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T17:42:48.250Z,1741974168.250 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20250314T173554/Courier0003.lzma 2025-03-14T17:42:49.250Z,1741974169.250 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0003.lzma.bak 2025-03-14T17:42:49.250Z,1741974169.250 [DataOverHttps](INFO): SBD MOMSN=24229392 2025-03-14T17:43:05.881Z,1741974185.881 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20250314T173554/Express0005.lzma 2025-03-14T17:43:06.882Z,1741974186.882 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0005.lzma.bak 2025-03-14T17:43:06.882Z,1741974186.882 [DataOverHttps](INFO): SBD MOMSN=24229395 2025-03-14T17:43:08.439Z,1741974188.439 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T17:43:08.440Z,1741974188.440 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T17:43:08.440Z,1741974188.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T17:43:16.099Z,1741974196.099 [DVL_micro](ERROR): Failed to parse: :SA,-07.77,+09.19,127.8 2025-03-14T17:43:17.294Z,1741974197.294 [DVL_micro](ERROR): Failed to parse: :SA,-09.14,+01.33,141.4 2025-03-14T17:44:36.098Z,1741974276.098 [NAL9602](INFO): SBD MO Status=0, MOMSN=19198, MT Status=0, MTMSN=0 2025-03-14T17:44:36.098Z,1741974276.098 [NAL9602](INFO): No messages in MT queue 2025-03-14T17:45:06.797Z,1741974306.797 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T17:45:59.715Z,1741974359.715 [DVL_micro](ERROR): Failed to parse: :,+00353,+00000,A 2025-03-14T17:48:09.028Z,1741974489.028 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T17:48:09.028Z,1741974489.028 [Default:CheckIn:C.Wait] Stopped 2025-03-14T17:48:09.029Z,1741974489.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T17:48:09.029Z,1741974489.029 [Default:CheckIn:D] Running Loop=1 2025-03-14T17:48:09.403Z,1741974489.403 [Default:CheckIn:D] Stopped 2025-03-14T17:48:09.403Z,1741974489.403 [Default:CheckIn:E] Running Loop=1 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.943595 min 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn:E] Stopped 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn] Stopped 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn](INFO): Running loop #5 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn] Running Loop=5 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T17:48:09.813Z,1741974489.813 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T17:48:11.818Z,1741974491.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174806.00,A,3648.37987,N,12147.45580,W,6.104,52.32,140325,,,A*42 2025-03-14T17:48:11.820Z,1741974491.820 [NAL9602](INFO): GPS fix at 20250314T174806: (36.806331, -121.790930) 2025-03-14T17:48:11.856Z,1741974491.856 [UniversalFixResidualReporter](INFO): Fix residual: 41.7 %DT, over the last 611.0 m. Residual distance 255.0 m at bearing -49.4 degrees. Fix at (36.8063, -121.7909) with 766.5 m made good. 2025-03-14T17:48:11.857Z,1741974491.857 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T17:48:11.857Z,1741974491.857 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T17:48:19.540Z,1741974499.540 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20250314T173554/Courier0009.lzma 2025-03-14T17:48:20.542Z,1741974500.542 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0009.lzma.bak 2025-03-14T17:48:20.542Z,1741974500.542 [DataOverHttps](INFO): SBD MOMSN=24229407 2025-03-14T17:48:36.948Z,1741974516.948 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20250314T173554/Express0010.lzma 2025-03-14T17:48:37.942Z,1741974517.942 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0010.lzma.bak 2025-03-14T17:48:37.942Z,1741974517.942 [DataOverHttps](INFO): SBD MOMSN=24229410 2025-03-14T17:48:39.732Z,1741974519.732 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T17:48:39.732Z,1741974519.732 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T17:48:39.732Z,1741974519.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T17:48:47.018Z,1741974527.018 [NAL9602](INFO): SBD MO Status=0, MOMSN=19199, MT Status=0, MTMSN=0 2025-03-14T17:48:47.018Z,1741974527.018 [NAL9602](INFO): No messages in MT queue 2025-03-14T17:49:17.700Z,1741974557.700 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T17:53:40.344Z,1741974820.344 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T17:53:40.344Z,1741974820.344 [Default:CheckIn:C.Wait] Stopped 2025-03-14T17:53:40.344Z,1741974820.344 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T17:53:40.345Z,1741974820.345 [Default:CheckIn:D] Running Loop=1 2025-03-14T17:53:40.808Z,1741974820.808 [Default:CheckIn:D] Stopped 2025-03-14T17:53:40.808Z,1741974820.808 [Default:CheckIn:E] Running Loop=1 2025-03-14T17:53:41.142Z,1741974821.142 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.467019 min 2025-03-14T17:53:41.142Z,1741974821.142 [Default:CheckIn:E] Stopped 2025-03-14T17:53:41.142Z,1741974821.142 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T17:53:41.142Z,1741974821.142 [Default:CheckIn] Stopped 2025-03-14T17:53:41.142Z,1741974821.142 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T17:53:41.143Z,1741974821.143 [Default:CheckIn](INFO): Running loop #6 2025-03-14T17:53:41.143Z,1741974821.143 [Default:CheckIn] Running Loop=6 2025-03-14T17:53:41.143Z,1741974821.143 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T17:53:41.143Z,1741974821.143 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T17:53:43.154Z,1741974823.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175337.00,A,3648.33437,N,12147.12302,W,4.393,193.57,140325,,,A*77 2025-03-14T17:53:43.156Z,1741974823.156 [NAL9602](INFO): GPS fix at 20250314T175337: (36.805573, -121.785384) 2025-03-14T17:53:43.166Z,1741974823.166 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T17:53:43.167Z,1741974823.167 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T17:53:50.921Z,1741974830.921 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0012.lzma 2025-03-14T17:53:51.922Z,1741974831.922 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0012.lzma.bak 2025-03-14T17:53:51.922Z,1741974831.922 [DataOverHttps](INFO): SBD MOMSN=24229421 2025-03-14T17:54:08.236Z,1741974848.236 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20250314T173554/Express0013.lzma 2025-03-14T17:54:09.238Z,1741974849.238 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0013.lzma.bak 2025-03-14T17:54:09.238Z,1741974849.238 [DataOverHttps](INFO): SBD MOMSN=24229424 2025-03-14T17:54:10.650Z,1741974850.650 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T17:54:10.650Z,1741974850.650 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T17:54:10.651Z,1741974850.651 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T17:54:22.348Z,1741974862.348 [NAL9602](INFO): SBD MO Status=2, MOMSN=19200, MT Status=2, MTMSN=0 2025-03-14T17:54:22.348Z,1741974862.348 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T17:54:51.027Z,1741974891.027 [NAL9602](INFO): SBD MO Status=0, MOMSN=19200, MT Status=0, MTMSN=0 2025-03-14T17:54:51.027Z,1741974891.027 [NAL9602](INFO): No messages in MT queue 2025-03-14T17:55:21.751Z,1741974921.751 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T17:59:11.219Z,1741975151.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T17:59:11.219Z,1741975151.219 [Default:CheckIn:C.Wait] Stopped 2025-03-14T17:59:11.219Z,1741975151.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T17:59:11.219Z,1741975151.219 [Default:CheckIn:D] Running Loop=1 2025-03-14T17:59:11.637Z,1741975151.637 [Default:CheckIn:D] Stopped 2025-03-14T17:59:11.637Z,1741975151.637 [Default:CheckIn:E] Running Loop=1 2025-03-14T17:59:12.043Z,1741975152.043 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.980827 min 2025-03-14T17:59:12.043Z,1741975152.043 [Default:CheckIn:E] Stopped 2025-03-14T17:59:12.043Z,1741975152.043 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T17:59:12.043Z,1741975152.043 [Default:CheckIn] Stopped 2025-03-14T17:59:12.043Z,1741975152.043 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T17:59:12.043Z,1741975152.043 [Default:CheckIn](INFO): Running loop #7 2025-03-14T17:59:12.045Z,1741975152.045 [Default:CheckIn] Running Loop=7 2025-03-14T17:59:12.045Z,1741975152.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T17:59:12.045Z,1741975152.045 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T17:59:14.038Z,1741975154.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175908.00,A,3648.15252,N,12147.21565,W,0.078,288.48,140325,,,D*77 2025-03-14T17:59:14.041Z,1741975154.041 [NAL9602](INFO): GPS fix at 20250314T175908: (36.802542, -121.786928) 2025-03-14T17:59:14.051Z,1741975154.051 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T17:59:14.051Z,1741975154.051 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T17:59:22.128Z,1741975162.128 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250314T173554/Courier0015.lzma 2025-03-14T17:59:23.130Z,1741975163.130 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0015.lzma.bak 2025-03-14T17:59:23.130Z,1741975163.130 [DataOverHttps](INFO): SBD MOMSN=24229434 2025-03-14T17:59:35.045Z,1741975175.045 [NAL9602](INFO): SBD MO Status=2, MOMSN=19201, MT Status=2, MTMSN=0 2025-03-14T17:59:35.045Z,1741975175.045 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T17:59:43.148Z,1741975183.148 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250314T173554/Express0016.lzma 2025-03-14T17:59:43.960Z,1741975183.960 [NAL9602](INFO): SBD MO Status=0, MOMSN=19201, MT Status=0, MTMSN=0 2025-03-14T17:59:43.960Z,1741975183.960 [NAL9602](INFO): No messages in MT queue 2025-03-14T17:59:44.150Z,1741975184.150 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0016.lzma.bak 2025-03-14T17:59:44.150Z,1741975184.150 [DataOverHttps](INFO): SBD MOMSN=24229437 2025-03-14T17:59:45.588Z,1741975185.588 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T17:59:45.588Z,1741975185.588 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T17:59:45.588Z,1741975185.588 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:00:14.660Z,1741975214.660 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:04:46.155Z,1741975486.155 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:04:46.155Z,1741975486.155 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:04:46.155Z,1741975486.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:04:46.156Z,1741975486.156 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:04:46.570Z,1741975486.570 [Default:CheckIn:D] Stopped 2025-03-14T18:04:46.571Z,1741975486.571 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.563057 min 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn:E] Stopped 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn] Stopped 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn](INFO): Running loop #8 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn] Running Loop=8 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:04:46.966Z,1741975486.966 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:04:48.975Z,1741975488.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180443.00,A,3648.14984,N,12147.22368,W,0.078,316.04,140325,,,D*78 2025-03-14T18:04:48.977Z,1741975488.977 [NAL9602](INFO): GPS fix at 20250314T180443: (36.802497, -121.787061) 2025-03-14T18:04:48.987Z,1741975488.987 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:04:48.987Z,1741975488.987 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:04:56.355Z,1741975496.355 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0018.lzma 2025-03-14T18:04:57.358Z,1741975497.358 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0018.lzma.bak 2025-03-14T18:04:57.358Z,1741975497.358 [DataOverHttps](INFO): SBD MOMSN=24229447 2025-03-14T18:05:03.120Z,1741975503.120 [NAL9602](INFO): SBD MO Status=0, MOMSN=19202, MT Status=0, MTMSN=0 2025-03-14T18:05:03.120Z,1741975503.120 [NAL9602](INFO): No messages in MT queue 2025-03-14T18:05:13.316Z,1741975513.316 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250314T173554/Express0019.lzma 2025-03-14T18:05:14.318Z,1741975514.318 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0019.lzma.bak 2025-03-14T18:05:14.318Z,1741975514.318 [DataOverHttps](INFO): SBD MOMSN=24229450 2025-03-14T18:05:16.055Z,1741975516.055 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:05:16.055Z,1741975516.055 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:05:16.055Z,1741975516.055 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:05:33.823Z,1741975533.823 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:10:16.630Z,1741975816.630 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:10:16.630Z,1741975816.630 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:10:16.630Z,1741975816.630 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:10:16.631Z,1741975816.631 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:10:17.034Z,1741975817.034 [Default:CheckIn:D] Stopped 2025-03-14T18:10:17.034Z,1741975817.034 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:10:17.439Z,1741975817.439 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.070785 min 2025-03-14T18:10:17.439Z,1741975817.439 [Default:CheckIn:E] Stopped 2025-03-14T18:10:17.439Z,1741975817.439 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:10:17.439Z,1741975817.439 [Default:CheckIn] Stopped 2025-03-14T18:10:17.439Z,1741975817.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:10:17.439Z,1741975817.439 [Default:CheckIn](INFO): Running loop #9 2025-03-14T18:10:17.440Z,1741975817.440 [Default:CheckIn] Running Loop=9 2025-03-14T18:10:17.440Z,1741975817.440 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:10:17.440Z,1741975817.440 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:10:19.457Z,1741975819.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181013.00,A,3648.17597,N,12147.28022,W,0.156,259.71,140325,,,D*77 2025-03-14T18:10:19.459Z,1741975819.459 [NAL9602](INFO): GPS fix at 20250314T181013: (36.802933, -121.788004) 2025-03-14T18:10:19.469Z,1741975819.469 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:10:19.469Z,1741975819.469 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:10:27.332Z,1741975827.332 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0021.lzma 2025-03-14T18:10:28.334Z,1741975828.334 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0021.lzma.bak 2025-03-14T18:10:28.334Z,1741975828.334 [DataOverHttps](INFO): SBD MOMSN=24229469 2025-03-14T18:10:46.020Z,1741975846.020 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250314T173554/Express0022.lzma 2025-03-14T18:10:47.022Z,1741975847.022 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0022.lzma.bak 2025-03-14T18:10:47.022Z,1741975847.022 [DataOverHttps](INFO): SBD MOMSN=24229472 2025-03-14T18:10:48.567Z,1741975848.567 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:10:48.567Z,1741975848.567 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:10:48.567Z,1741975848.567 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:10:50.979Z,1741975850.979 [NAL9602](INFO): SBD MO Status=0, MOMSN=19203, MT Status=0, MTMSN=0 2025-03-14T18:10:50.979Z,1741975850.979 [NAL9602](INFO): No messages in MT queue 2025-03-14T18:11:21.681Z,1741975881.681 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:15:49.157Z,1741976149.157 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:15:49.157Z,1741976149.157 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:15:49.157Z,1741976149.157 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:15:49.157Z,1741976149.157 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:15:49.561Z,1741976149.561 [Default:CheckIn:D] Stopped 2025-03-14T18:15:49.561Z,1741976149.561 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:15:49.954Z,1741976149.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.612903 min 2025-03-14T18:15:49.954Z,1741976149.954 [Default:CheckIn:E] Stopped 2025-03-14T18:15:49.954Z,1741976149.954 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:15:49.954Z,1741976149.954 [Default:CheckIn] Stopped 2025-03-14T18:15:49.954Z,1741976149.954 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:15:49.954Z,1741976149.954 [Default:CheckIn](INFO): Running loop #10 2025-03-14T18:15:49.955Z,1741976149.955 [Default:CheckIn] Running Loop=10 2025-03-14T18:15:49.955Z,1741976149.955 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:15:49.955Z,1741976149.955 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:15:51.973Z,1741976151.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181546.00,A,3648.17225,N,12147.27803,W,0.078,259.71,140325,,,A*70 2025-03-14T18:15:51.976Z,1741976151.976 [NAL9602](INFO): GPS fix at 20250314T181546: (36.802871, -121.787967) 2025-03-14T18:15:51.986Z,1741976151.986 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:15:51.986Z,1741976151.986 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:16:00.991Z,1741976160.991 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0024.lzma 2025-03-14T18:16:01.994Z,1741976161.994 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0024.lzma.bak 2025-03-14T18:16:01.994Z,1741976161.994 [DataOverHttps](INFO): SBD MOMSN=24229482 2025-03-14T18:16:18.628Z,1741976178.628 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250314T173554/Express0025.lzma 2025-03-14T18:16:19.443Z,1741976179.443 [NAL9602](INFO): SBD MO Status=0, MOMSN=19204, MT Status=0, MTMSN=0 2025-03-14T18:16:19.443Z,1741976179.443 [NAL9602](INFO): No messages in MT queue 2025-03-14T18:16:19.630Z,1741976179.630 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0025.lzma.bak 2025-03-14T18:16:19.630Z,1741976179.630 [DataOverHttps](INFO): SBD MOMSN=24229485 2025-03-14T18:16:21.477Z,1741976181.477 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:16:21.477Z,1741976181.477 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:16:21.477Z,1741976181.477 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:16:50.145Z,1741976210.145 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:20:25.576Z,1741976425.576 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-03-14T18:21:22.104Z,1741976482.104 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:21:22.104Z,1741976482.104 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:21:22.104Z,1741976482.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:21:22.105Z,1741976482.105 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:21:22.507Z,1741976482.507 [Default:CheckIn:D] Stopped 2025-03-14T18:21:22.507Z,1741976482.507 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:21:22.901Z,1741976482.901 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.162000 min 2025-03-14T18:21:22.901Z,1741976482.901 [Default:CheckIn:E] Stopped 2025-03-14T18:21:22.901Z,1741976482.901 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:21:22.901Z,1741976482.901 [Default:CheckIn] Stopped 2025-03-14T18:21:22.902Z,1741976482.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:21:22.902Z,1741976482.902 [Default:CheckIn](INFO): Running loop #11 2025-03-14T18:21:22.902Z,1741976482.902 [Default:CheckIn] Running Loop=11 2025-03-14T18:21:22.902Z,1741976482.902 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:21:22.902Z,1741976482.902 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:21:24.930Z,1741976484.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182119.00,A,3648.17729,N,12147.27977,W,0.272,177.44,140325,,,A*77 2025-03-14T18:21:24.933Z,1741976484.933 [NAL9602](INFO): GPS fix at 20250314T182119: (36.802955, -121.787996) 2025-03-14T18:21:24.970Z,1741976484.970 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:21:24.970Z,1741976484.970 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:21:31.984Z,1741976491.984 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0027.lzma 2025-03-14T18:21:32.986Z,1741976492.986 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0027.lzma.bak 2025-03-14T18:21:32.986Z,1741976492.986 [DataOverHttps](INFO): SBD MOMSN=24229496 2025-03-14T18:21:39.198Z,1741976499.198 [NAL9602](INFO): SBD MO Status=0, MOMSN=19205, MT Status=0, MTMSN=0 2025-03-14T18:21:39.198Z,1741976499.198 [NAL9602](INFO): No messages in MT queue 2025-03-14T18:21:51.677Z,1741976511.677 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20250314T173554/Express0028.lzma 2025-03-14T18:21:52.470Z,1741976512.470 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0028.lzma.bak 2025-03-14T18:21:52.470Z,1741976512.470 [DataOverHttps](INFO): SBD MOMSN=24229499 2025-03-14T18:21:54.099Z,1741976514.099 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:21:54.099Z,1741976514.099 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:21:54.099Z,1741976514.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:22:09.850Z,1741976529.850 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:22:30.932Z,1741976550.932 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-03-14T18:22:30.932Z,1741976550.932 [DVL_micro] Communications Fault, FailCount= 1 2025-03-14T18:22:30.932Z,1741976550.932 [DVL_micro](ERROR): Communications Fault 2025-03-14T18:22:30.932Z,1741976550.932 [DVL_micro](ERROR): Failed to parse: 2025-03-14T18:22:30.987Z,1741976550.987 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-03-14T18:22:31.352Z,1741976551.352 [DVL_micro](INFO): Powering down 2025-03-14T18:22:32.086Z,1741976552.086 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-03-14T18:22:32.086Z,1741976552.086 [DVL_micro] No Fault, FailCount= 1 2025-03-14T18:26:54.701Z,1741976814.701 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:26:54.701Z,1741976814.701 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:26:54.701Z,1741976814.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:26:54.702Z,1741976814.702 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:26:55.110Z,1741976815.110 [Default:CheckIn:D] Stopped 2025-03-14T18:26:55.110Z,1741976815.110 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:26:55.519Z,1741976815.519 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.705387 min 2025-03-14T18:26:55.519Z,1741976815.519 [Default:CheckIn:E] Stopped 2025-03-14T18:26:55.519Z,1741976815.519 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:26:55.519Z,1741976815.519 [Default:CheckIn] Stopped 2025-03-14T18:26:55.519Z,1741976815.519 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:26:55.519Z,1741976815.519 [Default:CheckIn](INFO): Running loop #12 2025-03-14T18:26:55.520Z,1741976815.520 [Default:CheckIn] Running Loop=12 2025-03-14T18:26:55.520Z,1741976815.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:26:55.520Z,1741976815.520 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:26:57.521Z,1741976817.521 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182651.00,A,3648.17529,N,12147.27881,W,0.078,314.63,140325,,,A*7C 2025-03-14T18:26:57.524Z,1741976817.524 [NAL9602](INFO): GPS fix at 20250314T182651: (36.802921, -121.787980) 2025-03-14T18:26:57.534Z,1741976817.534 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:26:57.534Z,1741976817.534 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:27:04.744Z,1741976824.744 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0030.lzma 2025-03-14T18:27:05.746Z,1741976825.746 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0030.lzma.bak 2025-03-14T18:27:05.746Z,1741976825.746 [DataOverHttps](INFO): SBD MOMSN=24229509 2025-03-14T18:27:21.829Z,1741976841.829 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20250314T173554/Express0031.lzma 2025-03-14T18:27:22.830Z,1741976842.830 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0031.lzma.bak 2025-03-14T18:27:22.830Z,1741976842.830 [DataOverHttps](INFO): SBD MOMSN=24229512 2025-03-14T18:27:24.211Z,1741976844.211 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:27:24.212Z,1741976844.212 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:27:24.212Z,1741976844.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:28:28.436Z,1741976908.436 [NAL9602](INFO): SBD MO Status=2, MOMSN=19206, MT Status=2, MTMSN=0 2025-03-14T18:28:28.436Z,1741976908.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T18:28:54.282Z,1741976934.282 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-14T18:28:54.282Z,1741976934.282 [DropWeight] Hardware Fault, FailCount= 1 2025-03-14T18:28:54.282Z,1741976934.282 [DropWeight](ERROR): Hardware Fault 2025-03-14T18:28:54.299Z,1741976934.299 [CommandExec](FAULT): Scheduling is paused 2025-03-14T18:28:54.300Z,1741976934.300 [CBIT](INFO): Critical error at 20250314T182854 2025-03-14T18:28:54.302Z,1741976934.302 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-14T18:28:54.303Z,1741976934.303 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-14T18:28:54.724Z,1741976934.724 [CBIT](INFO): Critical error at 20250314T182854 2025-03-14T18:29:07.212Z,1741976947.212 [NAL9602](INFO): SBD MO Status=2, MOMSN=19206, MT Status=2, MTMSN=0 2025-03-14T18:29:07.212Z,1741976947.212 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-14T18:30:08.209Z,1741977008.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=19206, MT Status=0, MTMSN=0 2025-03-14T18:30:08.209Z,1741977008.209 [NAL9602](INFO): No messages in MT queue 2025-03-14T18:30:38.913Z,1741977038.913 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:32:24.808Z,1741977144.808 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:32:24.808Z,1741977144.808 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:32:24.808Z,1741977144.808 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:32:24.808Z,1741977144.808 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:32:25.200Z,1741977145.200 [Default:CheckIn:D] Stopped 2025-03-14T18:32:25.200Z,1741977145.200 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.206877 min 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn:E] Stopped 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn] Stopped 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn](INFO): Running loop #13 2025-03-14T18:32:25.599Z,1741977145.599 [Default:CheckIn] Running Loop=13 2025-03-14T18:32:25.616Z,1741977145.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:32:25.616Z,1741977145.616 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:32:27.592Z,1741977147.592 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183221.00,A,3648.17425,N,12147.27698,W,0.389,219.19,140325,,,A*79 2025-03-14T18:32:27.594Z,1741977147.594 [NAL9602](INFO): GPS fix at 20250314T183221: (36.802904, -121.787950) 2025-03-14T18:32:27.605Z,1741977147.605 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:32:27.605Z,1741977147.605 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:32:35.112Z,1741977155.112 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250314T173554/Courier0033.lzma 2025-03-14T18:32:36.923Z,1741977156.923 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0033.lzma.bak 2025-03-14T18:32:36.924Z,1741977156.924 [DataOverHttps](INFO): SBD MOMSN=24229566 2025-03-14T18:32:53.121Z,1741977173.121 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20250314T173554/Express0034.lzma 2025-03-14T18:32:54.122Z,1741977174.122 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0034.lzma.bak 2025-03-14T18:32:54.122Z,1741977174.122 [DataOverHttps](INFO): SBD MOMSN=24229571 2025-03-14T18:32:55.496Z,1741977175.496 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:32:55.496Z,1741977175.496 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:32:55.496Z,1741977175.496 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:37:30.210Z,1741977450.210 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-14T18:37:56.090Z,1741977476.090 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:37:56.090Z,1741977476.090 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:37:56.090Z,1741977476.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:37:56.090Z,1741977476.090 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:37:56.497Z,1741977476.497 [Default:CheckIn:D] Stopped 2025-03-14T18:37:56.497Z,1741977476.497 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:37:56.886Z,1741977476.886 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.728499 min 2025-03-14T18:37:56.886Z,1741977476.886 [Default:CheckIn:E] Stopped 2025-03-14T18:37:56.886Z,1741977476.886 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:37:56.887Z,1741977476.887 [Default:CheckIn] Stopped 2025-03-14T18:37:56.887Z,1741977476.887 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:37:56.887Z,1741977476.887 [Default:CheckIn](INFO): Running loop #14 2025-03-14T18:37:56.887Z,1741977476.887 [Default:CheckIn] Running Loop=14 2025-03-14T18:37:56.887Z,1741977476.887 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:37:56.887Z,1741977476.887 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:37:58.909Z,1741977478.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183753.00,A,3648.17510,N,12147.28338,W,0.603,219.19,140325,,,A*79 2025-03-14T18:37:58.911Z,1741977478.911 [NAL9602](INFO): GPS fix at 20250314T183753: (36.802918, -121.788056) 2025-03-14T18:37:58.921Z,1741977478.921 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:37:58.921Z,1741977478.921 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:38:06.923Z,1741977486.923 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T173554/Courier0036.lzma 2025-03-14T18:38:07.926Z,1741977487.926 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0036.lzma.bak 2025-03-14T18:38:07.926Z,1741977487.926 [DataOverHttps](INFO): SBD MOMSN=24229581 2025-03-14T18:38:24.084Z,1741977504.084 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20250314T173554/Express0037.lzma 2025-03-14T18:38:25.088Z,1741977505.088 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0037.lzma.bak 2025-03-14T18:38:25.088Z,1741977505.088 [DataOverHttps](INFO): SBD MOMSN=24229584 2025-03-14T18:38:26.785Z,1741977506.785 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:38:26.785Z,1741977506.785 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:38:26.785Z,1741977506.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:38:31.340Z,1741977511.340 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:38:40.513Z,1741977520.513 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-03-14T18:38:40.514Z,1741977520.514 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-03-14T18:38:40.517Z,1741977520.517 [BPC1](INFO): Received data from all battery sticks. 2025-03-14T18:43:27.387Z,1741977807.387 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:43:27.387Z,1741977807.387 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:43:27.387Z,1741977807.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:43:27.388Z,1741977807.388 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:43:27.781Z,1741977807.781 [Default:CheckIn:D] Stopped 2025-03-14T18:43:27.781Z,1741977807.781 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:43:28.189Z,1741977808.189 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.249894 min 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn:E] Stopped 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn] Stopped 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn](INFO): Running loop #15 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn] Running Loop=15 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:43:28.190Z,1741977808.190 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:43:30.201Z,1741977810.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184324.00,A,3648.16951,N,12147.28025,W,0.564,228.42,140325,,,A*73 2025-03-14T18:43:30.203Z,1741977810.203 [NAL9602](INFO): GPS fix at 20250314T184324: (36.802825, -121.788004) 2025-03-14T18:43:30.214Z,1741977810.214 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:43:30.214Z,1741977810.214 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:43:38.756Z,1741977818.756 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250314T173554/Courier0039.lzma 2025-03-14T18:43:39.758Z,1741977819.758 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0039.lzma.bak 2025-03-14T18:43:39.758Z,1741977819.758 [DataOverHttps](INFO): SBD MOMSN=24229598 2025-03-14T18:43:54.503Z,1741977834.503 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-14T18:43:54.503Z,1741977834.503 [DropWeight] No Fault, FailCount= 1 2025-03-14T18:43:55.704Z,1741977835.704 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250314T173554/Express0040.lzma 2025-03-14T18:43:56.706Z,1741977836.706 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0040.lzma.bak 2025-03-14T18:43:56.706Z,1741977836.706 [DataOverHttps](INFO): SBD MOMSN=24229601 2025-03-14T18:43:58.139Z,1741977838.139 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:43:58.139Z,1741977838.139 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:43:58.139Z,1741977838.139 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:44:00.914Z,1741977840.914 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-14T18:44:00.996Z,1741977840.996 [NAL9602](ERROR): received: +CSQ:0 OK206, 0, 0, 0, 0 OK 2025-03-14T18:44:27.167Z,1741977867.167 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-02166,-000000.00,9999.99,000.00 2025-03-14T18:44:27.167Z,1741977867.167 [DVL_micro](ERROR): Failed to parse: :BI,-02166,-000000.00,9999.99,000.00 2025-03-14T18:48:32.451Z,1741978112.451 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-14T18:48:58.751Z,1741978138.751 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-14T18:48:58.751Z,1741978138.751 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:48:58.751Z,1741978138.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:48:58.751Z,1741978138.751 [Default:CheckIn:D] Running Loop=1 2025-03-14T18:48:59.131Z,1741978139.131 [Default:CheckIn:D] Stopped 2025-03-14T18:48:59.131Z,1741978139.131 [Default:CheckIn:E] Running Loop=1 2025-03-14T18:48:59.535Z,1741978139.535 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.772396 min 2025-03-14T18:48:59.535Z,1741978139.535 [Default:CheckIn:E] Stopped 2025-03-14T18:48:59.535Z,1741978139.535 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-14T18:48:59.535Z,1741978139.535 [Default:CheckIn] Stopped 2025-03-14T18:48:59.536Z,1741978139.536 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:48:59.536Z,1741978139.536 [Default:CheckIn](INFO): Running loop #16 2025-03-14T18:48:59.536Z,1741978139.536 [Default:CheckIn] Running Loop=16 2025-03-14T18:48:59.536Z,1741978139.536 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-14T18:48:59.536Z,1741978139.536 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-14T18:49:01.562Z,1741978141.562 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184855.00,A,3648.15011,N,12147.27956,W,0.836,344.09,140325,,,A*7C 2025-03-14T18:49:01.564Z,1741978141.564 [NAL9602](INFO): GPS fix at 20250314T184855: (36.802502, -121.787993) 2025-03-14T18:49:01.574Z,1741978141.574 [Default:CheckIn:Read_GPS] Stopped 2025-03-14T18:49:01.574Z,1741978141.574 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-14T18:49:08.804Z,1741978148.804 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20250314T173554/Courier0042.lzma 2025-03-14T18:49:09.806Z,1741978149.806 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0042.lzma.bak 2025-03-14T18:49:09.806Z,1741978149.806 [DataOverHttps](INFO): SBD MOMSN=24229610 2025-03-14T18:49:25.792Z,1741978165.792 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20250314T173554/Express0043.lzma 2025-03-14T18:49:26.794Z,1741978166.794 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0043.lzma.bak 2025-03-14T18:49:26.794Z,1741978166.794 [DataOverHttps](INFO): SBD MOMSN=24229613 2025-03-14T18:49:28.223Z,1741978168.223 [Default:CheckIn:Read_Iridium] Stopped 2025-03-14T18:49:28.223Z,1741978168.223 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-14T18:49:28.223Z,1741978168.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-14T18:49:33.465Z,1741978173.465 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-14T18:51:27.337Z,1741978287.337 [CommandExec](IMPORTANT): got command quit 2025-03-14T18:51:28.344Z,1741978288.344 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:28.344Z,1741978288.344 [CommandExec](INFO): Uninitializing the command executive. 2025-03-14T18:51:28.344Z,1741978288.344 [CommandExec](INFO): Uninitializing the command scheduler. 2025-03-14T18:51:28.345Z,1741978288.345 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:28.408Z,1741978288.408 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-03-14T18:51:28.408Z,1741978288.408 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-03-14T18:51:28.409Z,1741978288.409 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:28.409Z,1741978288.409 [NavChartDb](INFO): Join timeout helper Thread ID is 3244 2025-03-14T18:51:28.416Z,1741978288.416 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:28.416Z,1741978288.416 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:28.433Z,1741978288.433 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-03-14T18:51:28.433Z,1741978288.433 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:28.434Z,1741978288.434 [Radio_Surface](INFO): Join timeout helper Thread ID is 3245 2025-03-14T18:51:28.488Z,1741978288.488 [Radio_Surface](INFO): Powering down 2025-03-14T18:51:28.489Z,1741978288.489 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:28.489Z,1741978288.489 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:28.490Z,1741978288.490 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-03-14T18:51:28.490Z,1741978288.490 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:28.490Z,1741978288.490 [Onboard](INFO): Join timeout helper Thread ID is 3246 2025-03-14T18:51:28.600Z,1741978288.600 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-03-14T18:51:32.804Z,1741978292.804 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:32.804Z,1741978292.804 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:32.812Z,1741978292.812 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-03-14T18:51:32.812Z,1741978292.812 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:32.812Z,1741978292.812 [DataOverHttps](INFO): Join timeout helper Thread ID is 3247 2025-03-14T18:51:33.620Z,1741978293.620 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:33.620Z,1741978293.620 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.640Z,1741978293.640 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-03-14T18:51:33.640Z,1741978293.640 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.640Z,1741978293.640 [DAT](INFO): Join timeout helper Thread ID is 3248 2025-03-14T18:51:33.688Z,1741978293.688 [DAT](INFO): Powering down 2025-03-14T18:51:33.760Z,1741978293.760 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:33.761Z,1741978293.761 [DAT](INFO): Powering down 2025-03-14T18:51:33.761Z,1741978293.761 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.765Z,1741978293.765 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-03-14T18:51:33.765Z,1741978293.765 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.766Z,1741978293.766 [BackseatComponent](INFO): Join timeout helper Thread ID is 3249 2025-03-14T18:51:33.788Z,1741978293.788 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:33.788Z,1741978293.788 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.790Z,1741978293.790 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-03-14T18:51:33.790Z,1741978293.790 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.790Z,1741978293.790 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3250 2025-03-14T18:51:33.808Z,1741978293.808 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:33.808Z,1741978293.808 [WetLabsBB2FL](INFO): Powering down 2025-03-14T18:51:33.809Z,1741978293.809 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.817Z,1741978293.817 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-03-14T18:51:33.817Z,1741978293.817 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:33.817Z,1741978293.817 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3251 2025-03-14T18:51:34.724Z,1741978294.724 [CTD_Seabird](INFO): Powering down 2025-03-14T18:51:34.736Z,1741978294.736 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:34.736Z,1741978294.736 [CTD_Seabird](INFO): Powering down 2025-03-14T18:51:34.748Z,1741978294.748 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.769Z,1741978294.769 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-03-14T18:51:34.769Z,1741978294.769 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.769Z,1741978294.769 [logger](INFO): Join timeout helper Thread ID is 3252 2025-03-14T18:51:34.770Z,1741978294.770 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:34.770Z,1741978294.770 [logger ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.771Z,1741978294.771 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-03-14T18:51:34.771Z,1741978294.771 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.772Z,1741978294.772 [CommandLine](INFO): Join timeout helper Thread ID is 3253 2025-03-14T18:51:34.804Z,1741978294.804 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:34.804Z,1741978294.804 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.812Z,1741978294.812 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-03-14T18:51:34.813Z,1741978294.813 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.813Z,1741978294.813 [CommandExec](INFO): Join timeout helper Thread ID is 3254 2025-03-14T18:51:34.814Z,1741978294.814 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-03-14T18:51:34.814Z,1741978294.814 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:34.815Z,1741978294.815 [controlThread](INFO): Join timeout helper Thread ID is 3255 2025-03-14T18:51:35.096Z,1741978295.096 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-03-14T18:51:35.096Z,1741978295.096 [controlThread](DEBUG): Uninitializing ControlThread 2025-03-14T18:51:35.098Z,1741978295.098 [AHRS_M2](INFO): Powering down 2025-03-14T18:51:35.264Z,1741978295.264 [DVL_micro](INFO): Powering down 2025-03-14T18:51:35.265Z,1741978295.265 [NAL9602](INFO): Powering down 2025-03-14T18:51:35.266Z,1741978295.266 [Sonardyne_Nano](INFO): Powering down 2025-03-14T18:51:35.384Z,1741978295.384 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-03-14T18:51:35.385Z,1741978295.385 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-03-14T18:51:35.386Z,1741978295.386 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-03-14T18:51:35.386Z,1741978295.386 [MissionManager](INFO): Uninitializing Mission Default 2025-03-14T18:51:35.386Z,1741978295.386 [Default] Stopped 2025-03-14T18:51:35.386Z,1741978295.386 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-14T18:51:35.386Z,1741978295.386 [Default:B.GoToSurface] Stopped 2025-03-14T18:51:35.386Z,1741978295.386 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-14T18:51:35.387Z,1741978295.387 [Default:CheckIn] Stopped 2025-03-14T18:51:35.387Z,1741978295.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-14T18:51:35.387Z,1741978295.387 [Default:CheckIn:C.Wait] Stopped 2025-03-14T18:51:35.387Z,1741978295.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-14T18:51:35.390Z,1741978295.390 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-03-14T18:51:35.390Z,1741978295.390 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-03-14T18:51:35.390Z,1741978295.390 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-03-14T18:51:35.391Z,1741978295.391 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-03-14T18:51:35.391Z,1741978295.391 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-03-14T18:51:35.391Z,1741978295.391 [BuoyancyServo](INFO): Powering down 2025-03-14T18:51:35.404Z,1741978295.404 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-14T18:51:35.404Z,1741978295.404 [ElevatorServo](INFO): Powering down 2025-03-14T18:51:35.405Z,1741978295.405 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-14T18:51:35.405Z,1741978295.405 [MassServo](INFO): Powering down 2025-03-14T18:51:35.406Z,1741978295.406 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-03-14T18:51:35.406Z,1741978295.406 [RudderServo](INFO): Powering down 2025-03-14T18:51:35.407Z,1741978295.407 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-14T18:51:35.407Z,1741978295.407 [ThrusterHE](INFO): Powering down 2025-03-14T18:51:35.407Z,1741978295.407 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-03-14T18:51:35.408Z,1741978295.408 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-03-14T18:51:35.408Z,1741978295.408 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-03-14T18:51:35.408Z,1741978295.408 [CBIT](DEBUG): Powering off loads. 2025-03-14T18:51:35.420Z,1741978295.420 [CBIT](DEBUG): Disabling WDT. 2025-03-14T18:51:35.432Z,1741978295.432 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-14T18:51:35.432Z,1741978295.432 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-14T18:51:35.433Z,1741978295.433 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.471Z,1741978295.471 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-03-14T18:51:35.471Z,1741978295.471 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-03-14T18:51:35.486Z,1741978295.486 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.494Z,1741978295.494 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.530Z,1741978295.530 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.533Z,1741978295.533 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.541Z,1741978295.541 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.548Z,1741978295.548 [DAT ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.591Z,1741978295.591 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.653Z,1741978295.653 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-03-14T18:51:35.735Z,1741978295.735 [logger ThreadHandler](INFO): Thread cancelled.