2025-09-12T21:17:24.912Z,1757711844.912 [CommandExec](IMPORTANT): got command restart logs
2025-09-12T21:17:43.868Z,1757711863.868 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20250912T002531/Express0113.lzma
2025-09-12T21:17:54.909Z,1757711874.909 [DataOverHttps](INFO): Moved sent file to Logs/20250912T002531/Express0113.lzma.bak
2025-09-12T21:17:54.909Z,1757711874.909 [DataOverHttps](INFO): SBD MOMSN=25976827
2025-09-12T21:18:21.574Z,1757711901.574 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250912T002531/Courier0115.lzma
2025-09-12T21:18:32.609Z,1757711912.609 [DataOverHttps](INFO): Moved sent file to Logs/20250912T002531/Courier0115.lzma.bak
2025-09-12T21:18:32.609Z,1757711912.609 [DataOverHttps](INFO): SBD MOMSN=25976837
2025-09-12T21:18:59.458Z,1757711939.458 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20250912T002531/Express0116.lzma
2025-09-12T21:19:10.501Z,1757711950.501 [DataOverHttps](INFO): Moved sent file to Logs/20250912T002531/Express0116.lzma.bak
2025-09-12T21:19:10.501Z,1757711950.501 [DataOverHttps](INFO): SBD MOMSN=25976846
2025-09-12T21:19:11.994Z,1757711951.994 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:19:11.994Z,1757711951.994 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:19:11.994Z,1757711951.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:22:04.125Z,1757712124.125 [NAL9602](INFO): SBD MO Status=1, MOMSN=23232, MT Status=0, MTMSN=0
2025-09-12T21:22:04.125Z,1757712124.125 [NAL9602](INFO): No messages in MT queue
2025-09-12T21:22:34.814Z,1757712154.814 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:22:45.827Z,1757712165.827 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-12T21:24:12.602Z,1757712252.602 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T21:24:12.602Z,1757712252.602 [Default:CheckIn:C.Wait] Stopped
2025-09-12T21:24:12.603Z,1757712252.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T21:24:12.603Z,1757712252.603 [Default:CheckIn:D] Running Loop=1
2025-09-12T21:24:13.051Z,1757712253.051 [Default:CheckIn:D] Stopped
2025-09-12T21:24:13.051Z,1757712253.051 [Default:CheckIn:E] Running Loop=1
2025-09-12T21:24:13.408Z,1757712253.408 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  35.531010 min 
2025-09-12T21:24:13.408Z,1757712253.408 [Default:CheckIn:E] Stopped
2025-09-12T21:24:13.408Z,1757712253.408 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T21:24:13.408Z,1757712253.408 [Default:CheckIn] Stopped
2025-09-12T21:24:13.408Z,1757712253.408 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T21:24:13.409Z,1757712253.409 [Default:CheckIn](INFO): Running loop #6
2025-09-12T21:24:13.409Z,1757712253.409 [Default:CheckIn] Running Loop=6
2025-09-12T21:24:13.409Z,1757712253.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T21:24:13.409Z,1757712253.409 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T21:24:15.409Z,1757712255.409 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212414.00,A,3648.35914,N,12151.63696,W,28.069,94.83,120925,,,D*7E
2025-09-12T21:24:15.412Z,1757712255.412 [NAL9602](INFO): GPS fix at 20250912T212414: (36.805986, -121.860616)
2025-09-12T21:24:15.438Z,1757712255.438 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T21:24:15.439Z,1757712255.439 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T21:24:23.211Z,1757712263.211 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20250912T211724/Courier0003.lzma
2025-09-12T21:24:30.947Z,1757712270.947 [NAL9602](INFO): SBD MO Status=1, MOMSN=23233, MT Status=0, MTMSN=0
2025-09-12T21:24:30.947Z,1757712270.947 [NAL9602](INFO): No messages in MT queue
2025-09-12T21:24:34.258Z,1757712274.258 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0003.lzma.bak
2025-09-12T21:24:34.258Z,1757712274.258 [DataOverHttps](INFO): SBD MOMSN=25976856
2025-09-12T21:24:50.279Z,1757712290.279 [DVL_micro](ERROR): Failed to parse:
9.99,+9999.99,+9999.99,+9999.99
2025-09-12T21:24:55.114Z,1757712295.114 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-09-12T21:24:55.114Z,1757712295.114 [DVL_micro] Communications Fault, FailCount= 1
2025-09-12T21:24:55.114Z,1757712295.114 [DVL_micro](ERROR): Communications Fault
2025-09-12T21:24:55.268Z,1757712295.268 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-09-12T21:24:55.627Z,1757712295.627 [DVL_micro](INFO): Powering down
2025-09-12T21:24:56.519Z,1757712296.519 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-09-12T21:24:56.519Z,1757712296.519 [DVL_micro] No Fault, FailCount= 1
2025-09-12T21:25:01.589Z,1757712301.589 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:25:01.724Z,1757712301.724 [DataOverHttps](INFO): Sending 563 bytes from file Logs/20250912T211724/Express0004.lzma
2025-09-12T21:25:12.764Z,1757712312.764 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0004.lzma.bak
2025-09-12T21:25:12.764Z,1757712312.764 [DataOverHttps](INFO): SBD MOMSN=25976880
2025-09-12T21:25:14.128Z,1757712314.128 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:25:14.128Z,1757712314.128 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:25:14.128Z,1757712314.128 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:26:17.194Z,1757712377.194 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T21:26:50.343Z,1757712410.343 [DVL_micro](ERROR): Failed to parse:
:SA,+00.92,-01.09,082.7
2025-09-12T21:30:14.818Z,1757712614.818 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T21:30:14.818Z,1757712614.818 [Default:CheckIn:C.Wait] Stopped
2025-09-12T21:30:14.819Z,1757712614.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T21:30:14.819Z,1757712614.819 [Default:CheckIn:D] Running Loop=1
2025-09-12T21:30:15.175Z,1757712615.175 [Default:CheckIn:D] Stopped
2025-09-12T21:30:15.175Z,1757712615.175 [Default:CheckIn:E] Running Loop=1
2025-09-12T21:30:15.565Z,1757712615.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  41.566488 min 
2025-09-12T21:30:15.565Z,1757712615.565 [Default:CheckIn:E] Stopped
2025-09-12T21:30:15.565Z,1757712615.565 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T21:30:15.565Z,1757712615.565 [Default:CheckIn] Stopped
2025-09-12T21:30:15.565Z,1757712615.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T21:30:15.566Z,1757712615.566 [Default:CheckIn](INFO): Running loop #7
2025-09-12T21:30:15.566Z,1757712615.566 [Default:CheckIn] Running Loop=7
2025-09-12T21:30:15.566Z,1757712615.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T21:30:15.566Z,1757712615.566 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T21:30:17.655Z,1757712617.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213016.00,A,3648.30037,N,12148.48512,W,25.153,80.90,120925,,,D*78
2025-09-12T21:30:17.657Z,1757712617.657 [NAL9602](INFO): GPS fix at 20250912T213016: (36.805006, -121.808085)
2025-09-12T21:30:17.692Z,1757712617.692 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T21:30:17.692Z,1757712617.692 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T21:30:25.922Z,1757712625.922 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250912T211724/Courier0006.lzma
2025-09-12T21:30:36.964Z,1757712636.964 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0006.lzma.bak
2025-09-12T21:30:36.964Z,1757712636.964 [DataOverHttps](INFO): SBD MOMSN=25976892
2025-09-12T21:31:05.227Z,1757712665.227 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20250912T211724/Express0007.lzma
2025-09-12T21:31:16.264Z,1757712676.264 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0007.lzma.bak
2025-09-12T21:31:16.264Z,1757712676.264 [DataOverHttps](INFO): SBD MOMSN=25976901
2025-09-12T21:31:17.810Z,1757712677.810 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:31:17.811Z,1757712677.811 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:31:17.811Z,1757712677.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:31:43.279Z,1757712703.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=23234, MT Status=0, MTMSN=0
2025-09-12T21:31:43.279Z,1757712703.279 [NAL9602](INFO): No messages in MT queue
2025-09-12T21:32:13.986Z,1757712733.986 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:34:58.821Z,1757712898.821 [DVL_micro](ERROR): Failed to parse:
:SA,-00.73,-03.62,162.3
2025-09-12T21:36:17.593Z,1757712977.593 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T21:36:18.418Z,1757712978.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T21:36:18.418Z,1757712978.418 [Default:CheckIn:C.Wait] Stopped
2025-09-12T21:36:18.418Z,1757712978.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T21:36:18.418Z,1757712978.418 [Default:CheckIn:D] Running Loop=1
2025-09-12T21:36:18.814Z,1757712978.814 [Default:CheckIn:D] Stopped
2025-09-12T21:36:18.814Z,1757712978.814 [Default:CheckIn:E] Running Loop=1
2025-09-12T21:36:19.223Z,1757712979.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  47.627136 min 
2025-09-12T21:36:19.223Z,1757712979.223 [Default:CheckIn:E] Stopped
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn] Stopped
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn](INFO): Running loop #8
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn] Running Loop=8
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T21:36:19.224Z,1757712979.224 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T21:36:21.255Z,1757712981.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213620.00,A,3648.36428,N,12147.11528,W,4.549,182.61,120925,,,D*7C
2025-09-12T21:36:21.258Z,1757712981.258 [NAL9602](INFO): GPS fix at 20250912T213620: (36.806071, -121.785255)
2025-09-12T21:36:21.268Z,1757712981.268 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T21:36:21.268Z,1757712981.268 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T21:36:29.077Z,1757712989.077 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250912T211724/Courier0009.lzma
2025-09-12T21:36:40.116Z,1757713000.116 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0009.lzma.bak
2025-09-12T21:36:40.116Z,1757713000.116 [DataOverHttps](INFO): SBD MOMSN=25976920
2025-09-12T21:36:41.426Z,1757713001.426 [NAL9602](INFO): SBD MO Status=2, MOMSN=23235, MT Status=2, MTMSN=0
2025-09-12T21:36:41.426Z,1757713001.426 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:37:06.747Z,1757713026.747 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20250912T211724/Express0010.lzma
2025-09-12T21:37:17.796Z,1757713037.796 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0010.lzma.bak
2025-09-12T21:37:17.797Z,1757713037.797 [DataOverHttps](INFO): SBD MOMSN=25976931
2025-09-12T21:37:19.011Z,1757713039.011 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:37:19.011Z,1757713039.011 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:37:19.012Z,1757713039.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:37:25.060Z,1757713045.060 [NAL9602](INFO): SBD MO Status=2, MOMSN=23235, MT Status=2, MTMSN=0
2025-09-12T21:37:25.061Z,1757713045.061 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:37:36.373Z,1757713056.373 [NAL9602](INFO): SBD MO Status=1, MOMSN=23235, MT Status=0, MTMSN=0
2025-09-12T21:37:36.373Z,1757713056.373 [NAL9602](INFO): No messages in MT queue
2025-09-12T21:38:07.102Z,1757713087.102 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:39:13.340Z,1757713153.340 [DVL_micro](ERROR): Failed to parse:
:BI,+00061,+00838,+00420,+00000,I
2025-09-12T21:39:45.649Z,1757713185.649 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-12T21:40:05.850Z,1757713205.850 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-09-12T21:42:19.658Z,1757713339.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T21:42:19.658Z,1757713339.658 [Default:CheckIn:C.Wait] Stopped
2025-09-12T21:42:19.658Z,1757713339.658 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T21:42:19.659Z,1757713339.659 [Default:CheckIn:D] Running Loop=1
2025-09-12T21:42:20.011Z,1757713340.011 [Default:CheckIn:D] Stopped
2025-09-12T21:42:20.011Z,1757713340.011 [Default:CheckIn:E] Running Loop=1
2025-09-12T21:42:20.422Z,1757713340.422 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  53.647091 min 
2025-09-12T21:42:20.422Z,1757713340.422 [Default:CheckIn:E] Stopped
2025-09-12T21:42:20.422Z,1757713340.422 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T21:42:20.422Z,1757713340.422 [Default:CheckIn] Stopped
2025-09-12T21:42:20.423Z,1757713340.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T21:42:20.424Z,1757713340.424 [Default:CheckIn](INFO): Running loop #9
2025-09-12T21:42:20.424Z,1757713340.424 [Default:CheckIn] Running Loop=9
2025-09-12T21:42:20.424Z,1757713340.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T21:42:20.424Z,1757713340.424 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T21:42:22.409Z,1757713342.409 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214221.00,A,3648.15278,N,12147.22099,W,0.039,205.94,120925,,,D*73
2025-09-12T21:42:22.412Z,1757713342.412 [NAL9602](INFO): GPS fix at 20250912T214221: (36.802546, -121.787016)
2025-09-12T21:42:22.422Z,1757713342.422 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T21:42:22.423Z,1757713342.423 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T21:42:29.898Z,1757713349.898 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250912T211724/Courier0012.lzma
2025-09-12T21:42:40.940Z,1757713360.940 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0012.lzma.bak
2025-09-12T21:42:40.941Z,1757713360.941 [DataOverHttps](INFO): SBD MOMSN=25976942
2025-09-12T21:42:49.564Z,1757713369.564 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:42:49.564Z,1757713369.564 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:43:06.535Z,1757713386.535 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:43:06.535Z,1757713386.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:43:07.482Z,1757713387.482 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20250912T211724/Express0013.lzma
2025-09-12T21:43:18.521Z,1757713398.521 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0013.lzma.bak
2025-09-12T21:43:18.521Z,1757713398.521 [DataOverHttps](INFO): SBD MOMSN=25976951
2025-09-12T21:43:19.874Z,1757713399.874 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:43:19.874Z,1757713399.874 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:43:19.874Z,1757713399.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:43:43.303Z,1757713423.303 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:43:43.303Z,1757713423.303 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:44:39.868Z,1757713479.868 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:44:39.868Z,1757713479.868 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:45:45.311Z,1757713545.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:45:45.311Z,1757713545.311 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:46:17.220Z,1757713577.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:46:17.221Z,1757713577.221 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:46:18.052Z,1757713578.052 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T21:46:35.417Z,1757713595.417 [NAL9602](INFO): SBD MO Status=2, MOMSN=23236, MT Status=2, MTMSN=0
2025-09-12T21:46:35.418Z,1757713595.418 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-12T21:47:24.689Z,1757713644.689 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-12T21:47:55.403Z,1757713675.403 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:48:20.453Z,1757713700.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T21:48:20.453Z,1757713700.453 [Default:CheckIn:C.Wait] Stopped
2025-09-12T21:48:20.453Z,1757713700.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T21:48:20.454Z,1757713700.454 [Default:CheckIn:D] Running Loop=1
2025-09-12T21:48:20.859Z,1757713700.859 [Default:CheckIn:D] Stopped
2025-09-12T21:48:20.859Z,1757713700.859 [Default:CheckIn:E] Running Loop=1
2025-09-12T21:48:21.269Z,1757713701.269 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  59.661226 min 
2025-09-12T21:48:21.269Z,1757713701.269 [Default:CheckIn:E] Stopped
2025-09-12T21:48:21.269Z,1757713701.269 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T21:48:21.269Z,1757713701.269 [Default:CheckIn] Stopped
2025-09-12T21:48:21.269Z,1757713701.269 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T21:48:21.270Z,1757713701.270 [Default:CheckIn](INFO): Running loop #10
2025-09-12T21:48:21.270Z,1757713701.270 [Default:CheckIn] Running Loop=10
2025-09-12T21:48:21.270Z,1757713701.270 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T21:48:21.270Z,1757713701.270 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T21:48:23.273Z,1757713703.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214822.00,A,3648.15557,N,12147.21935,W,0.058,329.28,120925,,,D*73
2025-09-12T21:48:23.276Z,1757713703.276 [NAL9602](INFO): GPS fix at 20250912T214822: (36.802593, -121.786989)
2025-09-12T21:48:23.306Z,1757713703.306 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T21:48:23.306Z,1757713703.306 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T21:48:31.679Z,1757713711.679 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250912T211724/Courier0015.lzma
2025-09-12T21:48:42.720Z,1757713722.720 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0015.lzma.bak
2025-09-12T21:48:42.721Z,1757713722.721 [DataOverHttps](INFO): SBD MOMSN=25976978
2025-09-12T21:48:48.337Z,1757713728.337 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-12T21:48:48.338Z,1757713728.338 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-12T21:48:48.339Z,1757713728.339 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-09-12T21:48:48.343Z,1757713728.343 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-09-12T21:48:53.999Z,1757713733.999 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-12T21:48:54.078Z,1757713734.078 [NAL9602](ERROR): received: 
+CSQ:0

OK236, 2, 0, 0, 0

OK
2025-09-12T21:49:11.428Z,1757713751.428 [DataOverHttps](INFO): Sending 277 bytes from file Logs/20250912T211724/Express0016.lzma
2025-09-12T21:49:22.567Z,1757713762.567 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0016.lzma.bak
2025-09-12T21:49:22.567Z,1757713762.567 [DataOverHttps](INFO): SBD MOMSN=25976987
2025-09-12T21:49:23.924Z,1757713763.924 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:49:23.924Z,1757713763.924 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:49:23.924Z,1757713763.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:52:05.519Z,1757713925.519 [NAL9602](INFO): SBD MO Status=1, MOMSN=23236, MT Status=0, MTMSN=0
2025-09-12T21:52:05.519Z,1757713925.519 [NAL9602](INFO): No messages in MT queue
2025-09-12T21:52:36.216Z,1757713956.216 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:54:24.494Z,1757714064.494 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T21:54:24.495Z,1757714064.495 [Default:CheckIn:C.Wait] Stopped
2025-09-12T21:54:24.495Z,1757714064.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T21:54:24.495Z,1757714064.495 [Default:CheckIn:D] Running Loop=1
2025-09-12T21:54:24.901Z,1757714064.901 [Default:CheckIn:D] Stopped
2025-09-12T21:54:24.901Z,1757714064.901 [Default:CheckIn:E] Running Loop=1
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  65.728589 min 
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn:E] Stopped
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn] Stopped
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn](INFO): Running loop #11
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn] Running Loop=11
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T21:54:25.304Z,1757714065.304 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T21:54:27.312Z,1757714067.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215426.00,A,3648.15342,N,12147.21651,W,0.039,329.28,120925,,,D*72
2025-09-12T21:54:27.315Z,1757714067.315 [NAL9602](INFO): GPS fix at 20250912T215426: (36.802557, -121.786942)
2025-09-12T21:54:27.345Z,1757714067.345 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T21:54:27.345Z,1757714067.345 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T21:54:36.963Z,1757714076.963 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250912T211724/Courier0018.lzma
2025-09-12T21:54:43.084Z,1757714083.084 [NAL9602](INFO): SBD MO Status=1, MOMSN=23237, MT Status=0, MTMSN=0
2025-09-12T21:54:43.084Z,1757714083.084 [NAL9602](INFO): No messages in MT queue
2025-09-12T21:54:48.062Z,1757714088.062 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0018.lzma.bak
2025-09-12T21:54:48.062Z,1757714088.062 [DataOverHttps](INFO): SBD MOMSN=25977003
2025-09-12T21:55:13.891Z,1757714113.891 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T21:55:14.491Z,1757714114.491 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20250912T211724/Express0019.lzma
2025-09-12T21:55:25.536Z,1757714125.536 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0019.lzma.bak
2025-09-12T21:55:25.536Z,1757714125.536 [DataOverHttps](INFO): SBD MOMSN=25977013
2025-09-12T21:55:26.809Z,1757714126.809 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T21:55:26.809Z,1757714126.809 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T21:55:26.809Z,1757714126.809 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T21:56:18.107Z,1757714178.107 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T22:00:27.395Z,1757714427.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:00:27.395Z,1757714427.395 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:00:27.395Z,1757714427.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:00:27.395Z,1757714427.395 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:00:27.803Z,1757714427.803 [Default:CheckIn:D] Stopped
2025-09-12T22:00:27.803Z,1757714427.803 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:00:28.202Z,1757714428.202 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  71.776961 min 
2025-09-12T22:00:28.218Z,1757714428.218 [Default:CheckIn:E] Stopped
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn] Stopped
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn](INFO): Running loop #12
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn] Running Loop=12
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:00:28.219Z,1757714428.219 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:00:30.215Z,1757714430.215 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220029.00,A,3648.15752,N,12147.22048,W,0.097,276.67,120925,,,D*73
2025-09-12T22:00:30.217Z,1757714430.217 [NAL9602](INFO): GPS fix at 20250912T220029: (36.802625, -121.787008)
2025-09-12T22:00:30.228Z,1757714430.228 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:00:30.228Z,1757714430.228 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:00:39.190Z,1757714439.190 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250912T211724/Courier0021.lzma
2025-09-12T22:00:50.232Z,1757714450.232 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0021.lzma.bak
2025-09-12T22:00:50.232Z,1757714450.232 [DataOverHttps](INFO): SBD MOMSN=25977039
2025-09-12T22:01:05.369Z,1757714465.369 [NAL9602](INFO): SBD MO Status=1, MOMSN=23238, MT Status=0, MTMSN=0
2025-09-12T22:01:05.369Z,1757714465.369 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:01:17.512Z,1757714477.512 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250912T211724/Express0022.lzma
2025-09-12T22:01:28.553Z,1757714488.553 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0022.lzma.bak
2025-09-12T22:01:28.553Z,1757714488.553 [DataOverHttps](INFO): SBD MOMSN=25977048
2025-09-12T22:01:30.014Z,1757714490.014 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:01:30.014Z,1757714490.014 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:01:30.014Z,1757714490.014 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:01:36.065Z,1757714496.065 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:06:18.177Z,1757714778.177 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T22:06:30.704Z,1757714790.704 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:06:30.704Z,1757714790.704 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:06:30.704Z,1757714790.704 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:06:30.705Z,1757714790.705 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:06:31.107Z,1757714791.107 [Default:CheckIn:D] Stopped
2025-09-12T22:06:31.107Z,1757714791.107 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:06:31.522Z,1757714791.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  77.832023 min 
2025-09-12T22:06:31.522Z,1757714791.522 [Default:CheckIn:E] Stopped
2025-09-12T22:06:31.522Z,1757714791.522 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:06:31.522Z,1757714791.522 [Default:CheckIn] Stopped
2025-09-12T22:06:31.522Z,1757714791.522 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:06:31.522Z,1757714791.522 [Default:CheckIn](INFO): Running loop #13
2025-09-12T22:06:31.523Z,1757714791.523 [Default:CheckIn] Running Loop=13
2025-09-12T22:06:31.523Z,1757714791.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:06:31.523Z,1757714791.523 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:06:33.520Z,1757714793.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220632.00,A,3648.17441,N,12147.27158,W,2.177,292.27,120925,,,D*7A
2025-09-12T22:06:33.523Z,1757714793.523 [NAL9602](INFO): GPS fix at 20250912T220632: (36.802907, -121.787860)
2025-09-12T22:06:33.535Z,1757714793.535 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:06:33.535Z,1757714793.535 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:06:35.535Z,1757714795.535 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-12T22:07:06.658Z,1757714826.658 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250912T211724/Courier0024.lzma
2025-09-12T22:07:07.659Z,1757714827.659 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-12T22:07:14.441Z,1757714834.441 [NAL9602](INFO): SBD MO Status=1, MOMSN=23239, MT Status=0, MTMSN=0
2025-09-12T22:07:14.441Z,1757714834.441 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:07:15.374Z,1757714835.374 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250912T211724/Courier0024.lzma
2025-09-12T22:07:24.409Z,1757714844.409 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0024.lzma.bak
2025-09-12T22:07:24.409Z,1757714844.409 [DataOverHttps](INFO): SBD MOMSN=25977070
2025-09-12T22:07:45.137Z,1757714865.137 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:07:51.204Z,1757714871.204 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250912T211724/Express0025.lzma
2025-09-12T22:08:02.249Z,1757714882.249 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0025.lzma.bak
2025-09-12T22:08:02.249Z,1757714882.249 [DataOverHttps](INFO): SBD MOMSN=25977082
2025-09-12T22:08:03.736Z,1757714883.736 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:08:03.736Z,1757714883.736 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:08:03.736Z,1757714883.736 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:09:20.580Z,1757714960.580 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-09-12T22:11:09.249Z,1757715069.249 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-12T22:13:04.434Z,1757715184.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:13:04.434Z,1757715184.434 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:13:04.434Z,1757715184.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:13:04.434Z,1757715184.434 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:13:04.822Z,1757715184.822 [Default:CheckIn:D] Stopped
2025-09-12T22:13:04.822Z,1757715184.822 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:13:05.222Z,1757715185.222 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  84.393937 min 
2025-09-12T22:13:05.222Z,1757715185.222 [Default:CheckIn:E] Stopped
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn] Stopped
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn](INFO): Running loop #14
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn] Running Loop=14
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:13:05.223Z,1757715185.223 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:13:07.272Z,1757715187.272 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221306.00,A,3648.17606,N,12147.27650,W,0.058,13.95,120925,,,D*4B
2025-09-12T22:13:07.279Z,1757715187.279 [NAL9602](INFO): GPS fix at 20250912T221306: (36.802934, -121.787942)
2025-09-12T22:13:07.309Z,1757715187.309 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:13:07.310Z,1757715187.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:13:15.438Z,1757715195.438 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250912T211724/Courier0027.lzma
2025-09-12T22:13:25.417Z,1757715205.417 [NAL9602](INFO): SBD MO Status=1, MOMSN=23240, MT Status=0, MTMSN=0
2025-09-12T22:13:25.417Z,1757715205.417 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:13:26.483Z,1757715206.483 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0027.lzma.bak
2025-09-12T22:13:26.483Z,1757715206.483 [DataOverHttps](INFO): SBD MOMSN=25977098
2025-09-12T22:13:53.287Z,1757715233.287 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20250912T211724/Express0028.lzma
2025-09-12T22:13:56.119Z,1757715236.119 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:14:04.328Z,1757715244.328 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0028.lzma.bak
2025-09-12T22:14:04.328Z,1757715244.328 [DataOverHttps](INFO): SBD MOMSN=25977106
2025-09-12T22:14:05.859Z,1757715245.859 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:14:05.859Z,1757715245.859 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:14:05.859Z,1757715245.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:16:18.375Z,1757715378.375 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T22:16:32.215Z,1757715392.215 [RudderServo](ERROR): getPosition uart error serial timeout
2025-09-12T22:16:32.215Z,1757715392.215 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-09-12T22:16:32.215Z,1757715392.215 [RudderServo] Communications Fault, FailCount= 1
2025-09-12T22:16:32.215Z,1757715392.215 [RudderServo](ERROR): Communications Fault
2025-09-12T22:16:32.219Z,1757715392.219 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-09-12T22:16:32.527Z,1757715392.527 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-09-12T22:16:32.527Z,1757715392.527 [RudderServo](INFO): Powering down
2025-09-12T22:16:33.482Z,1757715393.482 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-12T22:16:33.595Z,1757715393.595 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-12T22:16:33.600Z,1757715393.600 [CBIT](INFO): Clearing failed state for component RudderServo
2025-09-12T22:16:33.600Z,1757715393.600 [RudderServo] No Fault, FailCount= 1
2025-09-12T22:19:06.420Z,1757715546.420 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:19:06.420Z,1757715546.420 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:19:06.420Z,1757715546.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:19:06.421Z,1757715546.421 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:19:06.823Z,1757715546.823 [Default:CheckIn:D] Stopped
2025-09-12T22:19:06.823Z,1757715546.823 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:19:07.232Z,1757715547.232 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  90.427287 min 
2025-09-12T22:19:07.232Z,1757715547.232 [Default:CheckIn:E] Stopped
2025-09-12T22:19:07.232Z,1757715547.232 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:19:07.232Z,1757715547.232 [Default:CheckIn] Stopped
2025-09-12T22:19:07.233Z,1757715547.233 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:19:07.233Z,1757715547.233 [Default:CheckIn](INFO): Running loop #15
2025-09-12T22:19:07.233Z,1757715547.233 [Default:CheckIn] Running Loop=15
2025-09-12T22:19:07.233Z,1757715547.233 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:19:07.233Z,1757715547.233 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:19:09.241Z,1757715549.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221908.00,A,3648.18212,N,12147.27786,W,0.136,242.56,120925,,,D*7B
2025-09-12T22:19:09.243Z,1757715549.243 [NAL9602](INFO): GPS fix at 20250912T221908: (36.803035, -121.787964)
2025-09-12T22:19:09.282Z,1757715549.282 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:19:09.283Z,1757715549.283 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:19:17.458Z,1757715557.458 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250912T211724/Courier0030.lzma
2025-09-12T22:19:28.500Z,1757715568.500 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0030.lzma.bak
2025-09-12T22:19:28.500Z,1757715568.500 [DataOverHttps](INFO): SBD MOMSN=25977136
2025-09-12T22:19:31.891Z,1757715571.891 [NAL9602](INFO): SBD MO Status=1, MOMSN=23241, MT Status=0, MTMSN=0
2025-09-12T22:19:31.891Z,1757715571.891 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:19:54.695Z,1757715594.695 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20250912T211724/Express0031.lzma
2025-09-12T22:20:02.284Z,1757715602.284 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:20:05.736Z,1757715605.736 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0031.lzma.bak
2025-09-12T22:20:05.737Z,1757715605.737 [DataOverHttps](INFO): SBD MOMSN=25977143
2025-09-12T22:20:07.041Z,1757715607.041 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:20:07.041Z,1757715607.041 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:20:07.041Z,1757715607.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:20:51.049Z,1757715651.049 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-12T22:25:07.604Z,1757715907.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:25:07.604Z,1757715907.604 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:25:07.604Z,1757715907.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:25:07.604Z,1757715907.604 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:25:08.031Z,1757715908.031 [Default:CheckIn:D] Stopped
2025-09-12T22:25:08.031Z,1757715908.031 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  96.447428 min 
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn:E] Stopped
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn] Stopped
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn](INFO): Running loop #16
2025-09-12T22:25:08.406Z,1757715908.406 [Default:CheckIn] Running Loop=16
2025-09-12T22:25:08.407Z,1757715908.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:25:08.407Z,1757715908.407 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:25:10.419Z,1757715910.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222509.00,A,3648.18520,N,12147.27765,W,0.019,336.61,120925,,,D*74
2025-09-12T22:25:10.421Z,1757715910.421 [NAL9602](INFO): GPS fix at 20250912T222509: (36.803087, -121.787961)
2025-09-12T22:25:10.432Z,1757715910.432 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:25:10.432Z,1757715910.432 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:25:18.452Z,1757715918.452 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250912T211724/Courier0033.lzma
2025-09-12T22:25:29.492Z,1757715929.492 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0033.lzma.bak
2025-09-12T22:25:29.493Z,1757715929.493 [DataOverHttps](INFO): SBD MOMSN=25977159
2025-09-12T22:25:41.537Z,1757715941.537 [NAL9602](INFO): SBD MO Status=1, MOMSN=23242, MT Status=0, MTMSN=0
2025-09-12T22:25:41.537Z,1757715941.537 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:25:55.771Z,1757715955.771 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250912T211724/Express0034.lzma
2025-09-12T22:26:06.812Z,1757715966.812 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0034.lzma.bak
2025-09-12T22:26:06.813Z,1757715966.813 [DataOverHttps](INFO): SBD MOMSN=25977167
2025-09-12T22:26:08.214Z,1757715968.214 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:26:08.214Z,1757715968.214 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:26:08.214Z,1757715968.214 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:26:12.236Z,1757715972.236 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:26:18.713Z,1757715978.713 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T22:26:49.014Z,1757716009.014 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-09-12T22:26:49.014Z,1757716009.014 [DropWeight] Hardware Fault, FailCount= 1
2025-09-12T22:26:49.014Z,1757716009.014 [DropWeight](ERROR): Hardware Fault
2025-09-12T22:26:49.031Z,1757716009.031 [CommandExec](FAULT): Scheduling is paused
2025-09-12T22:26:49.032Z,1757716009.032 [CBIT](INFO): Critical error at 20250912T222649
2025-09-12T22:26:49.035Z,1757716009.035 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-12T22:26:49.035Z,1757716009.035 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-12T22:26:49.442Z,1757716009.442 [CBIT](INFO): Critical error at 20250912T222649
2025-09-12T22:29:49.990Z,1757716189.990 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-12T22:29:50.006Z,1757716190.006 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-12T22:29:50.007Z,1757716190.007 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-09-12T22:29:50.010Z,1757716190.010 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8.
2025-09-12T22:31:08.790Z,1757716268.790 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:31:08.790Z,1757716268.790 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:31:08.791Z,1757716268.791 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:31:08.791Z,1757716268.791 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:31:09.189Z,1757716269.189 [Default:CheckIn:D] Stopped
2025-09-12T22:31:09.190Z,1757716269.190 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:31:09.592Z,1757716269.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  102.466724 min 
2025-09-12T22:31:09.592Z,1757716269.592 [Default:CheckIn:E] Stopped
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn] Stopped
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn](INFO): Running loop #17
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn] Running Loop=17
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:31:09.593Z,1757716269.593 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:31:11.606Z,1757716271.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223110.00,A,3648.18455,N,12147.27598,W,0.039,347.05,120925,,,D*7C
2025-09-12T22:31:11.608Z,1757716271.608 [NAL9602](INFO): GPS fix at 20250912T223110: (36.803076, -121.787933)
2025-09-12T22:31:11.619Z,1757716271.619 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:31:11.619Z,1757716271.619 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:31:19.495Z,1757716279.495 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20250912T211724/Courier0036.lzma
2025-09-12T22:31:28.164Z,1757716288.164 [NAL9602](INFO): SBD MO Status=1, MOMSN=23243, MT Status=0, MTMSN=0
2025-09-12T22:31:28.164Z,1757716288.164 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:31:30.536Z,1757716290.536 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0036.lzma.bak
2025-09-12T22:31:30.536Z,1757716290.536 [DataOverHttps](INFO): SBD MOMSN=25977195
2025-09-12T22:31:57.399Z,1757716317.399 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20250912T211724/Express0037.lzma
2025-09-12T22:31:58.871Z,1757716318.871 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:32:08.440Z,1757716328.440 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0037.lzma.bak
2025-09-12T22:32:08.441Z,1757716328.441 [DataOverHttps](INFO): SBD MOMSN=25977204
2025-09-12T22:32:09.793Z,1757716329.793 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:32:09.793Z,1757716329.793 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:32:09.794Z,1757716329.794 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:36:19.057Z,1757716579.057 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-12T22:37:10.365Z,1757716630.365 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:37:10.365Z,1757716630.365 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:37:10.365Z,1757716630.365 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:37:10.365Z,1757716630.365 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:37:10.780Z,1757716630.780 [Default:CheckIn:D] Stopped
2025-09-12T22:37:10.780Z,1757716630.780 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:37:11.175Z,1757716631.175 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  108.493229 min 
2025-09-12T22:37:11.175Z,1757716631.175 [Default:CheckIn:E] Stopped
2025-09-12T22:37:11.175Z,1757716631.175 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:37:11.175Z,1757716631.175 [Default:CheckIn] Stopped
2025-09-12T22:37:11.175Z,1757716631.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:37:11.175Z,1757716631.175 [Default:CheckIn](INFO): Running loop #18
2025-09-12T22:37:11.176Z,1757716631.176 [Default:CheckIn] Running Loop=18
2025-09-12T22:37:11.176Z,1757716631.176 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:37:11.176Z,1757716631.176 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:37:13.205Z,1757716633.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223712.00,A,3648.16449,N,12147.28174,W,0.525,158.36,120925,,,D*76
2025-09-12T22:37:13.207Z,1757716633.207 [NAL9602](INFO): GPS fix at 20250912T223712: (36.802742, -121.788029)
2025-09-12T22:37:13.217Z,1757716633.217 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:37:13.217Z,1757716633.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:37:23.422Z,1757716643.422 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250912T211724/Courier0039.lzma
2025-09-12T22:37:34.464Z,1757716654.464 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0039.lzma.bak
2025-09-12T22:37:34.465Z,1757716654.465 [DataOverHttps](INFO): SBD MOMSN=25977234
2025-09-12T22:37:53.193Z,1757716673.193 [NAL9602](INFO): SBD MO Status=1, MOMSN=23244, MT Status=0, MTMSN=0
2025-09-12T22:37:53.193Z,1757716673.193 [NAL9602](INFO): No messages in MT queue
2025-09-12T22:38:00.608Z,1757716680.608 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20250912T211724/Express0040.lzma
2025-09-12T22:38:11.648Z,1757716691.648 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Express0040.lzma.bak
2025-09-12T22:38:11.649Z,1757716691.649 [DataOverHttps](INFO): SBD MOMSN=25977241
2025-09-12T22:38:12.990Z,1757716692.990 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:38:12.990Z,1757716692.990 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-12T22:38:12.991Z,1757716692.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-12T22:38:23.880Z,1757716703.880 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-12T22:39:28.524Z,1757716768.524 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-09-12T22:41:49.139Z,1757716909.139 [CBIT](INFO): Clearing failed state for component DropWeight
2025-09-12T22:41:49.139Z,1757716909.139 [DropWeight] No Fault, FailCount= 1
2025-09-12T22:43:13.575Z,1757716993.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-12T22:43:13.576Z,1757716993.576 [Default:CheckIn:C.Wait] Stopped
2025-09-12T22:43:13.576Z,1757716993.576 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-12T22:43:13.576Z,1757716993.576 [Default:CheckIn:D] Running Loop=1
2025-09-12T22:43:13.982Z,1757716993.982 [Default:CheckIn:D] Stopped
2025-09-12T22:43:13.982Z,1757716993.982 [Default:CheckIn:E] Running Loop=1
2025-09-12T22:43:14.445Z,1757716994.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  114.546338 min 
2025-09-12T22:43:14.445Z,1757716994.445 [Default:CheckIn:E] Stopped
2025-09-12T22:43:14.446Z,1757716994.446 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-12T22:43:14.446Z,1757716994.446 [Default:CheckIn] Stopped
2025-09-12T22:43:14.446Z,1757716994.446 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:43:14.446Z,1757716994.446 [Default:CheckIn](INFO): Running loop #19
2025-09-12T22:43:14.446Z,1757716994.446 [Default:CheckIn] Running Loop=19
2025-09-12T22:43:14.447Z,1757716994.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-12T22:43:14.447Z,1757716994.447 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-12T22:43:16.382Z,1757716996.382 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224315.00,A,3648.16463,N,12147.27917,W,0.369,84.17,120925,,,A*40
2025-09-12T22:43:16.385Z,1757716996.385 [NAL9602](INFO): GPS fix at 20250912T224315: (36.802744, -121.787986)
2025-09-12T22:43:16.395Z,1757716996.395 [Default:CheckIn:Read_GPS] Stopped
2025-09-12T22:43:16.395Z,1757716996.395 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-12T22:43:24.186Z,1757717004.186 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250912T211724/Courier0042.lzma
2025-09-12T22:43:47.275Z,1757717027.275 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-12T22:43:58.514Z,1757717038.514 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250912T211724/Courier0042.lzma
2025-09-12T22:43:59.517Z,1757717039.517 [DataOverHttps](INFO): Moved sent file to Logs/20250912T211724/Courier0042.lzma.bak
2025-09-12T22:43:59.517Z,1757717039.517 [DataOverHttps](INFO): SBD MOMSN=25977260
2025-09-12T22:44:05.555Z,1757717045.555 [CommandExec](IMPORTANT): got command quit
2025-09-12T22:44:06.559Z,1757717046.559 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:06.559Z,1757717046.559 [CommandExec](INFO): Uninitializing the command executive.
2025-09-12T22:44:06.559Z,1757717046.559 [CommandExec](INFO): Uninitializing the command scheduler.
2025-09-12T22:44:06.560Z,1757717046.560 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:06.578Z,1757717046.578 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-09-12T22:44:06.579Z,1757717046.579 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-09-12T22:44:06.579Z,1757717046.579 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:06.579Z,1757717046.579 [NavChartDb](INFO): Join timeout helper Thread ID is 3201
2025-09-12T22:44:06.715Z,1757717046.715 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:06.715Z,1757717046.715 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:06.718Z,1757717046.718 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-09-12T22:44:06.719Z,1757717046.719 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:06.719Z,1757717046.719 [Radio_Surface](INFO): Join timeout helper Thread ID is 3202
2025-09-12T22:44:06.879Z,1757717046.879 [Radio_Surface](INFO): Powering down
2025-09-12T22:44:06.880Z,1757717046.880 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:06.880Z,1757717046.880 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:06.881Z,1757717046.881 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-09-12T22:44:06.881Z,1757717046.881 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:06.881Z,1757717046.881 [Onboard](INFO): Join timeout helper Thread ID is 3203
2025-09-12T22:44:11.487Z,1757717051.487 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:11.487Z,1757717051.487 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:11.488Z,1757717051.488 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-09-12T22:44:11.488Z,1757717051.488 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:11.489Z,1757717051.489 [DataOverHttps](INFO): Join timeout helper Thread ID is 3204
2025-09-12T22:44:12.115Z,1757717052.115 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:12.119Z,1757717052.119 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.123Z,1757717052.123 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-09-12T22:44:12.123Z,1757717052.123 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.123Z,1757717052.123 [DAT](INFO): Join timeout helper Thread ID is 3205
2025-09-12T22:44:12.351Z,1757717052.351 [DAT](INFO): Powering down
2025-09-12T22:44:12.423Z,1757717052.423 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:12.423Z,1757717052.423 [DAT](INFO): Powering down
2025-09-12T22:44:12.424Z,1757717052.424 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.431Z,1757717052.431 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-09-12T22:44:12.432Z,1757717052.432 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.432Z,1757717052.432 [BackseatComponent](INFO): Join timeout helper Thread ID is 3206
2025-09-12T22:44:12.611Z,1757717052.611 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:12.611Z,1757717052.611 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.612Z,1757717052.612 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-09-12T22:44:12.612Z,1757717052.612 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.613Z,1757717052.613 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3207
2025-09-12T22:44:12.911Z,1757717052.911 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:12.911Z,1757717052.911 [WetLabsBB2FL](INFO): Powering down
2025-09-12T22:44:12.912Z,1757717052.912 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.915Z,1757717052.915 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-09-12T22:44:12.915Z,1757717052.915 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:12.916Z,1757717052.916 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3208
2025-09-12T22:44:13.199Z,1757717053.199 [CTD_Seabird](INFO): Powering down
2025-09-12T22:44:13.211Z,1757717053.211 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:13.211Z,1757717053.211 [CTD_Seabird](INFO): Powering down
2025-09-12T22:44:13.223Z,1757717053.223 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.231Z,1757717053.231 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-09-12T22:44:13.231Z,1757717053.231 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.232Z,1757717053.232 [logger](INFO): Join timeout helper Thread ID is 3209
2025-09-12T22:44:13.277Z,1757717053.277 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:13.277Z,1757717053.277 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.295Z,1757717053.295 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-09-12T22:44:13.295Z,1757717053.295 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.296Z,1757717053.296 [CommandLine](INFO): Join timeout helper Thread ID is 3210
2025-09-12T22:44:13.319Z,1757717053.319 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:13.319Z,1757717053.319 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.323Z,1757717053.323 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-09-12T22:44:13.323Z,1757717053.323 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.324Z,1757717053.324 [CommandExec](INFO): Join timeout helper Thread ID is 3211
2025-09-12T22:44:13.335Z,1757717053.335 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-09-12T22:44:13.335Z,1757717053.335 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.336Z,1757717053.336 [controlThread](INFO): Join timeout helper Thread ID is 3212
2025-09-12T22:44:13.395Z,1757717053.395 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-12T22:44:13.395Z,1757717053.395 [controlThread](DEBUG): Uninitializing ControlThread
2025-09-12T22:44:13.396Z,1757717053.396 [AHRS_M2](INFO): Powering down
2025-09-12T22:44:13.567Z,1757717053.567 [DVL_micro](INFO): Powering down
2025-09-12T22:44:13.568Z,1757717053.568 [NAL9602](INFO): Powering down
2025-09-12T22:44:13.569Z,1757717053.569 [Sonardyne_Nano](INFO): Powering down
2025-09-12T22:44:13.787Z,1757717053.787 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-09-12T22:44:13.788Z,1757717053.788 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-09-12T22:44:13.788Z,1757717053.788 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-09-12T22:44:13.789Z,1757717053.789 [MissionManager](INFO): Uninitializing Mission Default
2025-09-12T22:44:13.789Z,1757717053.789 [Default] Stopped
2025-09-12T22:44:13.789Z,1757717053.789 [Default](DEBUG): Aggregate::uninitialize Default
2025-09-12T22:44:13.789Z,1757717053.789 [Default:B.GoToSurface] Stopped
2025-09-12T22:44:13.789Z,1757717053.789 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-12T22:44:13.789Z,1757717053.789 [Default:CheckIn] Stopped
2025-09-12T22:44:13.789Z,1757717053.789 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-12T22:44:13.789Z,1757717053.789 [Default:CheckIn:Read_Iridium] Stopped
2025-09-12T22:44:13.803Z,1757717053.803 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-09-12T22:44:13.803Z,1757717053.803 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-09-12T22:44:13.803Z,1757717053.803 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-09-12T22:44:13.804Z,1757717053.804 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-09-12T22:44:13.804Z,1757717053.804 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-09-12T22:44:13.804Z,1757717053.804 [BuoyancyServo](INFO): Powering down
2025-09-12T22:44:13.819Z,1757717053.819 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-09-12T22:44:13.819Z,1757717053.819 [ElevatorServo](INFO): Powering down
2025-09-12T22:44:13.820Z,1757717053.820 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-12T22:44:13.820Z,1757717053.820 [MassServo](INFO): Powering down
2025-09-12T22:44:13.821Z,1757717053.821 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-09-12T22:44:13.821Z,1757717053.821 [RudderServo](INFO): Powering down
2025-09-12T22:44:13.821Z,1757717053.821 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-09-12T22:44:13.822Z,1757717053.822 [ThrusterHE](INFO): Powering down
2025-09-12T22:44:13.823Z,1757717053.823 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-09-12T22:44:13.823Z,1757717053.823 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-09-12T22:44:13.823Z,1757717053.823 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-09-12T22:44:13.823Z,1757717053.823 [CBIT](DEBUG): Powering off loads.
2025-09-12T22:44:13.834Z,1757717053.834 [CBIT](DEBUG): Disabling WDT.
2025-09-12T22:44:13.847Z,1757717053.847 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-12T22:44:13.847Z,1757717053.847 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-12T22:44:13.848Z,1757717053.848 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.889Z,1757717053.889 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-12T22:44:13.889Z,1757717053.889 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-12T22:44:13.928Z,1757717053.928 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:13.937Z,1757717053.937 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.002Z,1757717054.002 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.005Z,1757717054.005 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.013Z,1757717054.013 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.025Z,1757717054.025 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.107Z,1757717054.107 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.223Z,1757717054.223 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-12T22:44:14.398Z,1757717054.398 [logger ThreadHandler](INFO): Thread cancelled.