2025-03-18T18:44:20.341Z,1742323460.341 [Supervisor](DEBUG): Initializing supervisor.
2025-03-18T18:44:20.345Z,1742323460.345 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-18T18:44:20.345Z,1742323460.345 [SyncHandler](INFO): Protected caller Thread ID is 1175
2025-03-18T18:44:20.346Z,1742323460.346 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-18T18:44:20.347Z,1742323460.347 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-18T18:44:20.347Z,1742323460.347 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1176
2025-03-18T18:44:20.351Z,1742323460.351 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-18T18:44:20.370Z,1742323460.370 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-18T18:44:20.371Z,1742323460.371 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-18T18:44:20.371Z,1742323460.371 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1177
2025-03-18T18:44:20.375Z,1742323460.375 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-18T18:44:20.376Z,1742323460.376 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-18T18:44:20.377Z,1742323460.377 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1178
2025-03-18T18:44:20.379Z,1742323460.379 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-18T18:44:20.380Z,1742323460.380 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-18T18:44:20.380Z,1742323460.380 [logger ThreadHandler](INFO): Protected caller Thread ID is 1179
2025-03-18T18:44:20.384Z,1742323460.384 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-18T18:44:20.384Z,1742323460.384 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-18T18:44:20.386Z,1742323460.386 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-18T18:44:20.772Z,1742323460.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-18T18:44:20.772Z,1742323460.772 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-18T18:44:20.864Z,1742323460.864 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-18T18:44:20.982Z,1742323460.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-18T18:44:20.983Z,1742323460.983 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-18T18:44:21.123Z,1742323461.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-18T18:44:21.124Z,1742323461.124 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-18T18:44:21.478Z,1742323461.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-18T18:44:21.479Z,1742323461.479 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-18T18:44:21.626Z,1742323461.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-18T18:44:21.626Z,1742323461.626 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-18T18:44:22.087Z,1742323462.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-18T18:44:22.088Z,1742323462.088 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-18T18:44:22.322Z,1742323462.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-18T18:44:22.323Z,1742323462.323 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-18T18:44:22.414Z,1742323462.414 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-18T18:44:22.664Z,1742323462.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-18T18:44:22.665Z,1742323462.665 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-18T18:44:22.894Z,1742323462.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-18T18:44:22.894Z,1742323462.894 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-18T18:44:23.427Z,1742323463.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-18T18:44:23.428Z,1742323463.428 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-18T18:44:23.623Z,1742323463.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-18T18:44:23.623Z,1742323463.623 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-18T18:44:23.917Z,1742323463.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-18T18:44:23.918Z,1742323463.918 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-18T18:44:24.781Z,1742323464.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-18T18:44:24.782Z,1742323464.782 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-18T18:44:25.353Z,1742323465.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-18T18:44:25.355Z,1742323465.355 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-03-18T18:44:25.356Z,1742323465.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-03-18T18:44:25.463Z,1742323465.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-03-18T18:44:25.603Z,1742323465.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-03-18T18:44:25.699Z,1742323465.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-03-18T18:44:25.825Z,1742323465.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-03-18T18:44:25.934Z,1742323465.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-03-18T18:44:26.202Z,1742323466.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-18T18:44:26.203Z,1742323466.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-03-18T18:44:26.323Z,1742323466.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-03-18T18:44:26.431Z,1742323466.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-03-18T18:44:26.582Z,1742323466.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-03-18T18:44:26.682Z,1742323466.682 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2025-03-18T18:44:26.682Z,1742323466.682 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-03-18T18:44:26.683Z,1742323466.683 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-18T18:44:26.816Z,1742323466.816 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-18T18:44:26.817Z,1742323466.817 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-18T18:44:26.870Z,1742323466.870 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-18T18:44:26.931Z,1742323466.931 [VerticalControl] Loaded
2025-03-18T18:44:26.932Z,1742323466.932 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-18T18:44:26.934Z,1742323466.934 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-18T18:44:26.982Z,1742323466.982 [HorizontalControl] Loaded
2025-03-18T18:44:26.982Z,1742323466.982 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-18T18:44:26.984Z,1742323466.984 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-18T18:44:26.987Z,1742323466.987 [SpeedControl] Loaded
2025-03-18T18:44:26.988Z,1742323466.988 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-18T18:44:26.990Z,1742323466.990 [LoopControl](DEBUG): Construct LoopControl.
2025-03-18T18:44:26.991Z,1742323466.991 [LoopControl] Loaded
2025-03-18T18:44:26.991Z,1742323466.991 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-18T18:44:26.991Z,1742323466.991 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-18T18:44:26.992Z,1742323466.992 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-18T18:44:27.051Z,1742323467.051 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-18T18:44:27.052Z,1742323467.052 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-18T18:44:27.523Z,1742323467.523 [AHRS_M2] Loaded
2025-03-18T18:44:27.523Z,1742323467.523 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-18T18:44:27.561Z,1742323467.561 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4
2025-03-18T18:44:27.562Z,1742323467.562 [BackseatComponent] Loaded
2025-03-18T18:44:27.562Z,1742323467.562 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-18T18:44:27.563Z,1742323467.563 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0
2025-03-18T18:44:27.563Z,1742323467.563 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1259
2025-03-18T18:44:27.566Z,1742323467.566 [LcmUniversalReporter] Loaded
2025-03-18T18:44:27.566Z,1742323467.566 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-18T18:44:28.358Z,1742323468.358 [BPC1] Loaded
2025-03-18T18:44:28.359Z,1742323468.359 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-18T18:44:28.454Z,1742323468.454 [DAT] Loaded
2025-03-18T18:44:28.454Z,1742323468.454 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-03-18T18:44:28.455Z,1742323468.455 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0
2025-03-18T18:44:28.456Z,1742323468.456 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1260
2025-03-18T18:44:28.529Z,1742323468.529 [DataOverHttps] Loaded
2025-03-18T18:44:28.530Z,1742323468.530 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-18T18:44:28.531Z,1742323468.531 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0
2025-03-18T18:44:28.531Z,1742323468.531 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1261
2025-03-18T18:44:28.554Z,1742323468.554 [Depth_Keller] Loaded
2025-03-18T18:44:28.554Z,1742323468.554 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-18T18:44:28.559Z,1742323468.559 [DropWeight] Loaded
2025-03-18T18:44:28.559Z,1742323468.559 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-18T18:44:28.623Z,1742323468.623 [NAL9602] Loaded
2025-03-18T18:44:28.623Z,1742323468.623 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-18T18:44:28.652Z,1742323468.652 [Onboard] Loaded
2025-03-18T18:44:28.652Z,1742323468.652 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-18T18:44:28.653Z,1742323468.653 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0
2025-03-18T18:44:28.654Z,1742323468.654 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1262
2025-03-18T18:44:28.668Z,1742323468.668 [Power24vConverter] Loaded
2025-03-18T18:44:28.668Z,1742323468.668 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-18T18:44:28.684Z,1742323468.684 [Radio_Surface] Loaded
2025-03-18T18:44:28.684Z,1742323468.684 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-18T18:44:28.685Z,1742323468.685 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0
2025-03-18T18:44:28.685Z,1742323468.685 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1263
2025-03-18T18:44:28.701Z,1742323468.701 [Sonardyne_Nano] Loaded
2025-03-18T18:44:28.701Z,1742323468.701 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-18T18:44:28.725Z,1742323468.725 [Waterlinked] Loaded
2025-03-18T18:44:28.725Z,1742323468.725 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-03-18T18:44:28.725Z,1742323468.725 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-18T18:44:28.726Z,1742323468.726 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-18T18:44:28.736Z,1742323468.736 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-18T18:44:28.737Z,1742323468.737 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-18T18:44:28.766Z,1742323468.766 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-18T18:44:28.767Z,1742323468.767 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-18T18:44:28.927Z,1742323468.927 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-18T18:44:28.936Z,1742323468.936 [SBIT] Loaded
2025-03-18T18:44:28.936Z,1742323468.936 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-18T18:44:28.939Z,1742323468.939 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-18T18:44:28.953Z,1742323468.953 [IBIT] Loaded
2025-03-18T18:44:28.953Z,1742323468.953 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-18T18:44:28.958Z,1742323468.958 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-18T18:44:29.036Z,1742323469.036 [CBIT] Loaded
2025-03-18T18:44:29.036Z,1742323469.036 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-18T18:44:29.068Z,1742323469.068 [GFScanner] Loaded
2025-03-18T18:44:29.068Z,1742323469.068 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-18T18:44:29.069Z,1742323469.069 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-18T18:44:29.069Z,1742323469.069 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-18T18:44:29.413Z,1742323469.413 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-18T18:44:29.414Z,1742323469.414 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-18T18:44:29.464Z,1742323469.464 [DepthRateCalculator] Loaded
2025-03-18T18:44:29.465Z,1742323469.465 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-18T18:44:29.470Z,1742323469.470 [PitchRateCalculator] Loaded
2025-03-18T18:44:29.470Z,1742323469.470 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-18T18:44:29.480Z,1742323469.480 [SpeedCalculator] Loaded
2025-03-18T18:44:29.480Z,1742323469.480 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-18T18:44:29.485Z,1742323469.485 [YawRateCalculator] Loaded
2025-03-18T18:44:29.485Z,1742323469.485 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-18T18:44:29.503Z,1742323469.503 [ElevatorOffsetCalculator] Loaded
2025-03-18T18:44:29.504Z,1742323469.504 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-18T18:44:29.504Z,1742323469.504 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-18T18:44:29.505Z,1742323469.505 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-18T18:44:29.679Z,1742323469.679 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-18T18:44:29.679Z,1742323469.679 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-18T18:44:29.793Z,1742323469.793 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-18T18:44:29.793Z,1742323469.793 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-18T18:44:29.807Z,1742323469.807 [NavChart] Loaded
2025-03-18T18:44:29.807Z,1742323469.807 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-18T18:44:29.813Z,1742323469.813 [UniversalFixResidualReporter] Loaded
2025-03-18T18:44:29.813Z,1742323469.813 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-18T18:44:29.813Z,1742323469.813 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-18T18:44:29.814Z,1742323469.814 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-18T18:44:29.952Z,1742323469.952 [BuoyancyServo] Loaded
2025-03-18T18:44:29.952Z,1742323469.952 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-18T18:44:29.975Z,1742323469.975 [ElevatorServo] Loaded
2025-03-18T18:44:29.975Z,1742323469.975 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-18T18:44:29.997Z,1742323469.997 [MassServo] Loaded
2025-03-18T18:44:29.997Z,1742323469.997 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-18T18:44:30.018Z,1742323470.018 [RudderServo] Loaded
2025-03-18T18:44:30.018Z,1742323470.018 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-18T18:44:30.035Z,1742323470.035 [ThrusterHE] Loaded
2025-03-18T18:44:30.035Z,1742323470.035 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-18T18:44:30.035Z,1742323470.035 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-18T18:44:30.036Z,1742323470.036 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-18T18:44:30.202Z,1742323470.202 [CTD_Seabird] Loaded
2025-03-18T18:44:30.202Z,1742323470.202 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-18T18:44:30.204Z,1742323470.204 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0
2025-03-18T18:44:30.204Z,1742323470.204 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1264
2025-03-18T18:44:30.240Z,1742323470.240 [ESPComponent] Loaded
2025-03-18T18:44:30.241Z,1742323470.241 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-03-18T18:44:30.262Z,1742323470.262 [PAR_Licor] Loaded
2025-03-18T18:44:30.263Z,1742323470.263 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-18T18:44:30.308Z,1742323470.308 [WetLabsBB2FL] Loaded
2025-03-18T18:44:30.308Z,1742323470.308 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-18T18:44:30.309Z,1742323470.309 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0
2025-03-18T18:44:30.309Z,1742323470.309 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1265
2025-03-18T18:44:30.310Z,1742323470.310 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-18T18:44:30.316Z,1742323470.316 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-18T18:44:30.319Z,1742323470.319 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-18T18:44:30.331Z,1742323470.331 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-18T18:44:30.332Z,1742323470.332 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-03-18T18:44:30.332Z,1742323470.332 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1266
2025-03-18T18:44:30.336Z,1742323470.336 [Supervisor](INFO): Main Thread ID is 1174
2025-03-18T18:44:30.336Z,1742323470.336 [Supervisor](DEBUG): Running supervisor.
2025-03-18T18:44:30.337Z,1742323470.337 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1267
2025-03-18T18:44:30.338Z,1742323470.338 [CommandExec](INFO): Initializing the command executive.
2025-03-18T18:44:30.339Z,1742323470.339 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1268
2025-03-18T18:44:30.341Z,1742323470.341 [controlThread ThreadHandler](INFO): Handler Thread ID is 1269
2025-03-18T18:44:30.342Z,1742323470.342 [controlThread](DEBUG): Initializing ControlThread
2025-03-18T18:44:30.343Z,1742323470.343 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-18T18:44:30.344Z,1742323470.344 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-18T18:44:30.345Z,1742323470.345 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-18T18:44:30.346Z,1742323470.346 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-18T18:44:30.351Z,1742323470.351 [SBIT](INFO): Initialize SBIT Component.
2025-03-18T18:44:30.352Z,1742323470.352 [SBIT](IMPORTANT): git: 2025-03-12_C
2025-03-18T18:44:30.352Z,1742323470.352 [SBIT](INFO): git hash: c930cdb2e5c1f1ff3673ee566a0d82f2333fbba8
2025-03-18T18:44:30.352Z,1742323470.352 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-18T18:44:30.353Z,1742323470.353 [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-03-18T18:44:30.355Z,1742323470.355 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2025-03-18T18:44:30.355Z,1742323470.355 [IBIT](INFO): Initialize IBIT Component.
2025-03-18T18:44:30.356Z,1742323470.356 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-18T18:44:30.357Z,1742323470.357 [logger ThreadHandler](INFO): Handler Thread ID is 1270
2025-03-18T18:44:30.369Z,1742323470.369 [CBIT](DEBUG): Initialized mux pins.
2025-03-18T18:44:30.369Z,1742323470.369 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-03-18T18:44:30.369Z,1742323470.369 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-18T18:44:30.378Z,1742323470.378 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1271
2025-03-18T18:44:30.390Z,1742323470.390 [DAT ThreadHandler](INFO): Handler Thread ID is 1272
2025-03-18T18:44:30.390Z,1742323470.390 [DAT](INFO): Powering up
2025-03-18T18:44:30.391Z,1742323470.391 [DAT](DEBUG): Initializing DAT.
2025-03-18T18:44:30.393Z,1742323470.393 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2025-03-18T18:44:30.393Z,1742323470.393 [CBIT] Hardware Fault, FailCount= 1
2025-03-18T18:44:30.393Z,1742323470.393 [CBIT](ERROR): Hardware Fault
2025-03-18T18:44:30.394Z,1742323470.394 [CBIT](DEBUG): Initializing heartbeat.
2025-03-18T18:44:30.395Z,1742323470.395 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1274
2025-03-18T18:44:30.397Z,1742323470.397 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-18T18:44:30.406Z,1742323470.406 [Onboard ThreadHandler](INFO): Handler Thread ID is 1275
2025-03-18T18:44:30.435Z,1742323470.435 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1276
2025-03-18T18:44:30.454Z,1742323470.454 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1277
2025-03-18T18:44:30.454Z,1742323470.454 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-18T18:44:30.458Z,1742323470.458 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1279
2025-03-18T18:44:30.462Z,1742323470.462 [WetLabsBB2FL](INFO): Powering up
2025-03-18T18:44:30.463Z,1742323470.463 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1281
2025-03-18T18:44:30.465Z,1742323470.465 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-18T18:44:30.466Z,1742323470.466 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-18T18:44:30.466Z,1742323470.466 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-18T18:44:30.466Z,1742323470.466 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-18T18:44:30.467Z,1742323470.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-18T18:44:30.467Z,1742323470.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-18T18:44:30.467Z,1742323470.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-18T18:44:30.467Z,1742323470.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-18T18:44:30.467Z,1742323470.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-18T18:44:30.505Z,1742323470.505 [CBIT](DEBUG): Backplane powered.
2025-03-18T18:44:30.506Z,1742323470.506 [GFScanner](DEBUG): Initializing GFScanner
2025-03-18T18:44:30.506Z,1742323470.506 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-18T18:44:30.506Z,1742323470.506 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-18T18:44:30.507Z,1742323470.507 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-18T18:44:30.507Z,1742323470.507 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-18T18:44:30.507Z,1742323470.507 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-18T18:44:30.509Z,1742323470.509 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-18T18:44:30.509Z,1742323470.509 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-18T18:44:30.514Z,1742323470.514 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-18T18:44:30.515Z,1742323470.515 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-18T18:44:30.515Z,1742323470.515 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-18T18:44:30.516Z,1742323470.516 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-18T18:44:30.593Z,1742323470.593 [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-03-18T18:44:30.606Z,1742323470.606 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-18T18:44:30.659Z,1742323470.659 [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-03-18T18:44:30.660Z,1742323470.660 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-18T18:44:30.660Z,1742323470.660 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-18T18:44:30.661Z,1742323470.661 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-18T18:44:30.837Z,1742323470.837 [Radio_Surface](INFO): Powering up
2025-03-18T18:44:30.977Z,1742323470.977 [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-03-18T18:44:30.987Z,1742323470.987 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-18T18:44:30.994Z,1742323470.994 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-18T18:44:30.997Z,1742323470.997 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-18T18:44:31.048Z,1742323471.048 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-18T18:44:31.051Z,1742323471.051 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-18T18:44:31.069Z,1742323471.069 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-18T18:44:31.072Z,1742323471.072 [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-03-18T18:44:31.089Z,1742323471.089 [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,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-18T18:44:31.097Z,1742323471.097 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-18T18:44:31.167Z,1742323471.167 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2025-03-18T18:44:31.168Z,1742323471.168 [Power24vConverter](INFO): Powering up.
2025-03-18T18:44:31.169Z,1742323471.169 [Sonardyne_Nano](INFO): Initializing.
2025-03-18T18:44:31.207Z,1742323471.207 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-18T18:44:31.230Z,1742323471.230 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:31.237Z,1742323471.237 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-18T18:44:31.239Z,1742323471.239 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:31.246Z,1742323471.246 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-18T18:44:31.247Z,1742323471.247 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:31.253Z,1742323471.253 [MassServo](DEBUG): Initializing MassServo.
2025-03-18T18:44:31.254Z,1742323471.254 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:31.261Z,1742323471.261 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-18T18:44:31.262Z,1742323471.262 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:31.269Z,1742323471.269 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-18T18:44:31.271Z,1742323471.271 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-18T18:44:31.271Z,1742323471.271 [CBIT](DEBUG): Powering off loads.
2025-03-18T18:44:31.285Z,1742323471.285 [CBIT](DEBUG): Disabling WDT.
2025-03-18T18:44:31.543Z,1742323471.543 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-03-18T18:44:31.543Z,1742323471.543 [DropWeight] Hardware Fault, FailCount= 1
2025-03-18T18:44:31.543Z,1742323471.543 [DropWeight](ERROR): Hardware Fault
2025-03-18T18:44:32.645Z,1742323472.645 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2025-03-18T18:44:32.645Z,1742323472.645 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2025-03-18T18:44:32.646Z,1742323472.646 [BuoyancyServo] Communications Fault, FailCount= 1
2025-03-18T18:44:32.646Z,1742323472.646 [BuoyancyServo](ERROR): Communications Fault
2025-03-18T18:44:32.957Z,1742323472.957 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2025-03-18T18:44:32.957Z,1742323472.957 [ElevatorServo](FAULT): Elevator failed to initialize
2025-03-18T18:44:32.957Z,1742323472.957 [ElevatorServo] Communications Fault, FailCount= 1
2025-03-18T18:44:32.957Z,1742323472.957 [ElevatorServo](ERROR): Communications Fault
2025-03-18T18:44:33.261Z,1742323473.261 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2025-03-18T18:44:33.261Z,1742323473.261 [RudderServo](FAULT): Rudder failed to initialize
2025-03-18T18:44:33.261Z,1742323473.261 [RudderServo] Communications Fault, FailCount= 1
2025-03-18T18:44:33.261Z,1742323473.261 [RudderServo](ERROR): Communications Fault
2025-03-18T18:44:33.316Z,1742323473.316 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-18T18:44:33.317Z,1742323473.317 [ElevatorServo](INFO): Powering down
2025-03-18T18:44:33.326Z,1742323473.326 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-18T18:44:33.326Z,1742323473.326 [RudderServo](INFO): Powering down
2025-03-18T18:44:33.328Z,1742323473.328 [ThrusterHE](FAULT): Thruster failed to initialize
2025-03-18T18:44:33.328Z,1742323473.328 [ThrusterHE] Communications Fault, FailCount= 1
2025-03-18T18:44:33.328Z,1742323473.328 [ThrusterHE](ERROR): Communications Fault
2025-03-18T18:44:33.680Z,1742323473.680 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-18T18:44:33.729Z,1742323473.729 [ElevatorServo](FAULT): LCB fault:  Current Limiter Activated.
2025-03-18T18:44:33.730Z,1742323473.730 [ElevatorServo] Hardware Fault, FailCount= 1
2025-03-18T18:44:33.730Z,1742323473.730 [ElevatorServo](ERROR): Hardware Fault
2025-03-18T18:44:33.730Z,1742323473.730 [RudderServo](FAULT): LCB fault:  Current Limiter Activated.
2025-03-18T18:44:33.731Z,1742323473.731 [RudderServo] Hardware Fault, FailCount= 1
2025-03-18T18:44:33.731Z,1742323473.731 [RudderServo](ERROR): Hardware Fault
2025-03-18T18:44:33.731Z,1742323473.731 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-18T18:44:33.731Z,1742323473.731 [ThrusterHE](INFO): Powering down
2025-03-18T18:44:34.109Z,1742323474.109 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:34.234Z,1742323474.234 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-18T18:44:34.537Z,1742323474.537 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2025-03-18T18:44:34.537Z,1742323474.537 [MassServo] Communications Fault, FailCount= 1
2025-03-18T18:44:34.537Z,1742323474.537 [MassServo](ERROR): Communications Fault
2025-03-18T18:44:34.538Z,1742323474.538 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:34.658Z,1742323474.658 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-18T18:44:34.775Z,1742323474.775 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-18T18:44:34.775Z,1742323474.775 [MassServo](INFO): Powering down
2025-03-18T18:44:35.153Z,1742323475.153 [MassServo](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-03-18T18:44:35.153Z,1742323475.153 [MassServo] Hardware Fault, FailCount= 1
2025-03-18T18:44:35.153Z,1742323475.153 [MassServo](ERROR): Hardware Fault
2025-03-18T18:44:35.506Z,1742323475.506 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-03-18T18:44:35.518Z,1742323475.518 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-18T18:44:35.639Z,1742323475.639 [MassServo](DEBUG): Initializing MassServo.
2025-03-18T18:44:35.753Z,1742323475.753 [WetLabsBB2FL] Communications Fault, FailCount= 1
2025-03-18T18:44:35.753Z,1742323475.753 [WetLabsBB2FL](ERROR): Communications Fault
2025-03-18T18:44:35.950Z,1742323475.950 [Sonardyne_Nano](ERROR): External power not detected
2025-03-18T18:44:36.253Z,1742323476.253 [WetLabsBB2FL](INFO): Powering down
2025-03-18T18:44:43.505Z,1742323483.505 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2025-03-18T18:44:43.505Z,1742323483.505 [CTD_Seabird] Communications Fault, FailCount= 1
2025-03-18T18:44:43.505Z,1742323483.505 [CTD_Seabird](ERROR): Communications Fault
2025-03-18T18:44:43.910Z,1742323483.910 [CTD_Seabird](INFO): Powering down
2025-03-18T18:44:49.625Z,1742323489.625 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-03-18T18:44:49.625Z,1742323489.625 [AHRS_M2] Data Fault, FailCount= 1
2025-03-18T18:44:49.625Z,1742323489.625 [AHRS_M2](ERROR): Data Fault
2025-03-18T18:44:50.015Z,1742323490.015 [AHRS_M2](INFO): Powering down
2025-03-18T18:44:58.907Z,1742323498.907 [NAL9602](INFO): Powering up NAL9602
2025-03-18T18:44:59.311Z,1742323499.311 [Waterlinked](FAULT): Could not initialize.
2025-03-18T18:44:59.311Z,1742323499.311 [Waterlinked] Communications Fault, FailCount= 1
2025-03-18T18:44:59.311Z,1742323499.311 [Waterlinked](ERROR): Communications Fault
2025-03-18T18:44:59.801Z,1742323499.801 [Waterlinked](INFO): Powering down
2025-03-18T18:45:19.515Z,1742323519.515 [NAL9602](ERROR): NAL9602 initialization error.
2025-03-18T18:45:19.515Z,1742323519.515 [NAL9602] Communications Fault, FailCount= 1
2025-03-18T18:45:19.515Z,1742323519.515 [NAL9602](ERROR): Communications Fault
2025-03-18T18:45:19.915Z,1742323519.915 [NAL9602](INFO): Powering down
2025-03-18T18:45:30.406Z,1742323530.406 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2025-03-18T18:45:30.406Z,1742323530.406 [DAT] Communications Fault, FailCount= 1
2025-03-18T18:45:30.406Z,1742323530.406 [DAT](ERROR): Communications Fault
2025-03-18T18:45:30.809Z,1742323530.809 [DAT](INFO): Powering down
2025-03-18T18:45:31.281Z,1742323531.281 [DAT](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-03-18T18:45:31.282Z,1742323531.282 [DAT] Hardware Fault, FailCount= 1
2025-03-18T18:45:31.282Z,1742323531.282 [DAT](ERROR): Hardware Fault
2025-03-18T18:45:42.148Z,1742323542.148 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-18T18:45:55.512Z,1742323555.512 [SBIT](ERROR): Could not read elevatorAngleReader_.
2025-03-18T18:45:55.512Z,1742323555.512 [SBIT](ERROR): Could not read rudderAngleReader_.
2025-03-18T18:45:55.512Z,1742323555.512 [SBIT](ERROR): Could not read massPosReader_.
2025-03-18T18:46:22.159Z,1742323582.159 [SBIT](ERROR): Could not read elevatorAngleReader_.
2025-03-18T18:46:22.159Z,1742323582.159 [SBIT](ERROR): Could not read rudderAngleReader_.
2025-03-18T18:46:22.159Z,1742323582.159 [SBIT](ERROR): Could not read massPosReader_.
2025-03-18T18:46:22.159Z,1742323582.159 [SBIT](FAULT): Control surface position failure.
2025-03-18T18:46:30.623Z,1742323590.623 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2025-03-18T18:46:30.623Z,1742323590.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-18T18:46:30.623Z,1742323590.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-18T18:46:35.881Z,1742323595.881 [SBIT](ERROR): Could not read elevatorAngleReader_.
2025-03-18T18:46:35.881Z,1742323595.881 [SBIT](ERROR): Could not read rudderAngleReader_.
2025-03-18T18:46:35.882Z,1742323595.882 [SBIT](ERROR): Could not read massPosReader_.
2025-03-18T18:46:35.882Z,1742323595.882 [SBIT](FAULT): Control surface position failure.
2025-03-18T18:46:36.287Z,1742323596.287 [SBIT](CRITICAL): SBIT FAILED
2025-03-18T18:46:36.287Z,1742323596.287 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-03-18T18:46:36.681Z,1742323596.681 [MissionManager](IMPORTANT): Started mission Startup
2025-03-18T18:46:36.682Z,1742323596.682 [Startup] Running Loop=1
2025-03-18T18:46:36.682Z,1742323596.682 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-18T18:46:36.682Z,1742323596.682 [Startup:A.GoToSurface] Running Loop=1
2025-03-18T18:46:36.682Z,1742323596.682 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-18T18:46:36.682Z,1742323596.682 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-18T18:46:36.683Z,1742323596.683 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-18T18:46:36.683Z,1742323596.683 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-18T18:46:36.684Z,1742323596.684 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-18T18:46:36.684Z,1742323596.684 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-18T18:46:36.684Z,1742323596.684 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-18T18:46:36.686Z,1742323596.686 [Startup:StartupSatComms] Running Loop=1
2025-03-18T18:46:36.686Z,1742323596.686 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-18T18:46:36.686Z,1742323596.686 [Startup:StartupSatComms:A] Running Loop=1
2025-03-18T18:46:37.094Z,1742323597.094 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-18T18:46:39.871Z,1742323599.871 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA1
2025-03-18T18:46:42.891Z,1742323602.891 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA2
2025-03-18T18:46:44.035Z,1742323604.035 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA3
2025-03-18T18:46:45.183Z,1742323605.183 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA4
2025-03-18T18:46:46.119Z,1742323606.119 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA5
2025-03-18T18:46:47.159Z,1742323607.159 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA6
2025-03-18T18:46:48.099Z,1742323608.099 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadA7
2025-03-18T18:47:36.882Z,1742323656.882 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-18T18:46:36.7Z
2025-03-18T18:47:36.882Z,1742323656.882 [Startup:StartupSatComms:A] Stopped
2025-03-18T18:47:36.882Z,1742323656.882 [Startup:StartupSatComms:B] Running Loop=1
2025-03-18T18:47:37.286Z,1742323657.286 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-18T18:47:52.784Z,1742323672.784 [CommandExec](IMPORTANT): got command set platform_communications 1 bool
2025-03-18T18:47:58.859Z,1742323678.859 [CommandExec](IMPORTANT): got command set platform_communications 1 bool
2025-03-18T18:48:37.071Z,1742323717.071 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-18T18:47:36.9Z
2025-03-18T18:48:37.071Z,1742323717.071 [Startup:StartupSatComms:B] Stopped
2025-03-18T18:48:37.071Z,1742323717.071 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-18T18:48:37.071Z,1742323717.071 [Startup:StartupSatComms] Stopped
2025-03-18T18:48:37.071Z,1742323717.071 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-18T18:48:37.072Z,1742323717.072 [Startup](INFO): Completed Startup
2025-03-18T18:48:37.072Z,1742323717.072 [MissionManager](INFO): Startup is completed.
2025-03-18T18:48:37.073Z,1742323717.073 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-18T18:48:37.073Z,1742323717.073 [Startup] Stopped
2025-03-18T18:48:37.073Z,1742323717.073 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-18T18:48:37.073Z,1742323717.073 [Startup:A.GoToSurface] Stopped
2025-03-18T18:48:37.073Z,1742323717.073 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-18T18:48:37.481Z,1742323717.481 [MissionManager](IMPORTANT): Started mission Default
2025-03-18T18:48:37.481Z,1742323717.481 [Default] Running Loop=1
2025-03-18T18:48:37.481Z,1742323717.481 [Default](DEBUG): Aggregate::initialize Default
2025-03-18T18:48:37.481Z,1742323717.481 [Default:B.GoToSurface] Running Loop=1
2025-03-18T18:48:37.482Z,1742323717.482 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-18T18:48:37.482Z,1742323717.482 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-18T18:48:37.482Z,1742323717.482 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-18T18:48:37.482Z,1742323717.482 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-18T18:48:37.483Z,1742323717.483 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-18T18:48:37.483Z,1742323717.483 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-18T18:48:37.483Z,1742323717.483 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-18T18:48:37.484Z,1742323717.484 [Default:A.Wait] Running Loop=1
2025-03-18T18:48:37.484Z,1742323717.484 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-18T18:48:50.812Z,1742323730.812 [Default:A.Wait](INFO): Done Waiting.
2025-03-18T18:48:50.812Z,1742323730.812 [Default:A.Wait] Stopped
2025-03-18T18:48:50.812Z,1742323730.812 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-18T18:48:51.214Z,1742323731.214 [Default:CheckIn] Running Loop=1
2025-03-18T18:48:51.214Z,1742323731.214 [Default:Chec