2025-01-28T16:41:18.053Z,1738082478.053 [Supervisor](DEBUG): Initializing supervisor.
2025-01-28T16:41:18.057Z,1738082478.057 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-01-28T16:41:18.058Z,1738082478.058 [SyncHandler](INFO): Protected caller Thread ID is 836
2025-01-28T16:41:18.058Z,1738082478.058 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-01-28T16:41:18.059Z,1738082478.059 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-01-28T16:41:18.059Z,1738082478.059 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2025-01-28T16:41:18.063Z,1738082478.063 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-01-28T16:41:18.081Z,1738082478.081 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-01-28T16:41:18.082Z,1738082478.082 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-01-28T16:41:18.082Z,1738082478.082 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2025-01-28T16:41:18.086Z,1738082478.086 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-01-28T16:41:18.087Z,1738082478.087 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-01-28T16:41:18.087Z,1738082478.087 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2025-01-28T16:41:18.090Z,1738082478.090 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-01-28T16:41:18.090Z,1738082478.090 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-01-28T16:41:18.091Z,1738082478.091 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2025-01-28T16:41:18.094Z,1738082478.094 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-01-28T16:41:18.095Z,1738082478.095 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-01-28T16:41:18.098Z,1738082478.098 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-01-28T16:41:18.442Z,1738082478.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-01-28T16:41:18.443Z,1738082478.443 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-01-28T16:41:18.534Z,1738082478.534 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-01-28T16:41:18.650Z,1738082478.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-01-28T16:41:18.651Z,1738082478.651 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-01-28T16:41:18.789Z,1738082478.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-01-28T16:41:18.790Z,1738082478.790 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-01-28T16:41:19.137Z,1738082479.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-01-28T16:41:19.139Z,1738082479.139 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-01-28T16:41:19.244Z,1738082479.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-01-28T16:41:19.245Z,1738082479.245 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-01-28T16:41:19.659Z,1738082479.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-01-28T16:41:19.661Z,1738082479.661 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-01-28T16:41:19.879Z,1738082479.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-01-28T16:41:19.880Z,1738082479.880 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-01-28T16:41:19.969Z,1738082479.969 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-01-28T16:41:20.210Z,1738082480.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-01-28T16:41:20.210Z,1738082480.210 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-01-28T16:41:20.435Z,1738082480.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-01-28T16:41:20.437Z,1738082480.437 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-01-28T16:41:20.961Z,1738082480.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-01-28T16:41:20.962Z,1738082480.962 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-01-28T16:41:21.153Z,1738082481.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-01-28T16:41:21.155Z,1738082481.155 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-01-28T16:41:21.446Z,1738082481.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-01-28T16:41:21.447Z,1738082481.447 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-01-28T16:41:22.332Z,1738082482.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-01-28T16:41:22.334Z,1738082482.334 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-01-28T16:41:22.900Z,1738082482.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-01-28T16:41:22.902Z,1738082482.902 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-01-28T16:41:22.904Z,1738082482.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-01-28T16:41:23.011Z,1738082483.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-01-28T16:41:23.135Z,1738082483.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-01-28T16:41:23.228Z,1738082483.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-01-28T16:41:23.344Z,1738082483.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-01-28T16:41:23.439Z,1738082483.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-01-28T16:41:23.706Z,1738082483.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-01-28T16:41:23.706Z,1738082483.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-01-28T16:41:23.824Z,1738082483.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-01-28T16:41:23.930Z,1738082483.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-01-28T16:41:24.092Z,1738082484.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-01-28T16:41:24.186Z,1738082484.186 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-01-28T16:41:24.207Z,1738082484.207 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-01-28T16:41:24.373Z,1738082484.373 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-01-28T16:41:24.374Z,1738082484.374 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-01-28T16:41:24.443Z,1738082484.443 [VerticalControl](DEBUG): Construct VerticalControl.
2025-01-28T16:41:24.502Z,1738082484.502 [VerticalControl] Loaded
2025-01-28T16:41:24.502Z,1738082484.502 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-01-28T16:41:24.505Z,1738082484.505 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-01-28T16:41:24.553Z,1738082484.553 [HorizontalControl] Loaded
2025-01-28T16:41:24.553Z,1738082484.553 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-01-28T16:41:24.555Z,1738082484.555 [SpeedControl](DEBUG): Construct SpeedControl.
2025-01-28T16:41:24.558Z,1738082484.558 [SpeedControl] Loaded
2025-01-28T16:41:24.558Z,1738082484.558 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-01-28T16:41:24.561Z,1738082484.561 [LoopControl](DEBUG): Construct LoopControl.
2025-01-28T16:41:24.561Z,1738082484.561 [LoopControl] Loaded
2025-01-28T16:41:24.562Z,1738082484.562 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-01-28T16:41:24.562Z,1738082484.562 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-01-28T16:41:24.563Z,1738082484.563 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-01-28T16:41:24.647Z,1738082484.647 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-01-28T16:41:24.648Z,1738082484.648 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-01-28T16:41:25.467Z,1738082485.467 [AHRS_M2] Loaded
2025-01-28T16:41:25.467Z,1738082485.467 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-01-28T16:41:25.720Z,1738082485.720 [BackseatComponent] Loaded
2025-01-28T16:41:25.720Z,1738082485.720 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-01-28T16:41:25.721Z,1738082485.721 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2025-01-28T16:41:25.721Z,1738082485.721 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 919
2025-01-28T16:41:25.724Z,1738082485.724 [LcmUniversalReporter] Loaded
2025-01-28T16:41:25.724Z,1738082485.724 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-01-28T16:41:26.536Z,1738082486.536 [BPC1] Loaded
2025-01-28T16:41:26.536Z,1738082486.536 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-01-28T16:41:26.631Z,1738082486.631 [DAT] Loaded
2025-01-28T16:41:26.631Z,1738082486.631 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-01-28T16:41:26.632Z,1738082486.632 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2025-01-28T16:41:26.633Z,1738082486.633 [DAT ThreadHandler](INFO): Protected caller Thread ID is 920
2025-01-28T16:41:26.786Z,1738082486.786 [DataOverHttps] Loaded
2025-01-28T16:41:26.786Z,1738082486.786 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-01-28T16:41:26.787Z,1738082486.787 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2025-01-28T16:41:26.788Z,1738082486.788 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921
2025-01-28T16:41:26.811Z,1738082486.811 [Depth_Keller] Loaded
2025-01-28T16:41:26.811Z,1738082486.811 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-01-28T16:41:26.816Z,1738082486.816 [DropWeight] Loaded
2025-01-28T16:41:26.817Z,1738082486.817 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-01-28T16:41:26.881Z,1738082486.881 [NAL9602] Loaded
2025-01-28T16:41:26.881Z,1738082486.881 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-01-28T16:41:26.910Z,1738082486.910 [Onboard] Loaded
2025-01-28T16:41:26.910Z,1738082486.910 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-01-28T16:41:26.911Z,1738082486.911 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2025-01-28T16:41:26.912Z,1738082486.912 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922
2025-01-28T16:41:26.925Z,1738082486.925 [Power24vConverter] Loaded
2025-01-28T16:41:26.926Z,1738082486.926 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-01-28T16:41:26.941Z,1738082486.941 [Radio_Surface] Loaded
2025-01-28T16:41:26.942Z,1738082486.942 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-01-28T16:41:26.942Z,1738082486.942 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2025-01-28T16:41:26.943Z,1738082486.943 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923
2025-01-28T16:41:26.958Z,1738082486.958 [Sonardyne_Nano] Loaded
2025-01-28T16:41:26.959Z,1738082486.959 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-01-28T16:41:26.982Z,1738082486.982 [Waterlinked] Loaded
2025-01-28T16:41:26.982Z,1738082486.982 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-01-28T16:41:26.982Z,1738082486.982 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-01-28T16:41:26.983Z,1738082486.983 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-01-28T16:41:26.998Z,1738082486.998 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-01-28T16:41:27.000Z,1738082487.000 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-01-28T16:41:27.042Z,1738082487.042 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-01-28T16:41:27.043Z,1738082487.043 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-01-28T16:41:27.214Z,1738082487.214 [SBIT](DEBUG): Construct Startup Built In Test.
2025-01-28T16:41:27.223Z,1738082487.223 [SBIT] Loaded
2025-01-28T16:41:27.223Z,1738082487.223 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-01-28T16:41:27.226Z,1738082487.226 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-01-28T16:41:27.238Z,1738082487.238 [IBIT] Loaded
2025-01-28T16:41:27.238Z,1738082487.238 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-01-28T16:41:27.244Z,1738082487.244 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-01-28T16:41:27.347Z,1738082487.347 [CBIT] Loaded
2025-01-28T16:41:27.348Z,1738082487.348 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-01-28T16:41:27.348Z,1738082487.348 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-01-28T16:41:27.349Z,1738082487.349 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-01-28T16:41:27.756Z,1738082487.756 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-01-28T16:41:27.757Z,1738082487.757 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-01-28T16:41:27.827Z,1738082487.827 [DepthRateCalculator] Loaded
2025-01-28T16:41:27.827Z,1738082487.827 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-01-28T16:41:27.832Z,1738082487.832 [PitchRateCalculator] Loaded
2025-01-28T16:41:27.832Z,1738082487.832 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-01-28T16:41:27.843Z,1738082487.843 [SpeedCalculator] Loaded
2025-01-28T16:41:27.843Z,1738082487.843 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-01-28T16:41:27.848Z,1738082487.848 [YawRateCalculator] Loaded
2025-01-28T16:41:27.848Z,1738082487.848 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-01-28T16:41:27.867Z,1738082487.867 [ElevatorOffsetCalculator] Loaded
2025-01-28T16:41:27.867Z,1738082487.867 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-01-28T16:41:27.868Z,1738082487.868 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-01-28T16:41:27.868Z,1738082487.868 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-01-28T16:41:28.078Z,1738082488.078 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-01-28T16:41:28.079Z,1738082488.079 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-01-28T16:41:28.214Z,1738082488.214 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-01-28T16:41:28.215Z,1738082488.215 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-01-28T16:41:28.228Z,1738082488.228 [NavChart] Loaded
2025-01-28T16:41:28.229Z,1738082488.229 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-01-28T16:41:28.234Z,1738082488.234 [UniversalFixResidualReporter] Loaded
2025-01-28T16:41:28.235Z,1738082488.235 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-01-28T16:41:28.235Z,1738082488.235 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-01-28T16:41:28.236Z,1738082488.236 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-01-28T16:41:28.397Z,1738082488.397 [BuoyancyServo] Loaded
2025-01-28T16:41:28.397Z,1738082488.397 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-01-28T16:41:28.419Z,1738082488.419 [ElevatorServo] Loaded
2025-01-28T16:41:28.420Z,1738082488.420 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-01-28T16:41:28.441Z,1738082488.441 [MassServo] Loaded
2025-01-28T16:41:28.441Z,1738082488.441 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-01-28T16:41:28.462Z,1738082488.462 [RudderServo] Loaded
2025-01-28T16:41:28.462Z,1738082488.462 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-01-28T16:41:28.478Z,1738082488.478 [ThrusterHE] Loaded
2025-01-28T16:41:28.479Z,1738082488.479 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-01-28T16:41:28.479Z,1738082488.479 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-01-28T16:41:28.480Z,1738082488.480 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-01-28T16:41:28.705Z,1738082488.705 [CTD_Seabird] Loaded
2025-01-28T16:41:28.705Z,1738082488.705 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-01-28T16:41:28.706Z,1738082488.706 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BEF4E0
2025-01-28T16:41:28.707Z,1738082488.707 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2025-01-28T16:41:28.742Z,1738082488.742 [ESPComponent] Loaded
2025-01-28T16:41:28.743Z,1738082488.743 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-01-28T16:41:28.764Z,1738082488.764 [PAR_Licor] Loaded
2025-01-28T16:41:28.765Z,1738082488.765 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-01-28T16:41:28.810Z,1738082488.810 [WetLabsBB2FL] Loaded
2025-01-28T16:41:28.810Z,1738082488.810 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-01-28T16:41:28.811Z,1738082488.811 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C1F4E0
2025-01-28T16:41:28.812Z,1738082488.812 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2025-01-28T16:41:28.812Z,1738082488.812 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-01-28T16:41:28.819Z,1738082488.819 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-01-28T16:41:28.822Z,1738082488.822 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-01-28T16:41:28.833Z,1738082488.833 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-01-28T16:41:28.834Z,1738082488.834 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C4F4E0
2025-01-28T16:41:28.834Z,1738082488.834 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2025-01-28T16:41:28.839Z,1738082488.839 [Supervisor](INFO): Main Thread ID is 827
2025-01-28T16:41:28.839Z,1738082488.839 [Supervisor](DEBUG): Running supervisor.
2025-01-28T16:41:28.839Z,1738082488.839 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2025-01-28T16:41:28.840Z,1738082488.840 [CommandExec](INFO): Initializing the command executive.
2025-01-28T16:41:28.841Z,1738082488.841 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2025-01-28T16:41:28.843Z,1738082488.843 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2025-01-28T16:41:28.844Z,1738082488.844 [controlThread](DEBUG): Initializing ControlThread
2025-01-28T16:41:28.845Z,1738082488.845 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-01-28T16:41:28.846Z,1738082488.846 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-01-28T16:41:28.847Z,1738082488.847 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-01-28T16:41:28.847Z,1738082488.847 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-01-28T16:41:28.854Z,1738082488.854 [SBIT](INFO): Initialize SBIT Component.
2025-01-28T16:41:28.855Z,1738082488.855 [SBIT](IMPORTANT): git: 2025-01-28
2025-01-28T16:41:28.855Z,1738082488.855 [SBIT](INFO): git hash: 661c520267d8cf9c944d8d885edbb2392118b23e
2025-01-28T16:41:28.855Z,1738082488.855 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-01-28T16:41:28.856Z,1738082488.856 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2025-01-28T16:41:28.858Z,1738082488.858 [SBIT](INFO): Beginning SBIT in 73.000000 seconds.
2025-01-28T16:41:28.858Z,1738082488.858 [IBIT](INFO): Initialize IBIT Component.
2025-01-28T16:41:28.859Z,1738082488.859 [CBIT](DEBUG): Initialize CBIT Component.
2025-01-28T16:41:28.860Z,1738082488.860 [logger ThreadHandler](INFO): Handler Thread ID is 930
2025-01-28T16:41:28.872Z,1738082488.872 [CBIT](DEBUG): Initialized mux pins.
2025-01-28T16:41:28.872Z,1738082488.872 [CBIT](DEBUG): Initializing the watchdog timer.
2025-01-28T16:41:28.880Z,1738082488.880 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 931
2025-01-28T16:41:28.892Z,1738082488.892 [DAT ThreadHandler](INFO): Handler Thread ID is 932
2025-01-28T16:41:28.893Z,1738082488.893 [DAT](INFO): Powering up
2025-01-28T16:41:28.893Z,1738082488.893 [DAT](DEBUG): Initializing DAT.
2025-01-28T16:41:28.896Z,1738082488.896 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-01-28T16:41:28.896Z,1738082488.896 [CBIT](DEBUG): Initializing heartbeat.
2025-01-28T16:41:28.898Z,1738082488.898 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2025-01-28T16:41:28.900Z,1738082488.900 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-01-28T16:41:28.909Z,1738082488.909 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2025-01-28T16:41:28.926Z,1738082488.926 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2025-01-28T16:41:28.945Z,1738082488.945 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937
2025-01-28T16:41:28.945Z,1738082488.945 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-01-28T16:41:28.949Z,1738082488.949 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939
2025-01-28T16:41:28.952Z,1738082488.952 [WetLabsBB2FL](INFO): Powering up
2025-01-28T16:41:28.953Z,1738082488.953 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2025-01-28T16:41:28.960Z,1738082488.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-01-28T16:41:28.960Z,1738082488.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-01-28T16:41:28.961Z,1738082488.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-01-28T16:41:28.961Z,1738082488.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-01-28T16:41:28.961Z,1738082488.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-01-28T16:41:28.961Z,1738082488.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-01-28T16:41:28.961Z,1738082488.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-01-28T16:41:28.961Z,1738082488.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-01-28T16:41:28.968Z,1738082488.968 [CBIT](DEBUG): Deactivating GF circuits.
2025-01-28T16:41:28.968Z,1738082488.968 [CBIT](DEBUG): Deactivating emergency mode.
2025-01-28T16:41:29.008Z,1738082489.008 [CBIT](DEBUG): Backplane powered.
2025-01-28T16:41:29.008Z,1738082489.008 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-01-28T16:41:29.009Z,1738082489.009 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-01-28T16:41:29.009Z,1738082489.009 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-01-28T16:41:29.009Z,1738082489.009 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-01-28T16:41:29.010Z,1738082489.010 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-01-28T16:41:29.011Z,1738082489.011 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-01-28T16:41:29.011Z,1738082489.011 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-01-28T16:41:29.016Z,1738082489.016 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-01-28T16:41:29.017Z,1738082489.017 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-01-28T16:41:29.017Z,1738082489.017 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-01-28T16:41:29.029Z,1738082489.029 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-01-28T16:41:29.092Z,1738082489.092 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-01-28T16:41:29.097Z,1738082489.097 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-01-28T16:41:29.122Z,1738082489.122 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


2025-01-28T16:41:29.123Z,1738082489.123 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-01-28T16:41:29.123Z,1738082489.123 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-01-28T16:41:29.152Z,1738082489.152 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-01-28T16:41:29.332Z,1738082489.332 [Radio_Surface](INFO): Powering up
2025-01-28T16:41:29.406Z,1738082489.406 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-01-28T16:41:29.432Z,1738082489.432 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-01-28T16:41:29.433Z,1738082489.433 [Default:A.Wait](DEBUG): Construct Wait.
2025-01-28T16:41:29.435Z,1738082489.435 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-01-28T16:41:29.477Z,1738082489.477 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-01-28T16:41:29.480Z,1738082489.480 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-01-28T16:41:29.502Z,1738082489.502 [Default:E.Execute](DEBUG): Construct Execute.
2025-01-28T16:41:29.505Z,1738082489.505 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

    syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-01-28T16:41:29.516Z,1738082489.516 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2025-01-28T16:41:29.524Z,1738082489.524 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-01-28T16:41:29.693Z,1738082489.693 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2025-01-28T16:41:29.694Z,1738082489.694 [Power24vConverter](INFO): Powering up.
2025-01-28T16:41:29.695Z,1738082489.695 [Sonardyne_Nano](INFO): Initializing.
2025-01-28T16:41:29.734Z,1738082489.734 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-01-28T16:41:29.762Z,1738082489.762 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-01-28T16:41:29.768Z,1738082489.768 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-01-28T16:41:29.769Z,1738082489.769 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-01-28T16:41:29.777Z,1738082489.777 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-01-28T16:41:29.778Z,1738082489.778 [MassServo](DEBUG): Initializing EZServoServo.
2025-01-28T16:41:29.796Z,1738082489.796 [MassServo](DEBUG): Initializing MassServo.
2025-01-28T16:41:29.797Z,1738082489.797 [RudderServo](DEBUG): Initializing EZServoServo.
2025-01-28T16:41:29.804Z,1738082489.804 [RudderServo](DEBUG): Initializing RudderServo.
2025-01-28T16:41:29.805Z,1738082489.805 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-01-28T16:41:29.812Z,1738082489.812 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-01-28T16:41:31.064Z,1738082491.064 [WetLabsBB2FL](INFO): Powering down
2025-01-28T16:41:31.955Z,1738082491.955 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-01-28T16:41:33.193Z,1738082493.193 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-01-28T16:41:35.421Z,1738082495.421 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-01-28T16:41:41.745Z,1738082501.745 [DAT](INFO): DAT read: 

2025-01-28T16:41:41.746Z,1738082501.746 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-01-28T16:41:43.509Z,1738082503.509 [DAT](INFO): DAT read: MF Frequency Band

2025-01-28T16:41:43.510Z,1738082503.510 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-01-28T16:41:43.511Z,1738082503.511 [DAT](INFO): DAT read: Jan 28 2025 16:41:38 

2025-01-28T16:41:44.517Z,1738082504.517 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-01-28T16:41:44.518Z,1738082504.518 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-01-28T16:41:44.518Z,1738082504.518 [DAT](INFO): commRate: 800
2025-01-28T16:41:46.585Z,1738082506.585 [DAT](INFO): entering command mode
2025-01-28T16:41:46.784Z,1738082506.784 [DAT](INFO): DAT read: 

2025-01-28T16:41:46.785Z,1738082506.785 [DAT](INFO): DAT read: user:1>

2025-01-28T16:41:46.785Z,1738082506.785 [DAT](INFO): setting verbose to 3
2025-01-28T16:41:47.037Z,1738082507.037 [DAT](INFO): DAT read: user:1>

2025-01-28T16:41:47.038Z,1738082507.038 [DAT](INFO): DAT read: Verbose         | 3           

2025-01-28T16:41:47.038Z,1738082507.038 [DAT](INFO): set verbose to 3
2025-01-28T16:41:47.038Z,1738082507.038 [DAT](INFO): setting DatVerbose to 27440
2025-01-28T16:41:47.288Z,1738082507.288 [DAT](INFO): DAT read: user:2>

2025-01-28T16:41:47.289Z,1738082507.289 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-01-28T16:41:47.290Z,1738082507.290 [DAT](INFO): set DatVerbose to 27440
2025-01-28T16:41:47.290Z,1738082507.290 [DAT](INFO): setting transmit power to 8
2025-01-28T16:41:47.541Z,1738082507.541 [DAT](INFO): DAT read: user:3>

2025-01-28T16:41:47.541Z,1738082507.541 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-01-28T16:41:47.542Z,1738082507.542 [DAT](INFO): set transmit power to 8
2025-01-28T16:41:47.542Z,1738082507.542 [DAT](INFO): setting local address to 1
2025-01-28T16:41:47.793Z,1738082507.793 [DAT](INFO): DAT read: user:4>

2025-01-28T16:41:47.793Z,1738082507.793 [DAT](INFO): DAT read: LocalAddr       | 1           

2025-01-28T16:41:47.794Z,1738082507.794 [DAT](INFO): set local address to 1
2025-01-28T16:41:47.795Z,1738082507.795 [DAT](INFO): Setting time to: 16:41:47 And date to:1/28/2025
2025-01-28T16:41:48.045Z,1738082508.045 [DAT](INFO): DAT read: user:5>

2025-01-28T16:41:48.045Z,1738082508.045 [DAT](INFO): DAT read: Tue Jan 28, 2025  16:41:47  

2025-01-28T16:41:48.046Z,1738082508.046 [DAT](INFO): Local DAT time set to Tue Jan 28, 2025  16:41:47  

2025-01-28T16:41:57.012Z,1738082517.012 [NAL9602](INFO): Powering up NAL9602
2025-01-28T16:42:00.283Z,1738082520.283 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI,    +0,    0,    +0,V
2025-01-28T16:42:03.533Z,1738082523.533 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019555386, 0.0, +.0,1475.0,  0
2025-01-28T16:42:03.955Z,1738082523.955 [Waterlinked](INFO): Powering down
2025-01-28T16:42:07.911Z,1738082527.911 [NAL9602](INFO): NAL9602 initialized
2025-01-28T16:42:42.288Z,1738082562.288 [SBIT](IMPORTANT): Beginning Startup BIT
2025-01-28T16:42:42.292Z,1738082562.292 [CBIT](IMPORTANT): Beginning ground fault scan
2025-01-28T16:42:43.861Z,1738082563.861 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:42:53.720Z,1738082573.720 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000278
CHAN A1 (24V): 0.000136
CHAN A2 (12V): -0.000297
CHAN A3 (5V): -0.001195
CHAN B0 (3.3V): 0.000096
CHAN B1 (3.15aV): 0.000027
CHAN B2 (3.15bV): 0.000024
CHAN B3 (GND): -0.000238
OPEN: 0.006371
Full Scale: +/- 1 mA
2025-01-28T16:42:55.330Z,1738082575.330 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.774947
2025-01-28T16:42:58.001Z,1738082578.001 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:43:12.141Z,1738082592.141 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:43:21.853Z,1738082601.853 [NAL9602](INFO): SBD MO Status=0, MOMSN=20612, MT Status=0, MTMSN=0
2025-01-28T16:43:21.853Z,1738082601.853 [NAL9602](INFO): No messages in MT queue
2025-01-28T16:43:23.059Z,1738082603.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164322.00,A,3648.14203,N,12147.22975,W,0.447,275.60,280125,,,A*76
2025-01-28T16:43:23.063Z,1738082603.063 [NAL9602](INFO): GPS fix at 20250128T164322: (36.802367, -121.787162)
2025-01-28T16:43:25.886Z,1738082605.886 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:43:36.009Z,1738082616.009 [SBIT](CRITICAL): SBIT FAILED
2025-01-28T16:43:36.009Z,1738082616.009 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.100000 count_per_second;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 25.000000 count_per_second;

2025-01-28T16:43:36.010Z,1738082616.010 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_diatoms 30.000000 count_per_milliliter;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_dinoflagellates 30.000000 count_per_milliliter;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): Normal all _.predict_plankton_data_pct_dinos_sqerr;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=0 bool;

2025-01-28T16:43:36.011Z,1738082616.011 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.564728 cubic_centimeter;

2025-01-28T16:43:36.012Z,1738082616.012 [SBIT](IMPORTANT): VerticalControl.massDefault=13.432641 millimeter;

2025-01-28T16:43:36.014Z,1738082616.014 [CommandExec](FAULT): Scheduling is paused
2025-01-28T16:43:36.014Z,1738082616.014 [CBIT](INFO): Critical error at 20250128T164336
2025-01-28T16:43:36.014Z,1738082616.014 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-01-28T16:43:36.408Z,1738082616.408 [MissionManager](IMPORTANT): Started mission Startup
2025-01-28T16:43:36.408Z,1738082616.408 [Startup] Running Loop=1
2025-01-28T16:43:36.408Z,1738082616.408 [Startup](DEBUG): Aggregate::initialize Startup
2025-01-28T16:43:36.409Z,1738082616.409 [Startup:A.GoToSurface] Running Loop=1
2025-01-28T16:43:36.409Z,1738082616.409 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-28T16:43:36.409Z,1738082616.409 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-01-28T16:43:36.410Z,1738082616.410 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-01-28T16:43:36.410Z,1738082616.410 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-01-28T16:43:36.410Z,1738082616.410 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-01-28T16:43:36.410Z,1738082616.411 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-28T16:43:36.411Z,1738082616.411 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-28T16:43:36.413Z,1738082616.413 [Startup:StartupSatComms] Running Loop=1
2025-01-28T16:43:36.413Z,1738082616.413 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-01-28T16:43:36.413Z,1738082616.413 [Startup:StartupSatComms:A] Running Loop=1
2025-01-28T16:43:36.819Z,1738082616.819 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-01-28T16:43:38.415Z,1738082618.415 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164338.00,A,3648.14179,N,12147.23000,W,0.039,275.60,280125,,,A*74
2025-01-28T16:43:38.424Z,1738082618.424 [NAL9602](INFO): GPS fix at 20250128T164338: (36.802363, -121.787167)
2025-01-28T16:43:38.437Z,1738082618.437 [Startup:StartupSatComms:A] Stopped
2025-01-28T16:43:38.437Z,1738082618.437 [Startup:StartupSatComms:B] Running Loop=1
2025-01-28T16:43:38.853Z,1738082618.853 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-01-28T16:43:39.620Z,1738082619.620 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:44:37.851Z,1738082677.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=20613, MT Status=2, MTMSN=0
2025-01-28T16:44:37.851Z,1738082677.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-28T16:44:38.676Z,1738082678.676 [Startup:StartupSatComms:B](INFO): Timed out from 2025-01-28T16:43:38.4Z
2025-01-28T16:44:38.676Z,1738082678.676 [Startup:StartupSatComms:B] Stopped
2025-01-28T16:44:38.676Z,1738082678.676 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-01-28T16:44:38.676Z,1738082678.676 [Startup:StartupSatComms] Stopped
2025-01-28T16:44:38.676Z,1738082678.676 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-01-28T16:44:38.677Z,1738082678.677 [Startup](INFO): Completed Startup
2025-01-28T16:44:38.678Z,1738082678.678 [MissionManager](INFO): Startup is completed.
2025-01-28T16:44:38.678Z,1738082678.678 [MissionManager](INFO): Uninitializing Mission Startup
2025-01-28T16:44:38.678Z,1738082678.678 [Startup] Stopped
2025-01-28T16:44:38.678Z,1738082678.678 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-01-28T16:44:38.678Z,1738082678.678 [Startup:A.GoToSurface] Stopped
2025-01-28T16:44:38.678Z,1738082678.678 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-28T16:44:39.112Z,1738082679.112 [MissionManager](IMPORTANT): Started mission Default
2025-01-28T16:44:39.113Z,1738082679.113 [Default] Running Loop=1
2025-01-28T16:44:39.113Z,1738082679.113 [Default](DEBUG): Aggregate::initialize Default
2025-01-28T16:44:39.113Z,1738082679.113 [Default:B.GoToSurface] Running Loop=1
2025-01-28T16:44:39.113Z,1738082679.113 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-28T16:44:39.113Z,1738082679.113 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-01-28T16:44:39.114Z,1738082679.114 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-01-28T16:44:39.114Z,1738082679.114 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-01-28T16:44:39.114Z,1738082679.114 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-01-28T16:44:39.114Z,1738082679.114 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-28T16:44:39.115Z,1738082679.115 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-28T16:44:39.115Z,1738082679.115 [Default:A.Wait] Running Loop=1
2025-01-28T16:44:39.115Z,1738082679.115 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-01-28T16:44:52.507Z,1738082692.507 [Default:A.Wait](INFO): Done Waiting.
2025-01-28T16:44:52.507Z,1738082692.507 [Default:A.Wait] Stopped
2025-01-28T16:44:52.507Z,1738082692.507 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-28T16:44:52.858Z,1738082692.858 [Default:CheckIn] Running Loop=1
2025-01-28T16:44:52.858Z,1738082692.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-28T16:44:52.858Z,1738082692.858 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-28T16:44:53.273Z,1738082693.273 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-01-28T16:44:59.716Z,1738082699.716 [NAL9602](INFO): SBD MO Status=2, MOMSN=20613, MT Status=2, MTMSN=0
2025-01-28T16:44:59.716Z,1738082699.716 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-28T16:45:00.925Z,1738082700.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164500.00,A,3648.14200,N,12147.23056,W,0.058,275.60,280125,,,A*70
2025-01-28T16:45:00.927Z,1738082700.927 [NAL9602](INFO): GPS fix at 20250128T164500: (36.802367, -121.787176)
2025-01-28T16:45:00.939Z,1738082700.939 [Default:CheckIn:Read_GPS] Stopped
2025-01-28T16:45:00.939Z,1738082700.939 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-28T16:45:01.413Z,1738082701.413 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-01-28T16:45:10.083Z,1738082710.083 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004456
2025-01-28T16:45:13.931Z,1738082713.931 [NAL9602](INFO): SBD MO Status=1, MOMSN=20613, MT Status=0, MTMSN=0
2025-01-28T16:45:14.024Z,1738082714.024 [NAL9602](INFO): Sent 18 bytes from file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:45:14.024Z,1738082714.024 [NAL9602](INFO): Packets left to send: 0
2025-01-28T16:45:14.079Z,1738082714.079 [Default:CheckIn:Read_Iridium] Stopped
2025-01-28T16:45:14.079Z,1738082714.079 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-28T16:45:14.079Z,1738082714.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-28T16:45:44.637Z,1738082744.637 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-28T16:45:56.486Z,1738082756.486 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:45:56.486Z,1738082756.486 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:45:57.489Z,1738082757.489 [DataOverHttps](IMPORTANT): SBD MTMSN=20250128T164557
2025-01-28T16:46:04.938Z,1738082764.938 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:46:04.938Z,1738082764.938 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:46:04.940Z,1738082764.940 [DataOverHttps](INFO): Received command: ibit
2025-01-28T16:46:04.973Z,1738082764.973 [CommandExec](IMPORTANT): got command ibit
2025-01-28T16:46:05.286Z,1738082765.286 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-01-28T16:46:05.286Z,1738082765.286 [IBIT](IMPORTANT): Beginning control surface checks.
2025-01-28T16:46:05.293Z,1738082765.293 [CBIT](IMPORTANT): Beginning ground fault scan
2025-01-28T16:46:06.859Z,1738082766.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164606.00,A,3648.14117,N,12147.23099,W,0.058,275.60,280125,,,D*76
2025-01-28T16:46:06.861Z,1738082766.861 [NAL9602](INFO): GPS fix at 20250128T164606: (36.802353, -121.787183)
2025-01-28T16:46:08.063Z,1738082768.063 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:46:16.315Z,1738082776.315 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000131
CHAN A1 (24V): 0.000598
CHAN A2 (12V): 0.000231
CHAN A3 (5V): -0.001189
CHAN B0 (3.3V): 0.000308
CHAN B1 (3.15aV): 0.000180
CHAN B2 (3.15bV): -0.000326
CHAN B3 (GND): -0.000839
OPEN: 0.006447
Full Scale: +/- 1 mA
2025-01-28T16:46:18.974Z,1738082778.974 [NAL9602](INFO): SBD MO Status=0, MOMSN=20614, MT Status=0, MTMSN=0
2025-01-28T16:46:18.974Z,1738082778.974 [NAL9602](INFO): No messages in MT queue
2025-01-28T16:46:20.186Z,1738082780.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164620.00,A,3648.14111,N,12147.23102,W,0.039,275.60,280125,,,D*70
2025-01-28T16:46:20.189Z,1738082780.189 [NAL9602](INFO): GPS fix at 20250128T164620: (36.802352, -121.787184)
2025-01-28T16:46:23.012Z,1738082783.012 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:46:23.439Z,1738082783.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164623.00,A,3648.14112,N,12147.23108,W,0.058,275.60,280125,,,D*7D
2025-01-28T16:46:23.441Z,1738082783.441 [NAL9602](INFO): GPS fix at 20250128T164623: (36.802352, -121.787185)
2025-01-28T16:46:26.267Z,1738082786.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164626.00,A,3648.14103,N,12147.23109,W,0.019,275.60,280125,,,D*7C
2025-01-28T16:46:26.269Z,1738082786.269 [NAL9602](INFO): GPS fix at 20250128T164626: (36.802351, -121.787185)
2025-01-28T16:46:29.483Z,1738082789.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164629.00,A,3648.14100,N,12147.23110,W,0.058,275.60,280125,,,D*7D
2025-01-28T16:46:29.485Z,1738082789.485 [NAL9602](INFO): GPS fix at 20250128T164629: (36.802350, -121.787185)
2025-01-28T16:46:32.307Z,1738082792.307 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164632.00,A,3648.14102,N,12147.23105,W,0.019,275.60,280125,,,D*74
2025-01-28T16:46:32.309Z,1738082792.309 [NAL9602](INFO): GPS fix at 20250128T164632: (36.802350, -121.787184)
2025-01-28T16:46:35.538Z,1738082795.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164635.00,A,3648.14105,N,12147.23098,W,0.039,275.60,280125,,,D*73
2025-01-28T16:46:35.540Z,1738082795.540 [NAL9602](INFO): GPS fix at 20250128T164635: (36.802351, -121.787183)
2025-01-28T16:46:35.686Z,1738082795.686 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:46:35.686Z,1738082795.686 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:46:38.378Z,1738082798.378 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:46:38.393Z,1738082798.393 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164638.00,A,3648.14103,N,12147.23092,W,0.097,275.60,280125,,,D*76
2025-01-28T16:46:38.395Z,1738082798.395 [NAL9602](INFO): GPS fix at 20250128T164638: (36.802351, -121.787182)
2025-01-28T16:46:41.196Z,1738082801.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164641.00,A,3648.14099,N,12147.23093,W,0.039,275.60,280125,,,D*7F
2025-01-28T16:46:41.198Z,1738082801.198 [NAL9602](INFO): GPS fix at 20250128T164641: (36.802350, -121.787182)
2025-01-28T16:46:44.431Z,1738082804.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164644.00,A,3648.14095,N,12147.23095,W,0.039,275.60,280125,,,D*70
2025-01-28T16:46:44.433Z,1738082804.433 [NAL9602](INFO): GPS fix at 20250128T164644: (36.802349, -121.787183)
2025-01-28T16:46:47.255Z,1738082807.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164647.00,A,3648.14098,N,12147.23094,W,0.039,275.60,280125,,,D*7F
2025-01-28T16:46:47.258Z,1738082807.258 [NAL9602](INFO): GPS fix at 20250128T164647: (36.802350, -121.787182)
2025-01-28T16:46:50.509Z,1738082810.509 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164650.00,A,3648.14095,N,12147.23097,W,0.078,275.60,280125,,,D*72
2025-01-28T16:46:50.511Z,1738082810.511 [NAL9602](INFO): GPS fix at 20250128T164650: (36.802349, -121.787183)
2025-01-28T16:46:51.333Z,1738082811.333 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802349 Longitude: -121.787186
2025-01-28T16:46:51.742Z,1738082811.742 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-01-28T16:46:52.167Z,1738082812.167 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-01-28T16:46:52.167Z,1738082812.167 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-01-28T16:46:52.167Z,1738082812.167 [IBIT](IMPORTANT): Pressure:9.909498 PSI
2025-01-28T16:46:52.172Z,1738082812.172 [IBIT](IMPORTANT): Humidity:23.312172 %
2025-01-28T16:46:52.532Z,1738082812.532 [IBIT](IMPORTANT): Vehicle Pitch:-1.223176 degrees
2025-01-28T16:46:52.532Z,1738082812.532 [IBIT](IMPORTANT): Vehicle  Roll:-1.757536 degrees
2025-01-28T16:46:52.532Z,1738082812.532 [IBIT](IMPORTANT): Vehicle  Heading:33.071186 degrees
2025-01-28T16:46:52.976Z,1738082812.976 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-01-28T16:46:52.976Z,1738082812.976 [IBIT](IMPORTANT): buoyancyNeutral: 279.564728 cc
2025-01-28T16:46:52.976Z,1738082812.976 [IBIT](IMPORTANT): massDefault: 1.343264 cm
2025-01-28T16:46:52.977Z,1738082812.977 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-01-28T16:46:52.977Z,1738082812.977 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-01-28T16:46:52.977Z,1738082812.977 [IBIT](IMPORTANT): IBIT PASSED
2025-01-28T16:46:53.715Z,1738082813.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:47:06.406Z,1738082826.406 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:47:06.406Z,1738082826.406 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:47:08.669Z,1738082828.669 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:47:22.811Z,1738082842.811 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-28T16:47:23.615Z,1738082843.615 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:47:36.978Z,1738082856.978 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:47:36.978Z,1738082856.978 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:47:38.980Z,1738082858.980 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:47:54.319Z,1738082874.319 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:48:07.462Z,1738082887.462 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:48:07.462Z,1738082887.462 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:48:09.292Z,1738082889.292 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-01-28T16:48:25.042Z,1738082905.042 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-01-28T16:48:25.043Z,1738082905.043 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2025-01-28T16:48:25.050Z,1738082905.050 [BPC1](INFO): Received data from all battery sticks.
2025-01-28T16:48:37.950Z,1738082917.950 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:48:37.950Z,1738082917.950 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:49:08.538Z,1738082948.538 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:49:08.538Z,1738082948.538 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:49:38.966Z,1738082978.966 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:49:38.966Z,1738082978.966 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:50:09.422Z,1738083009.422 [DataOverHttps](IMPORTANT): Could not stat file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:50:09.422Z,1738083009.422 [DataOverHttps](FAULT): Could not open file Logs/20250128T162422/Courier0010.lzma
2025-01-28T16:50:14.527Z,1738083014.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-28T16:50:14.527Z,1738083014.527 [Default:CheckIn:C.Wait] Stopped
2025-01-28T16:50:14.528Z,1738083014.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-28T16:50:14.528Z,1738083014.528 [Default:CheckIn:D] Running Loop=1
2025-01-28T16:50:14.927Z,1738083014.927 [Default:CheckIn:D] Stopped
2025-01-28T16:50:14.927Z,1738083014.927 [Default:CheckIn:E] Running Loop=1
2025-01-28T16:50:15.332Z,1738083015.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  5.596909 min 
2025-01-28T16:50:15.332Z,1738083015.332 [Default:CheckIn:E] Stopped
2025-01-28T16:50:15.332Z,1738083015.332 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-28T16:50:15.333Z,1738083015.333 [Default:CheckIn] Stopped
2025-01-28T16:50:15.333Z,1738083015.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-28T16:50:15.333Z,1738083015.333 [Default:CheckIn](INFO): Running loop #2
2025-01-28T16:50:15.333Z,1738083015.333 [Default:CheckIn] Running Loop=2
2025-01-28T16:50:15.333Z,1738083015.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-28T16:50:15.333Z,1738083015.333 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-28T16:50:17.339Z,1738083017.339 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165017.00,A,3648.14989,N,12147.22469,W,0.019,16.63,280125,,,D*45
2025-01-28T16:50:17.341Z,1738083017.341 [NAL9602](INFO): GPS fix at 20250128T165017: (36.802498, -121.787078)
2025-01-28T16:50:17.353Z,1738083017.353 [Default:CheckIn:Read_GPS] Stopped
2025-01-28T16:50:17.353Z,1738083017.353 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-28T16:50:24.992Z,1738083024.992 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20250128T164118/Courier0000.lzma
2025-01-28T16:50:25.994Z,1738083025.994 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Courier0000.lzma.bak
2025-01-28T16:50:25.994Z,1738083025.994 [DataOverHttps](INFO): SBD MOMSN=23942930
2025-01-28T16:50:41.883Z,1738083041.883 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250128T164118/Courier0004.lzma
2025-01-28T16:50:42.886Z,1738083042.886 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Courier0004.lzma.bak
2025-01-28T16:50:42.886Z,1738083042.886 [DataOverHttps](INFO): SBD MOMSN=23942935
2025-01-28T16:50:50.498Z,1738083050.498 [NAL9602](INFO): SBD MO Status=2, MOMSN=20615, MT Status=2, MTMSN=0
2025-01-28T16:50:50.499Z,1738083050.499 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-28T16:51:01.923Z,1738083061.923 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250128T164118/Courier0007.lzma
2025-01-28T16:51:02.926Z,1738083062.926 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Courier0007.lzma.bak
2025-01-28T16:51:02.926Z,1738083062.926 [DataOverHttps](INFO): SBD MOMSN=23942938
2025-01-28T16:51:07.866Z,1738083067.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=20615, MT Status=2, MTMSN=0
2025-01-28T16:51:07.867Z,1738083067.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-28T16:51:18.855Z,1738083078.855 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250128T162422/Express0011.lzma
2025-01-28T16:51:19.858Z,1738083079.858 [DataOverHttps](INFO): Moved sent file to Logs/20250128T162422/Express0011.lzma.bak
2025-01-28T16:51:19.858Z,1738083079.858 [DataOverHttps](INFO): SBD MOMSN=23942941
2025-01-28T16:51:35.755Z,1738083095.755 [DataOverHttps](INFO): Sending 1258 bytes from file Logs/20250128T164118/Express0001.lzma
2025-01-28T16:51:36.754Z,1738083096.754 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Express0001.lzma.bak
2025-01-28T16:51:36.754Z,1738083096.754 [DataOverHttps](INFO): SBD MOMSN=23942943
2025-01-28T16:51:52.699Z,1738083112.699 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250128T164118/Express0005.lzma
2025-01-28T16:51:53.702Z,1738083113.702 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Express0005.lzma.bak
2025-01-28T16:51:53.702Z,1738083113.702 [DataOverHttps](INFO): SBD MOMSN=23942975
2025-01-28T16:52:09.658Z,1738083129.658 [DataOverHttps](INFO): Sending 946 bytes from file Logs/20250128T164118/Express0008.lzma
2025-01-28T16:52:10.657Z,1738083130.657 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Express0008.lzma.bak
2025-01-28T16:52:10.658Z,1738083130.658 [DataOverHttps](INFO): SBD MOMSN=23942978
2025-01-28T16:52:26.683Z,1738083146.683 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250128T164118/Express0011.lzma
2025-01-28T16:52:27.686Z,1738083147.686 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Express0011.lzma.bak
2025-01-28T16:52:27.686Z,1738083147.686 [DataOverHttps](INFO): SBD MOMSN=23943022
2025-01-28T16:52:29.097Z,1738083149.097 [Default:CheckIn:Read_Iridium] Stopped
2025-01-28T16:52:29.098Z,1738083149.098 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-28T16:52:29.098Z,1738083149.098 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-28T16:53:13.518Z,1738083193.518 [NAL9602](INFO): SBD MO Status=0, MOMSN=20615, MT Status=0, MTMSN=0
2025-01-28T16:53:13.518Z,1738083193.518 [NAL9602](INFO): No messages in MT queue
2025-01-28T16:53:44.221Z,1738083224.221 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-28T16:57:29.671Z,1738083449.671 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-28T16:57:29.671Z,1738083449.671 [Default:CheckIn:C.Wait] Stopped
2025-01-28T16:57:29.671Z,1738083449.671 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-28T16:57:29.671Z,1738083449.671 [Default:CheckIn:D] Running Loop=1
2025-01-28T16:57:30.069Z,1738083450.069 [Default:CheckIn:D] Stopped
2025-01-28T16:57:30.069Z,1738083450.069 [Default:CheckIn:E] Running Loop=1
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  12.849272 min 
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn:E] Stopped
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn] Stopped
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn](INFO): Running loop #3
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn] Running Loop=3
2025-01-28T16:57:30.477Z,1738083450.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-28T16:57:30.478Z,1738083450.478 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-28T16:57:32.483Z,1738083452.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165732.00,A,3648.14979,N,12147.22208,W,0.019,16.63,280125,,,A*4E
2025-01-28T16:57:32.485Z,1738083452.485 [NAL9602](INFO): GPS fix at 20250128T165732: (36.802496, -121.787035)
2025-01-28T16:57:32.496Z,1738083452.496 [Default:CheckIn:Read_GPS] Stopped
2025-01-28T16:57:32.496Z,1738083452.496 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-28T16:57:40.335Z,1738083460.335 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250128T164118/Courier0013.lzma
2025-01-28T16:57:41.338Z,1738083461.338 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Courier0013.lzma.bak
2025-01-28T16:57:41.338Z,1738083461.338 [DataOverHttps](INFO): SBD MOMSN=23943042
2025-01-28T16:57:47.027Z,1738083467.027 [NAL9602](INFO): SBD MO Status=0, MOMSN=20616, MT Status=0, MTMSN=0
2025-01-28T16:57:47.027Z,1738083467.027 [NAL9602](INFO): No messages in MT queue
2025-01-28T16:57:57.127Z,1738083477.127 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250128T164118/Express0014.lzma
2025-01-28T16:57:58.130Z,1738083478.130 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Express0014.lzma.bak
2025-01-28T16:57:58.130Z,1738083478.130 [DataOverHttps](INFO): SBD MOMSN=23943045
2025-01-28T16:57:59.167Z,1738083479.167 [Default:CheckIn:Read_Iridium] Stopped
2025-01-28T16:57:59.167Z,1738083479.167 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-28T16:57:59.167Z,1738083479.167 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-28T16:58:17.729Z,1738083497.729 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-28T17:02:59.752Z,1738083779.752 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-28T17:02:59.752Z,1738083779.752 [Default:CheckIn:C.Wait] Stopped
2025-01-28T17:02:59.752Z,1738083779.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-28T17:02:59.752Z,1738083779.752 [Default:CheckIn:D] Running Loop=1
2025-01-28T17:03:00.162Z,1738083780.162 [Default:CheckIn:D] Stopped
2025-01-28T17:03:00.162Z,1738083780.162 [Default:CheckIn:E] Running Loop=1
2025-01-28T17:03:00.555Z,1738083780.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  18.350822 min 
2025-01-28T17:03:00.555Z,1738083780.555 [Default:CheckIn:E] Stopped
2025-01-28T17:03:00.555Z,1738083780.555 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-28T17:03:00.555Z,1738083780.555 [Default:CheckIn] Stopped
2025-01-28T17:03:00.555Z,1738083780.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-28T17:03:00.555Z,1738083780.555 [Default:CheckIn](INFO): Running loop #4
2025-01-28T17:03:00.556Z,1738083780.556 [Default:CheckIn] Running Loop=4
2025-01-28T17:03:00.556Z,1738083780.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-28T17:03:00.556Z,1738083780.556 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-28T17:03:02.566Z,1738083782.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170302.00,A,3648.14901,N,12147.22238,W,0.019,16.63,280125,,,A*41
2025-01-28T17:03:02.568Z,1738083782.568 [NAL9602](INFO): GPS fix at 20250128T170302: (36.802484, -121.787040)
2025-01-28T17:03:02.580Z,1738083782.580 [Default:CheckIn:Read_GPS] Stopped
2025-01-28T17:03:02.580Z,1738083782.580 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-28T17:03:09.591Z,1738083789.591 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250128T164118/Courier0016.lzma
2025-01-28T17:03:10.594Z,1738083790.594 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Courier0016.lzma.bak
2025-01-28T17:03:10.594Z,1738083790.594 [DataOverHttps](INFO): SBD MOMSN=23943065
2025-01-28T17:03:11.060Z,1738083791.060 [NAL9602](INFO): SBD MO Status=0, MOMSN=20617, MT Status=0, MTMSN=0
2025-01-28T17:03:11.060Z,1738083791.060 [NAL9602](INFO): No messages in MT queue
2025-01-28T17:03:28.892Z,1738083808.892 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20250128T164118/Express0017.lzma
2025-01-28T17:03:29.894Z,1738083809.894 [DataOverHttps](INFO): Moved sent file to Logs/20250128T164118/Express0017.lzma.bak
2025-01-28T17:03:29.894Z,1738083809.894 [DataOverHttps](INFO): SBD MOMSN=23943069
2025-01-28T17:03:30.925Z,1738083810.925 [Default:CheckIn:Read_Iridium] Stopped
2025-01-28T17:03:30.925Z,1738083810.925 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-28T17:03:30.925Z,1738083810.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-28T17:03:41.799Z,1738083821.799 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-28T17:04:08.897Z,1738083848.897 [DataOverHttps](IMPORTANT): SBD MTMSN=20250128T170408
2025-01-28T17:04:16.423Z,1738083856.423 [DataOverHttps](INFO): Received command: restart app
2025-01-28T17:04:16.453Z,1738083856.453 [CommandExec](IMPORTANT): got command restart application
2025-01-28T17:04:17.456Z,1738083857.456 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:17.456Z,1738083857.456 [CommandExec](INFO): Uninitializing the command executive.
2025-01-28T17:04:17.456Z,1738083857.456 [CommandExec](INFO): Uninitializing the command scheduler.
2025-01-28T17:04:17.456Z,1738083857.456 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:17.512Z,1738083857.512 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-01-28T17:04:17.512Z,1738083857.512 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-01-28T17:04:17.512Z,1738083857.512 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:17.513Z,1738083857.513 [NavChartDb](INFO): Join timeout helper Thread ID is 1055
2025-01-28T17:04:17.520Z,1738083857.520 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:17.520Z,1738083857.520 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:17.532Z,1738083857.532 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-01-28T17:04:17.532Z,1738083857.532 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:17.533Z,1738083857.533 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1056
2025-01-28T17:04:17.944Z,1738083857.944 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:17.944Z,1738083857.944 [WetLabsBB2FL](INFO): Powering down
2025-01-28T17:04:17.945Z,1738083857.945 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:17.952Z,1738083857.952 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-01-28T17:04:17.952Z,1738083857.952 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:17.952Z,1738083857.952 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1057
2025-01-28T17:04:18.856Z,1738083858.856 [CTD_Seabird](INFO): Powering down
2025-01-28T17:04:18.868Z,1738083858.868 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:18.869Z,1738083858.869 [CTD_Seabird](INFO): Powering down
2025-01-28T17:04:18.884Z,1738083858.884 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:18.896Z,1738083858.896 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-01-28T17:04:18.896Z,1738083858.896 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:18.896Z,1738083858.896 [Radio_Surface](INFO): Join timeout helper Thread ID is 1058
2025-01-28T17:04:19.152Z,1738083859.152 [Radio_Surface](INFO): Powering down
2025-01-28T17:04:19.153Z,1738083859.153 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:19.153Z,1738083859.153 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:19.156Z,1738083859.156 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-01-28T17:04:19.156Z,1738083859.156 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:19.156Z,1738083859.156 [Onboard](INFO): Join timeout helper Thread ID is 1059
2025-01-28T17:04:19.432Z,1738083859.432 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-01-28T17:04:20.144Z,1738083860.144 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.145Z,1738083860.145 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.153Z,1738083860.153 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-01-28T17:04:20.153Z,1738083860.153 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.153Z,1738083860.153 [DataOverHttps](INFO): Join timeout helper Thread ID is 1060
2025-01-28T17:04:20.436Z,1738083860.436 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.436Z,1738083860.436 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.452Z,1738083860.452 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-01-28T17:04:20.453Z,1738083860.453 [DAT ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.453Z,1738083860.453 [DAT](INFO): Join timeout helper Thread ID is 1061
2025-01-28T17:04:20.632Z,1738083860.632 [DAT](INFO): Powering down
2025-01-28T17:04:20.704Z,1738083860.704 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.705Z,1738083860.705 [DAT](INFO): Powering down
2025-01-28T17:04:20.705Z,1738083860.705 [DAT ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.712Z,1738083860.712 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-01-28T17:04:20.713Z,1738083860.713 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.713Z,1738083860.713 [BackseatComponent](INFO): Join timeout helper Thread ID is 1062
2025-01-28T17:04:20.792Z,1738083860.792 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.792Z,1738083860.792 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.812Z,1738083860.812 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-01-28T17:04:20.813Z,1738083860.813 [logger ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.813Z,1738083860.813 [logger](INFO): Join timeout helper Thread ID is 1063
2025-01-28T17:04:20.820Z,1738083860.820 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.820Z,1738083860.820 [logger ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.832Z,1738083860.832 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-01-28T17:04:20.833Z,1738083860.833 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.833Z,1738083860.833 [CommandLine](INFO): Join timeout helper Thread ID is 1064
2025-01-28T17:04:20.908Z,1738083860.908 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.909Z,1738083860.909 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.915Z,1738083860.915 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-01-28T17:04:20.915Z,1738083860.915 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.915Z,1738083860.915 [CommandExec](INFO): Join timeout helper Thread ID is 1065
2025-01-28T17:04:20.917Z,1738083860.917 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-01-28T17:04:20.917Z,1738083860.917 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:20.917Z,1738083860.917 [controlThread](INFO): Join timeout helper Thread ID is 1066
2025-01-28T17:04:20.964Z,1738083860.964 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-01-28T17:04:20.964Z,1738083860.964 [controlThread](DEBUG): Uninitializing ControlThread
2025-01-28T17:04:20.965Z,1738083860.965 [AHRS_M2](INFO): Powering down
2025-01-28T17:04:21.037Z,1738083861.037 [NAL9602](INFO): Powering down
2025-01-28T17:04:21.038Z,1738083861.038 [Sonardyne_Nano](INFO): Powering down
2025-01-28T17:04:21.156Z,1738083861.156 [Waterlinked](INFO): Powering down
2025-01-28T17:04:21.158Z,1738083861.158 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-01-28T17:04:21.158Z,1738083861.158 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-01-28T17:04:21.159Z,1738083861.159 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-01-28T17:04:21.159Z,1738083861.159 [MissionManager](INFO): Uninitializing Mission Default
2025-01-28T17:04:21.160Z,1738083861.160 [Default] Stopped
2025-01-28T17:04:21.160Z,1738083861.160 [Default](DEBUG): Aggregate::uninitialize Default
2025-01-28T17:04:21.160Z,1738083861.160 [Default:B.GoToSurface] Stopped
2025-01-28T17:04:21.160Z,1738083861.160 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-28T17:04:21.160Z,1738083861.160 [Default:CheckIn] Stopped
2025-01-28T17:04:21.160Z,1738083861.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-28T17:04:21.160Z,1738083861.160 [Default:CheckIn:C.Wait] Stopped
2025-01-28T17:04:21.160Z,1738083861.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-28T17:04:21.163Z,1738083861.163 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-01-28T17:04:21.163Z,1738083861.163 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-01-28T17:04:21.164Z,1738083861.164 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-01-28T17:04:21.164Z,1738083861.164 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-01-28T17:04:21.164Z,1738083861.164 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-01-28T17:04:21.164Z,1738083861.164 [BuoyancyServo](INFO): Powering down
2025-01-28T17:04:21.176Z,1738083861.176 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-01-28T17:04:21.176Z,1738083861.176 [ElevatorServo](INFO): Powering down
2025-01-28T17:04:21.177Z,1738083861.177 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-01-28T17:04:21.177Z,1738083861.177 [MassServo](INFO): Powering down
2025-01-28T17:04:21.178Z,1738083861.178 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-01-28T17:04:21.178Z,1738083861.178 [RudderServo](INFO): Powering down
2025-01-28T17:04:21.179Z,1738083861.179 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-01-28T17:04:21.179Z,1738083861.179 [ThrusterHE](INFO): Powering down
2025-01-28T17:04:21.180Z,1738083861.180 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-01-28T17:04:21.180Z,1738083861.180 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-01-28T17:04:21.180Z,1738083861.180 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-01-28T17:04:21.181Z,1738083861.181 [CBIT](DEBUG): Powering off loads.
2025-01-28T17:04:21.192Z,1738083861.192 [CBIT](DEBUG): Disabling WDT.
2025-01-28T17:04:21.204Z,1738083861.204 [CBIT](DEBUG): Opening all GF detection circuits.
2025-01-28T17:04:21.205Z,1738083861.205 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.238Z,1738083861.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.241Z,1738083861.241 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.247Z,1738083861.247 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.253Z,1738083861.253 [DAT ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.294Z,1738083861.294 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.382Z,1738083861.382 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.388Z,1738083861.388 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.396Z,1738083861.396 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-01-28T17:04:21.467Z,1738083861.467 [logger ThreadHandler](INFO): Thread cancelled.