2025-03-20T18:13:29.438Z,1742494409.438 [Supervisor](DEBUG): Initializing supervisor.
2025-03-20T18:13:29.442Z,1742494409.442 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-20T18:13:29.442Z,1742494409.442 [SyncHandler](INFO): Protected caller Thread ID is 837
2025-03-20T18:13:29.443Z,1742494409.443 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-20T18:13:29.444Z,1742494409.444 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-20T18:13:29.444Z,1742494409.444 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2025-03-20T18:13:29.448Z,1742494409.448 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-20T18:13:29.468Z,1742494409.468 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-20T18:13:29.469Z,1742494409.469 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-20T18:13:29.470Z,1742494409.470 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2025-03-20T18:13:29.474Z,1742494409.474 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-20T18:13:29.475Z,1742494409.475 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-20T18:13:29.475Z,1742494409.475 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2025-03-20T18:13:29.478Z,1742494409.478 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-20T18:13:29.478Z,1742494409.478 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-20T18:13:29.479Z,1742494409.479 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2025-03-20T18:13:29.483Z,1742494409.483 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-20T18:13:29.483Z,1742494409.483 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-20T18:13:29.487Z,1742494409.487 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-20T18:13:29.836Z,1742494409.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-20T18:13:29.838Z,1742494409.838 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-20T18:13:29.929Z,1742494409.929 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-20T18:13:30.046Z,1742494410.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-20T18:13:30.047Z,1742494410.047 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-20T18:13:30.186Z,1742494410.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-20T18:13:30.187Z,1742494410.187 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-20T18:13:30.542Z,1742494410.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-20T18:13:30.542Z,1742494410.542 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-20T18:13:30.648Z,1742494410.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-20T18:13:30.650Z,1742494410.650 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-20T18:13:31.075Z,1742494411.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-20T18:13:31.077Z,1742494411.077 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-20T18:13:31.311Z,1742494411.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-20T18:13:31.313Z,1742494411.313 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-20T18:13:31.403Z,1742494411.403 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-20T18:13:31.653Z,1742494411.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-20T18:13:31.654Z,1742494411.654 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-20T18:13:31.883Z,1742494411.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-20T18:13:31.884Z,1742494411.884 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-20T18:13:32.419Z,1742494412.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-20T18:13:32.420Z,1742494412.420 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-20T18:13:32.620Z,1742494412.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-20T18:13:32.622Z,1742494412.622 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-20T18:13:32.953Z,1742494412.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-20T18:13:32.954Z,1742494412.954 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-20T18:13:33.826Z,1742494413.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-20T18:13:33.827Z,1742494413.827 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-20T18:13:34.402Z,1742494414.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-20T18:13:34.404Z,1742494414.404 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-03-20T18:13:34.406Z,1742494414.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-03-20T18:13:34.513Z,1742494414.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-03-20T18:13:34.655Z,1742494414.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-03-20T18:13:34.750Z,1742494414.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-03-20T18:13:34.868Z,1742494414.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-03-20T18:13:34.966Z,1742494414.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-03-20T18:13:35.237Z,1742494415.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-20T18:13:35.237Z,1742494415.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-03-20T18:13:35.357Z,1742494415.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-03-20T18:13:35.468Z,1742494415.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-03-20T18:13:35.621Z,1742494415.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-03-20T18:13:35.716Z,1742494415.716 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-03-20T18:13:35.720Z,1742494415.720 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-20T18:13:35.889Z,1742494415.889 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-20T18:13:35.890Z,1742494415.890 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-20T18:13:35.962Z,1742494415.962 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-20T18:13:36.024Z,1742494416.024 [VerticalControl] Loaded
2025-03-20T18:13:36.024Z,1742494416.024 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-20T18:13:36.027Z,1742494416.027 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-20T18:13:36.074Z,1742494416.074 [HorizontalControl] Loaded
2025-03-20T18:13:36.075Z,1742494416.075 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-20T18:13:36.077Z,1742494416.077 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-20T18:13:36.080Z,1742494416.080 [SpeedControl] Loaded
2025-03-20T18:13:36.081Z,1742494416.081 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-20T18:13:36.083Z,1742494416.083 [LoopControl](DEBUG): Construct LoopControl.
2025-03-20T18:13:36.084Z,1742494416.084 [LoopControl] Loaded
2025-03-20T18:13:36.084Z,1742494416.084 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-20T18:13:36.084Z,1742494416.084 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-20T18:13:36.086Z,1742494416.086 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-20T18:13:36.172Z,1742494416.172 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-20T18:13:36.172Z,1742494416.172 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-20T18:13:37.014Z,1742494417.014 [AHRS_M2] Loaded
2025-03-20T18:13:37.014Z,1742494417.014 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-20T18:13:37.266Z,1742494417.266 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4
2025-03-20T18:13:37.267Z,1742494417.267 [BackseatComponent] Loaded
2025-03-20T18:13:37.268Z,1742494417.268 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-20T18:13:37.269Z,1742494417.269 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0
2025-03-20T18:13:37.269Z,1742494417.269 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920
2025-03-20T18:13:37.272Z,1742494417.272 [LcmUniversalReporter] Loaded
2025-03-20T18:13:37.272Z,1742494417.272 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-20T18:13:38.067Z,1742494418.067 [BPC1] Loaded
2025-03-20T18:13:38.067Z,1742494418.067 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-20T18:13:38.162Z,1742494418.162 [DAT] Loaded
2025-03-20T18:13:38.162Z,1742494418.162 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-03-20T18:13:38.163Z,1742494418.163 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0
2025-03-20T18:13:38.164Z,1742494418.164 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921
2025-03-20T18:13:38.307Z,1742494418.307 [DataOverHttps] Loaded
2025-03-20T18:13:38.307Z,1742494418.307 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-20T18:13:38.308Z,1742494418.308 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0
2025-03-20T18:13:38.309Z,1742494418.309 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2025-03-20T18:13:38.331Z,1742494418.331 [Depth_Keller] Loaded
2025-03-20T18:13:38.332Z,1742494418.332 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-20T18:13:38.337Z,1742494418.337 [DropWeight] Loaded
2025-03-20T18:13:38.337Z,1742494418.337 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-20T18:13:38.416Z,1742494418.416 [NAL9602] Loaded
2025-03-20T18:13:38.416Z,1742494418.416 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-20T18:13:38.449Z,1742494418.449 [Onboard] Loaded
2025-03-20T18:13:38.449Z,1742494418.449 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-20T18:13:38.450Z,1742494418.450 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0
2025-03-20T18:13:38.450Z,1742494418.450 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2025-03-20T18:13:38.465Z,1742494418.465 [Power24vConverter] Loaded
2025-03-20T18:13:38.465Z,1742494418.465 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-20T18:13:38.481Z,1742494418.481 [Radio_Surface] Loaded
2025-03-20T18:13:38.481Z,1742494418.481 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-20T18:13:38.482Z,1742494418.482 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0
2025-03-20T18:13:38.482Z,1742494418.482 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2025-03-20T18:13:38.498Z,1742494418.498 [Sonardyne_Nano] Loaded
2025-03-20T18:13:38.498Z,1742494418.498 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-20T18:13:38.522Z,1742494418.522 [Waterlinked] Loaded
2025-03-20T18:13:38.522Z,1742494418.522 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-03-20T18:13:38.523Z,1742494418.523 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-20T18:13:38.524Z,1742494418.524 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-20T18:13:38.539Z,1742494418.539 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-20T18:13:38.540Z,1742494418.540 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-20T18:13:38.587Z,1742494418.587 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-20T18:13:38.588Z,1742494418.588 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-20T18:13:38.800Z,1742494418.800 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-20T18:13:38.810Z,1742494418.810 [SBIT] Loaded
2025-03-20T18:13:38.810Z,1742494418.810 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-20T18:13:38.813Z,1742494418.813 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-20T18:13:38.825Z,1742494418.825 [IBIT] Loaded
2025-03-20T18:13:38.826Z,1742494418.826 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-20T18:13:38.831Z,1742494418.831 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-20T18:13:38.911Z,1742494418.911 [CBIT] Loaded
2025-03-20T18:13:38.911Z,1742494418.911 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-20T18:13:38.943Z,1742494418.943 [GFScanner] Loaded
2025-03-20T18:13:38.944Z,1742494418.944 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-20T18:13:38.944Z,1742494418.944 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-20T18:13:38.945Z,1742494418.945 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-20T18:13:39.362Z,1742494419.362 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-20T18:13:39.363Z,1742494419.363 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-20T18:13:39.435Z,1742494419.435 [DepthRateCalculator] Loaded
2025-03-20T18:13:39.435Z,1742494419.435 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-20T18:13:39.440Z,1742494419.440 [PitchRateCalculator] Loaded
2025-03-20T18:13:39.440Z,1742494419.440 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-20T18:13:39.451Z,1742494419.451 [SpeedCalculator] Loaded
2025-03-20T18:13:39.451Z,1742494419.451 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-20T18:13:39.455Z,1742494419.455 [YawRateCalculator] Loaded
2025-03-20T18:13:39.456Z,1742494419.456 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-20T18:13:39.475Z,1742494419.475 [ElevatorOffsetCalculator] Loaded
2025-03-20T18:13:39.475Z,1742494419.475 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-20T18:13:39.475Z,1742494419.475 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-20T18:13:39.476Z,1742494419.476 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-20T18:13:39.695Z,1742494419.695 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-20T18:13:39.696Z,1742494419.696 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-20T18:13:39.835Z,1742494419.835 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-20T18:13:39.835Z,1742494419.835 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-20T18:13:39.849Z,1742494419.849 [NavChart] Loaded
2025-03-20T18:13:39.849Z,1742494419.849 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-20T18:13:39.855Z,1742494419.855 [UniversalFixResidualReporter] Loaded
2025-03-20T18:13:39.855Z,1742494419.855 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-20T18:13:39.855Z,1742494419.855 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-20T18:13:39.856Z,1742494419.856 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-20T18:13:40.024Z,1742494420.024 [BuoyancyServo] Loaded
2025-03-20T18:13:40.024Z,1742494420.024 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-20T18:13:40.047Z,1742494420.047 [ElevatorServo] Loaded
2025-03-20T18:13:40.048Z,1742494420.048 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-20T18:13:40.069Z,1742494420.069 [MassServo] Loaded
2025-03-20T18:13:40.070Z,1742494420.070 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-20T18:13:40.090Z,1742494420.090 [RudderServo] Loaded
2025-03-20T18:13:40.091Z,1742494420.091 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-20T18:13:40.108Z,1742494420.108 [ThrusterHE] Loaded
2025-03-20T18:13:40.108Z,1742494420.108 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-20T18:13:40.108Z,1742494420.108 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-20T18:13:40.109Z,1742494420.109 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-20T18:13:40.325Z,1742494420.325 [CTD_Seabird] Loaded
2025-03-20T18:13:40.325Z,1742494420.325 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-20T18:13:40.326Z,1742494420.326 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0
2025-03-20T18:13:40.326Z,1742494420.326 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925
2025-03-20T18:13:40.363Z,1742494420.363 [ESPComponent] Loaded
2025-03-20T18:13:40.363Z,1742494420.363 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-03-20T18:13:40.386Z,1742494420.386 [PAR_Licor] Loaded
2025-03-20T18:13:40.387Z,1742494420.387 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-20T18:13:40.432Z,1742494420.432 [WetLabsBB2FL] Loaded
2025-03-20T18:13:40.433Z,1742494420.433 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-20T18:13:40.434Z,1742494420.434 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0
2025-03-20T18:13:40.434Z,1742494420.434 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926
2025-03-20T18:13:40.435Z,1742494420.435 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-20T18:13:40.441Z,1742494420.441 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-20T18:13:40.444Z,1742494420.444 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-20T18:13:40.455Z,1742494420.455 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-20T18:13:40.456Z,1742494420.456 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-03-20T18:13:40.457Z,1742494420.457 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927
2025-03-20T18:13:40.461Z,1742494420.461 [Supervisor](INFO): Main Thread ID is 828
2025-03-20T18:13:40.461Z,1742494420.461 [Supervisor](DEBUG): Running supervisor.
2025-03-20T18:13:40.462Z,1742494420.462 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928
2025-03-20T18:13:40.462Z,1742494420.462 [CommandExec](INFO): Initializing the command executive.
2025-03-20T18:13:40.464Z,1742494420.464 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929
2025-03-20T18:13:40.466Z,1742494420.466 [controlThread ThreadHandler](INFO): Handler Thread ID is 930
2025-03-20T18:13:40.466Z,1742494420.466 [controlThread](DEBUG): Initializing ControlThread
2025-03-20T18:13:40.467Z,1742494420.467 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-20T18:13:40.469Z,1742494420.469 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-20T18:13:40.470Z,1742494420.470 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-20T18:13:40.470Z,1742494420.470 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-20T18:13:40.474Z,1742494420.474 [SBIT](INFO): Initialize SBIT Component.
2025-03-20T18:13:40.474Z,1742494420.474 [SBIT](IMPORTANT): git: 2025-03-12_C-7-g9e16b3bff
2025-03-20T18:13:40.474Z,1742494420.474 [SBIT](INFO): git hash: 9e16b3bff22d42af46d832863712edbefb75a998
2025-03-20T18:13:40.475Z,1742494420.475 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-20T18:13:40.476Z,1742494420.476 [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-20T18:13:40.477Z,1742494420.477 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2025-03-20T18:13:40.478Z,1742494420.478 [IBIT](INFO): Initialize IBIT Component.
2025-03-20T18:13:40.478Z,1742494420.478 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-20T18:13:40.479Z,1742494420.479 [logger ThreadHandler](INFO): Handler Thread ID is 931
2025-03-20T18:13:40.492Z,1742494420.492 [CBIT](DEBUG): Initialized mux pins.
2025-03-20T18:13:40.492Z,1742494420.492 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-03-20T18:13:40.492Z,1742494420.492 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-20T18:13:40.500Z,1742494420.500 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932
2025-03-20T18:13:40.516Z,1742494420.516 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-20T18:13:40.516Z,1742494420.516 [CBIT](DEBUG): Initializing heartbeat.
2025-03-20T18:13:40.512Z,1742494420.512 [DAT ThreadHandler](INFO): Handler Thread ID is 933
2025-03-20T18:13:40.517Z,1742494420.517 [DAT](INFO): Powering up
2025-03-20T18:13:40.517Z,1742494420.517 [DAT](DEBUG): Initializing DAT.
2025-03-20T18:13:40.521Z,1742494420.521 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2025-03-20T18:13:40.523Z,1742494420.523 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-20T18:13:40.544Z,1742494420.544 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2025-03-20T18:13:40.561Z,1742494420.561 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2025-03-20T18:13:40.582Z,1742494420.582 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
2025-03-20T18:13:40.583Z,1742494420.583 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-20T18:13:40.588Z,1742494420.588 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-20T18:13:40.589Z,1742494420.589 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940
2025-03-20T18:13:40.592Z,1742494420.592 [WetLabsBB2FL](INFO): Powering up
2025-03-20T18:13:40.593Z,1742494420.593 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2025-03-20T18:13:40.600Z,1742494420.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-20T18:13:40.601Z,1742494420.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-20T18:13:40.601Z,1742494420.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-20T18:13:40.601Z,1742494420.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-20T18:13:40.601Z,1742494420.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-20T18:13:40.601Z,1742494420.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-20T18:13:40.602Z,1742494420.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-20T18:13:40.602Z,1742494420.602 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-20T18:13:40.628Z,1742494420.628 [CBIT](DEBUG): Backplane powered.
2025-03-20T18:13:40.628Z,1742494420.628 [GFScanner](DEBUG): Initializing GFScanner
2025-03-20T18:13:40.628Z,1742494420.628 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-20T18:13:40.629Z,1742494420.629 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-20T18:13:40.629Z,1742494420.629 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-20T18:13:40.630Z,1742494420.630 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-20T18:13:40.630Z,1742494420.630 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-20T18:13:40.631Z,1742494420.631 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-20T18:13:40.631Z,1742494420.631 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-20T18:13:40.636Z,1742494420.636 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-20T18:13:40.637Z,1742494420.637 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-20T18:13:40.637Z,1742494420.637 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-20T18:13:40.642Z,1742494420.642 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-20T18:13:40.725Z,1742494420.725 [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-20T18:13:40.729Z,1742494420.729 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-20T18:13:40.774Z,1742494420.774 [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-20T18:13:40.775Z,1742494420.775 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-20T18:13:40.775Z,1742494420.775 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-20T18:13:40.778Z,1742494420.778 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-20T18:13:40.968Z,1742494420.968 [Radio_Surface](INFO): Powering up
2025-03-20T18:13:41.064Z,1742494421.064 [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-20T18:13:41.086Z,1742494421.086 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-20T18:13:41.088Z,1742494421.088 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-20T18:13:41.090Z,1742494421.090 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-20T18:13:41.152Z,1742494421.152 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-20T18:13:41.154Z,1742494421.154 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-20T18:13:41.176Z,1742494421.176 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-20T18:13:41.188Z,1742494421.188 [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-20T18:13:41.193Z,1742494421.193 [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-20T18:13:41.213Z,1742494421.213 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-20T18:13:41.381Z,1742494421.381 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2025-03-20T18:13:41.382Z,1742494421.382 [Power24vConverter](INFO): Powering up.
2025-03-20T18:13:41.383Z,1742494421.383 [Sonardyne_Nano](INFO): Initializing.
2025-03-20T18:13:41.426Z,1742494421.426 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-20T18:13:41.449Z,1742494421.449 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-20T18:13:41.456Z,1742494421.456 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-20T18:13:41.457Z,1742494421.457 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-20T18:13:41.464Z,1742494421.464 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-20T18:13:41.465Z,1742494421.465 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-20T18:13:41.472Z,1742494421.472 [MassServo](DEBUG): Initializing MassServo.
2025-03-20T18:13:41.473Z,1742494421.473 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-20T18:13:41.480Z,1742494421.480 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-20T18:13:41.481Z,1742494421.481 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-20T18:13:41.488Z,1742494421.488 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-20T18:13:41.491Z,1742494421.491 [CBIT](FAULT): Main Battery Failure
2025-03-20T18:13:41.638Z,1742494421.638 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-03-20T18:13:41.638Z,1742494421.638 [DropWeight] Hardware Fault, FailCount= 1
2025-03-20T18:13:41.638Z,1742494421.638 [DropWeight](ERROR): Hardware Fault
2025-03-20T18:13:41.665Z,1742494421.665 [CommandExec](FAULT): Scheduling is paused
2025-03-20T18:13:41.665Z,1742494421.665 [CBIT](INFO): Critical error at 20250320T181341
2025-03-20T18:13:41.666Z,1742494421.666 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-20T18:13:41.677Z,1742494421.677 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-20T18:13:41.677Z,1742494421.677 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-20T18:13:42.031Z,1742494422.031 [CBIT](INFO): Critical error at 20250320T181341
2025-03-20T18:13:42.720Z,1742494422.720 [WetLabsBB2FL](INFO): Powering down
2025-03-20T18:13:43.626Z,1742494423.626 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-20T18:13:44.872Z,1742494424.872 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-03-20T18:13:53.644Z,1742494433.644 [DAT](INFO): DAT read: 

2025-03-20T18:13:53.646Z,1742494433.646 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-03-20T18:13:55.157Z,1742494435.157 [DAT](INFO): DAT read: MF Frequency Band

2025-03-20T18:13:55.158Z,1742494435.158 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-03-20T18:13:55.159Z,1742494435.159 [DAT](INFO): DAT read: Mar 20 2025 18:13:50 

2025-03-20T18:13:56.417Z,1742494436.417 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-03-20T18:13:56.418Z,1742494436.418 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-03-20T18:13:56.418Z,1742494436.418 [DAT](INFO): commRate: 800
2025-03-20T18:13:58.484Z,1742494438.484 [DAT](INFO): entering command mode
2025-03-20T18:13:58.684Z,1742494438.684 [DAT](INFO): DAT read: 

2025-03-20T18:13:58.685Z,1742494438.685 [DAT](INFO): DAT read: user:1>

2025-03-20T18:13:58.685Z,1742494438.685 [DAT](INFO): setting verbose to 3
2025-03-20T18:13:58.937Z,1742494438.937 [DAT](INFO): DAT read: user:1>

2025-03-20T18:13:58.938Z,1742494438.938 [DAT](INFO): DAT read: Verbose         | 3           

2025-03-20T18:13:58.938Z,1742494438.938 [DAT](INFO): set verbose to 3
2025-03-20T18:13:58.938Z,1742494438.938 [DAT](INFO): setting DatVerbose to 27440
2025-03-20T18:13:59.188Z,1742494439.188 [DAT](INFO): DAT read: user:2>

2025-03-20T18:13:59.189Z,1742494439.189 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-03-20T18:13:59.190Z,1742494439.190 [DAT](INFO): set DatVerbose to 27440
2025-03-20T18:13:59.190Z,1742494439.190 [DAT](INFO): setting transmit power to 8
2025-03-20T18:13:59.443Z,1742494439.443 [DAT](INFO): DAT read: user:3>

2025-03-20T18:13:59.444Z,1742494439.444 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-03-20T18:13:59.445Z,1742494439.445 [DAT](INFO): set transmit power to 8
2025-03-20T18:13:59.445Z,1742494439.445 [DAT](INFO): setting local address to 1
2025-03-20T18:13:59.692Z,1742494439.692 [DAT](INFO): DAT read: user:4>

2025-03-20T18:13:59.693Z,1742494439.693 [DAT](INFO): DAT read: LocalAddr       | 1           

2025-03-20T18:13:59.694Z,1742494439.694 [DAT](INFO): set local address to 1
2025-03-20T18:13:59.695Z,1742494439.695 [DAT](INFO): Setting time to: 18:13:59 And date to:3/20/2025
2025-03-20T18:13:59.945Z,1742494439.945 [DAT](INFO): DAT read: user:5>

2025-03-20T18:13:59.945Z,1742494439.945 [DAT](INFO): DAT read: Thu Mar 20, 2025  18:13:59  

2025-03-20T18:13:59.946Z,1742494439.946 [DAT](INFO): Local DAT time set to Thu Mar 20, 2025  18:13:59  

2025-03-20T18:14:08.785Z,1742494448.785 [NAL9602](INFO): Powering up NAL9602
2025-03-20T18:14:18.636Z,1742494458.636 [Waterlinked](INFO): Powering down
2025-03-20T18:14:19.737Z,1742494459.737 [NAL9602](INFO): NAL9602 initialized
2025-03-20T18:14:52.077Z,1742494492.077 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-20T18:15:45.823Z,1742494545.823 [SBIT](IMPORTANT): SBIT PASSED
2025-03-20T18:15:45.823Z,1742494545.823 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-03-20T18:15:46.202Z,1742494546.202 [MissionManager](IMPORTANT): Started mission Startup
2025-03-20T18:15:46.202Z,1742494546.202 [Startup] Running Loop=1
2025-03-20T18:15:46.202Z,1742494546.202 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-20T18:15:46.202Z,1742494546.202 [Startup:A.GoToSurface] Running Loop=1
2025-03-20T18:15:46.202Z,1742494546.202 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-20T18:15:46.203Z,1742494546.203 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-20T18:15:46.203Z,1742494546.203 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-20T18:15:46.204Z,1742494546.204 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-20T18:15:46.204Z,1742494546.204 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-20T18:15:46.204Z,1742494546.204 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-20T18:15:46.205Z,1742494546.205 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-20T18:15:46.206Z,1742494546.206 [Startup:StartupSatComms] Running Loop=1
2025-03-20T18:15:46.206Z,1742494546.206 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-20T18:15:46.207Z,1742494546.207 [Startup:StartupSatComms:A] Running Loop=1
2025-03-20T18:15:46.606Z,1742494546.606 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-20T18:16:24.701Z,1742494584.701 [CommandExec](IMPORTANT): got command failComponent
2025-03-20T18:16:24.701Z,1742494584.701 [CommandExec](IMPORTANT): Failed components:
2025-03-20T18:16:24.701Z,1742494584.701 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2025-03-20T18:16:40.994Z,1742494600.994 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-20T18:16:40.994Z,1742494600.994 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-20T18:16:41.042Z,1742494601.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-20T18:16:41.424Z,1742494601.424 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-20T18:16:41.424Z,1742494601.424 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-20T18:16:44.273Z,1742494604.273 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005586
2025-03-20T18:16:46.243Z,1742494606.243 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-20T18:15:46.2Z
2025-03-20T18:16:46.243Z,1742494606.243 [Startup:StartupSatComms:A] Stopped
2025-03-20T18:16:46.243Z,1742494606.243 [Startup:StartupSatComms:B] Running Loop=1
2025-03-20T18:16:46.638Z,1742494606.638 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-20T18:16:53.586Z,1742494613.586 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250320T180638/Courier0004.lzma
2025-03-20T18:16:54.586Z,1742494614.586 [DataOverHttps](INFO): Moved sent file to Logs/20250320T180638/Courier0004.lzma.bak
2025-03-20T18:16:54.586Z,1742494614.586 [DataOverHttps](INFO): SBD MOMSN=24428734
2025-03-20T18:17:10.156Z,1742494630.156 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250320T181329/Courier0000.lzma
2025-03-20T18:17:11.158Z,1742494631.158 [DataOverHttps](INFO): Moved sent file to Logs/20250320T181329/Courier0000.lzma.bak
2025-03-20T18:17:11.158Z,1742494631.158 [DataOverHttps](INFO): SBD MOMSN=24428736
2025-03-20T18:17:26.704Z,1742494646.704 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250320T175203/Express0008.lzma
2025-03-20T18:17:27.706Z,1742494647.706 [DataOverHttps](INFO): Moved sent file to Logs/20250320T175203/Express0008.lzma.bak
2025-03-20T18:17:27.706Z,1742494647.706 [DataOverHttps](INFO): SBD MOMSN=24428741
2025-03-20T18:17:43.304Z,1742494663.304 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250320T180437/Express0001.lzma
2025-03-20T18:17:44.306Z,1742494664.306 [DataOverHttps](INFO): Moved sent file to Logs/20250320T180437/Express0001.lzma.bak
2025-03-20T18:17:44.306Z,1742494664.306 [DataOverHttps](INFO): SBD MOMSN=24428743
2025-03-20T18:17:46.444Z,1742494666.444 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-20T18:16:46.2Z
2025-03-20T18:17:46.444Z,1742494666.444 [Startup:StartupSatComms:B] Stopped
2025-03-20T18:17:46.444Z,1742494666.444 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-20T18:17:46.444Z,1742494666.444 [Startup:StartupSatComms] Stopped
2025-03-20T18:17:46.444Z,1742494666.444 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-20T18:17:46.445Z,1742494666.445 [Startup](INFO): Completed Startup
2025-03-20T18:17:46.445Z,1742494666.445 [MissionManager](INFO): Startup is completed.
2025-03-20T18:17:46.446Z,1742494666.446 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-20T18:17:46.446Z,1742494666.446 [Startup] Stopped
2025-03-20T18:17:46.446Z,1742494666.446 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-20T18:17:46.446Z,1742494666.446 [Startup:A.GoToSurface] Stopped
2025-03-20T18:17:46.446Z,1742494666.446 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-20T18:17:46.839Z,1742494666.839 [MissionManager](IMPORTANT): Started mission Default
2025-03-20T18:17:46.839Z,1742494666.839 [Default] Running Loop=1
2025-03-20T18:17:46.839Z,1742494666.839 [Default](DEBUG): Aggregate::initialize Default
2025-03-20T18:17:46.839Z,1742494666.839 [Default:B.GoToSurface] Running Loop=1
2025-03-20T18:17:46.839Z,1742494666.839 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-20T18:17:46.839Z,1742494666.839 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-20T18:17:46.840Z,1742494666.840 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-20T18:17:46.840Z,1742494666.840 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-20T18:17:46.840Z,1742494666.840 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-20T18:17:46.841Z,1742494666.841 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-20T18:17:46.841Z,1742494666.841 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-20T18:17:46.841Z,1742494666.841 [Default:A.Wait] Running Loop=1
2025-03-20T18:17:46.841Z,1742494666.841 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-20T18:18:00.024Z,1742494680.024 [DataOverHttps](INFO): Sending 914 bytes from file Logs/20250320T180638/Express0001.lzma
2025-03-20T18:18:00.167Z,1742494680.167 [Default:A.Wait](INFO): Done Waiting.
2025-03-20T18:18:00.167Z,1742494680.167 [Default:A.Wait] Stopped
2025-03-20T18:18:00.167Z,1742494680.167 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-20T18:18:00.575Z,1742494680.575 [Default:CheckIn] Running Loop=1
2025-03-20T18:18:00.575Z,1742494680.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-20T18:18:00.575Z,1742494680.575 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-20T18:18:00.985Z,1742494680.985 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-20T18:18:01.022Z,1742494681.022 [DataOverHttps](INFO): Moved sent file to Logs/20250320T180638/Express0001.lzma.bak
2025-03-20T18:18:01.022Z,1742494681.022 [DataOverHttps](INFO): SBD MOMSN=24428745
2025-03-20T18:18:41.758Z,1742494721.758 [BPC1](FAULT): Failed to initialize
2025-03-20T18:18:41.758Z,1742494721.758 [BPC1] Communications Fault, FailCount= 1
2025-03-20T18:18:41.758Z,1742494721.758 [BPC1](ERROR): Communications Fault
2025-03-20T18:18:41.819Z,1742494721.819 [CBIT](ERROR): Communications Fault in component: BPC1
2025-03-20T18:19:09.206Z,1742494749.206 [CommandExec](IMPORTANT): got command restart application
2025-03-20T18:19:10.208Z,1742494750.208 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:10.209Z,1742494750.209 [CommandExec](INFO): Uninitializing the command executive.
2025-03-20T18:19:10.209Z,1742494750.209 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-20T18:19:10.209Z,1742494750.209 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.368Z,1742494750.368 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-03-20T18:19:10.368Z,1742494750.368 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-20T18:19:10.369Z,1742494750.369 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.369Z,1742494750.369 [NavChartDb](INFO): Join timeout helper Thread ID is 984
2025-03-20T18:19:10.373Z,1742494750.373 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:10.373Z,1742494750.373 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.388Z,1742494750.388 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-20T18:19:10.388Z,1742494750.388 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.389Z,1742494750.389 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 985
2025-03-20T18:19:10.441Z,1742494750.441 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:10.441Z,1742494750.441 [WetLabsBB2FL](INFO): Powering down
2025-03-20T18:19:10.442Z,1742494750.442 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.448Z,1742494750.448 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-20T18:19:10.449Z,1742494750.449 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.449Z,1742494750.449 [CTD_Seabird](INFO): Join timeout helper Thread ID is 986
2025-03-20T18:19:10.573Z,1742494750.573 [CTD_Seabird](INFO): Powering down
2025-03-20T18:19:10.584Z,1742494750.584 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:10.585Z,1742494750.585 [CTD_Seabird](INFO): Powering down
2025-03-20T18:19:10.596Z,1742494750.596 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.602Z,1742494750.602 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-20T18:19:10.602Z,1742494750.602 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.602Z,1742494750.602 [Radio_Surface](INFO): Join timeout helper Thread ID is 987
2025-03-20T18:19:10.973Z,1742494750.973 [Radio_Surface](INFO): Powering down
2025-03-20T18:19:10.974Z,1742494750.974 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:10.974Z,1742494750.974 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.976Z,1742494750.976 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-20T18:19:10.976Z,1742494750.976 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:10.977Z,1742494750.977 [Onboard](INFO): Join timeout helper Thread ID is 988
2025-03-20T18:19:11.456Z,1742494751.456 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:11.457Z,1742494751.457 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:11.461Z,1742494751.461 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-20T18:19:11.461Z,1742494751.461 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:11.462Z,1742494751.462 [DataOverHttps](INFO): Join timeout helper Thread ID is 989
2025-03-20T18:19:11.969Z,1742494751.969 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:11.969Z,1742494751.969 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:11.985Z,1742494751.985 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-03-20T18:19:11.985Z,1742494751.985 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:11.986Z,1742494751.986 [DAT](INFO): Join timeout helper Thread ID is 990
2025-03-20T18:19:12.056Z,1742494752.056 [DAT](INFO): Powering down
2025-03-20T18:19:12.138Z,1742494752.138 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:12.138Z,1742494752.138 [DAT](INFO): Powering down
2025-03-20T18:19:12.139Z,1742494752.139 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.145Z,1742494752.145 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-03-20T18:19:12.145Z,1742494752.145 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.146Z,1742494752.146 [BackseatComponent](INFO): Join timeout helper Thread ID is 991
2025-03-20T18:19:12.189Z,1742494752.189 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:12.189Z,1742494752.189 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.205Z,1742494752.205 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-20T18:19:12.205Z,1742494752.205 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.206Z,1742494752.206 [logger](INFO): Join timeout helper Thread ID is 992
2025-03-20T18:19:12.228Z,1742494752.228 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:12.229Z,1742494752.229 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.237Z,1742494752.237 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-20T18:19:12.237Z,1742494752.237 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.238Z,1742494752.238 [CommandLine](INFO): Join timeout helper Thread ID is 993
2025-03-20T18:19:12.317Z,1742494752.317 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:12.317Z,1742494752.317 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.337Z,1742494752.337 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-20T18:19:12.337Z,1742494752.337 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.337Z,1742494752.337 [CommandExec](INFO): Join timeout helper Thread ID is 994
2025-03-20T18:19:12.339Z,1742494752.339 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-20T18:19:12.339Z,1742494752.339 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.339Z,1742494752.339 [controlThread](INFO): Join timeout helper Thread ID is 995
2025-03-20T18:19:12.452Z,1742494752.452 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-20T18:19:12.453Z,1742494752.453 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-20T18:19:12.453Z,1742494752.453 [AHRS_M2](INFO): Powering down
2025-03-20T18:19:12.525Z,1742494752.525 [NAL9602](INFO): Powering down
2025-03-20T18:19:12.526Z,1742494752.526 [Sonardyne_Nano](INFO): Powering down
2025-03-20T18:19:12.645Z,1742494752.645 [Waterlinked](INFO): Powering down
2025-03-20T18:19:12.647Z,1742494752.647 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-20T18:19:12.648Z,1742494752.648 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-20T18:19:12.649Z,1742494752.649 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-20T18:19:12.649Z,1742494752.649 [MissionManager](INFO): Uninitializing Mission Default
2025-03-20T18:19:12.649Z,1742494752.649 [Default] Stopped
2025-03-20T18:19:12.649Z,1742494752.649 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-20T18:19:12.649Z,1742494752.649 [Default:B.GoToSurface] Stopped
2025-03-20T18:19:12.649Z,1742494752.649 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-20T18:19:12.650Z,1742494752.649 [Default:CheckIn] Stopped
2025-03-20T18:19:12.650Z,1742494752.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-20T18:19:12.650Z,1742494752.650 [Default:CheckIn:Read_GPS] Stopped
2025-03-20T18:19:12.653Z,1742494752.653 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-20T18:19:12.653Z,1742494752.653 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-20T18:19:12.653Z,1742494752.653 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-20T18:19:12.654Z,1742494752.654 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-20T18:19:12.654Z,1742494752.654 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-20T18:19:12.654Z,1742494752.654 [BuoyancyServo](INFO): Powering down
2025-03-20T18:19:12.669Z,1742494752.669 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-20T18:19:12.669Z,1742494752.669 [ElevatorServo](INFO): Powering down
2025-03-20T18:19:12.669Z,1742494752.669 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-20T18:19:12.670Z,1742494752.670 [MassServo](INFO): Powering down
2025-03-20T18:19:12.670Z,1742494752.670 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-20T18:19:12.670Z,1742494752.670 [RudderServo](INFO): Powering down
2025-03-20T18:19:12.671Z,1742494752.671 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-20T18:19:12.671Z,1742494752.671 [ThrusterHE](INFO): Powering down
2025-03-20T18:19:12.672Z,1742494752.672 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-20T18:19:12.673Z,1742494752.673 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-20T18:19:12.673Z,1742494752.673 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-20T18:19:12.673Z,1742494752.673 [CBIT](DEBUG): Powering off loads.
2025-03-20T18:19:12.684Z,1742494752.684 [CBIT](DEBUG): Disabling WDT.
2025-03-20T18:19:12.697Z,1742494752.697 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-20T18:19:12.697Z,1742494752.697 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.741Z,1742494752.741 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.745Z,1742494752.745 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.750Z,1742494752.750 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.757Z,1742494752.757 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.798Z,1742494752.798 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.836Z,1742494752.836 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-20T18:19:12.897Z,1742494752.897 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.903Z,1742494752.903 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.911Z,1742494752.911 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-20T18:19:12.975Z,1742494752.975 [logger ThreadHandler](INFO): Thread cancelled.