2026-03-10T19:56:18.042Z,1773172578.042 [Supervisor](DEBUG): Initializing supervisor.
2026-03-10T19:56:18.047Z,1773172578.047 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-10T19:56:18.047Z,1773172578.047 [SyncHandler](INFO): Protected caller Thread ID is 1604
2026-03-10T19:56:18.048Z,1773172578.048 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-10T19:56:18.049Z,1773172578.049 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-10T19:56:18.049Z,1773172578.049 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1605
2026-03-10T19:56:18.053Z,1773172578.053 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-10T19:56:18.074Z,1773172578.074 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-10T19:56:18.075Z,1773172578.075 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-10T19:56:18.076Z,1773172578.076 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1606
2026-03-10T19:56:18.080Z,1773172578.080 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-10T19:56:18.081Z,1773172578.081 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-10T19:56:18.081Z,1773172578.081 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1607
2026-03-10T19:56:18.083Z,1773172578.083 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-10T19:56:18.084Z,1773172578.084 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-10T19:56:18.084Z,1773172578.084 [logger ThreadHandler](INFO): Protected caller Thread ID is 1608
2026-03-10T19:56:18.088Z,1773172578.088 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-10T19:56:18.088Z,1773172578.088 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-10T19:56:18.090Z,1773172578.090 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-10T19:56:18.377Z,1773172578.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-10T19:56:18.378Z,1773172578.378 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-10T19:56:18.582Z,1773172578.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-10T19:56:18.583Z,1773172578.583 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-10T19:56:18.694Z,1773172578.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-10T19:56:18.695Z,1773172578.695 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-10T19:56:18.869Z,1773172578.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-10T19:56:18.869Z,1773172578.869 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-10T19:56:19.339Z,1773172579.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-10T19:56:19.340Z,1773172579.340 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-10T19:56:19.452Z,1773172579.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-10T19:56:19.453Z,1773172579.453 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-10T19:56:19.712Z,1773172579.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-10T19:56:19.712Z,1773172579.712 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-10T19:56:19.930Z,1773172579.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-10T19:56:19.931Z,1773172579.931 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-10T19:56:20.438Z,1773172580.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-10T19:56:20.439Z,1773172580.439 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-10T19:56:20.831Z,1773172580.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-10T19:56:20.831Z,1773172580.831 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-10T19:56:20.918Z,1773172580.918 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-10T19:56:21.282Z,1773172581.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-10T19:56:21.282Z,1773172581.282 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-10T19:56:21.425Z,1773172581.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-10T19:56:21.426Z,1773172581.426 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-10T19:56:22.311Z,1773172582.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-10T19:56:22.311Z,1773172582.311 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-10T19:56:22.641Z,1773172582.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-10T19:56:22.641Z,1773172582.641 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-10T19:56:22.754Z,1773172582.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-10T19:56:22.756Z,1773172582.756 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2026-03-10T19:56:22.757Z,1773172582.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2026-03-10T19:56:22.861Z,1773172582.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2026-03-10T19:56:22.973Z,1773172582.973 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2026-03-10T19:56:23.086Z,1773172583.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2026-03-10T19:56:23.181Z,1773172583.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2026-03-10T19:56:23.273Z,1773172583.273 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2026-03-10T19:56:23.381Z,1773172583.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2026-03-10T19:56:23.523Z,1773172583.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2026-03-10T19:56:23.686Z,1773172583.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2026-03-10T19:56:23.797Z,1773172583.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2026-03-10T19:56:24.067Z,1773172584.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-10T19:56:24.068Z,1773172584.068 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/
2026-03-10T19:56:24.069Z,1773172584.069 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-10T19:56:31.248Z,1773172591.248 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-10T19:56:31.594Z,1773172591.594 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-10T19:56:31.595Z,1773172591.595 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-10T19:56:32.166Z,1773172592.166 [BackseatComponent] Loaded
2026-03-10T19:56:32.167Z,1773172592.167 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2026-03-10T19:56:32.168Z,1773172592.168 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2026-03-10T19:56:32.168Z,1773172592.168 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1688
2026-03-10T19:56:32.171Z,1773172592.171 [LcmPublisher] Loaded
2026-03-10T19:56:32.172Z,1773172592.172 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2026-03-10T19:56:33.332Z,1773172593.332 [BPC1] Loaded
2026-03-10T19:56:33.332Z,1773172593.332 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-10T19:56:33.437Z,1773172593.437 [DAT] Loaded
2026-03-10T19:56:33.437Z,1773172593.437 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-03-10T19:56:33.438Z,1773172593.438 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2026-03-10T19:56:33.439Z,1773172593.439 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1689
2026-03-10T19:56:33.510Z,1773172593.510 [DataOverHttps] Loaded
2026-03-10T19:56:33.511Z,1773172593.511 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-10T19:56:33.512Z,1773172593.512 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2026-03-10T19:56:33.512Z,1773172593.512 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1690
2026-03-10T19:56:33.535Z,1773172593.535 [Depth_Keller] Loaded
2026-03-10T19:56:33.536Z,1773172593.536 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-10T19:56:33.600Z,1773172593.600 [NAL9602] Loaded
2026-03-10T19:56:33.600Z,1773172593.600 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-10T19:56:33.630Z,1773172593.630 [Onboard] Loaded
2026-03-10T19:56:33.631Z,1773172593.631 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-10T19:56:33.632Z,1773172593.632 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2026-03-10T19:56:33.632Z,1773172593.632 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1691
2026-03-10T19:56:33.646Z,1773172593.646 [Power24vConverter] Loaded
2026-03-10T19:56:33.646Z,1773172593.646 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-10T19:56:33.662Z,1773172593.662 [Radio_Surface] Loaded
2026-03-10T19:56:33.663Z,1773172593.663 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-10T19:56:33.664Z,1773172593.664 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2026-03-10T19:56:33.664Z,1773172593.664 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1692
2026-03-10T19:56:33.679Z,1773172593.679 [Sonardyne_Nano] Loaded
2026-03-10T19:56:33.679Z,1773172593.679 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-10T19:56:33.680Z,1773172593.680 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-10T19:56:33.680Z,1773172593.680 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-10T19:56:33.815Z,1773172593.815 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-10T19:56:33.815Z,1773172593.815 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-10T19:56:33.873Z,1773172593.873 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-10T19:56:33.931Z,1773172593.931 [VerticalControl] Loaded
2026-03-10T19:56:33.932Z,1773172593.932 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-10T19:56:33.934Z,1773172593.934 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-10T19:56:33.982Z,1773172593.982 [HorizontalControl] Loaded
2026-03-10T19:56:33.982Z,1773172593.982 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-10T19:56:33.984Z,1773172593.984 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-10T19:56:33.988Z,1773172593.988 [SpeedControl] Loaded
2026-03-10T19:56:33.988Z,1773172593.988 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-10T19:56:33.991Z,1773172593.991 [LoopControl](DEBUG): Construct LoopControl.
2026-03-10T19:56:33.991Z,1773172593.991 [LoopControl] Loaded
2026-03-10T19:56:33.991Z,1773172593.991 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-10T19:56:33.992Z,1773172593.992 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-10T19:56:33.992Z,1773172593.992 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-10T19:56:34.002Z,1773172594.002 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-10T19:56:34.003Z,1773172594.003 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-10T19:56:34.032Z,1773172594.032 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-10T19:56:34.032Z,1773172594.032 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-10T19:56:34.146Z,1773172594.146 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-10T19:56:34.146Z,1773172594.146 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-10T19:56:34.160Z,1773172594.160 [NavChart] Loaded
2026-03-10T19:56:34.160Z,1773172594.160 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-10T19:56:34.165Z,1773172594.165 [UniversalFixResidualReporter] Loaded
2026-03-10T19:56:34.166Z,1773172594.166 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-10T19:56:34.166Z,1773172594.166 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-10T19:56:34.167Z,1773172594.167 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-10T19:56:34.328Z,1773172594.328 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-10T19:56:34.337Z,1773172594.337 [SBIT] Loaded
2026-03-10T19:56:34.337Z,1773172594.337 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-10T19:56:34.340Z,1773172594.340 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-10T19:56:34.353Z,1773172594.353 [IBIT] Loaded
2026-03-10T19:56:34.353Z,1773172594.353 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-10T19:56:34.357Z,1773172594.357 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-10T19:56:34.437Z,1773172594.437 [CBIT] Loaded
2026-03-10T19:56:34.437Z,1773172594.437 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-10T19:56:34.469Z,1773172594.469 [GFScanner] Loaded
2026-03-10T19:56:34.469Z,1773172594.469 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-10T19:56:34.469Z,1773172594.469 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-10T19:56:34.470Z,1773172594.470 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-10T19:56:34.651Z,1773172594.651 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-10T19:56:34.651Z,1773172594.651 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-10T19:56:34.801Z,1773172594.801 [ElevatorServo] Loaded
2026-03-10T19:56:34.802Z,1773172594.802 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-10T19:56:34.822Z,1773172594.822 [RudderServo] Loaded
2026-03-10T19:56:34.823Z,1773172594.823 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-10T19:56:34.840Z,1773172594.840 [ThrusterHE] Loaded
2026-03-10T19:56:34.840Z,1773172594.840 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-10T19:56:34.840Z,1773172594.840 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-10T19:56:34.841Z,1773172594.841 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-10T19:56:35.035Z,1773172595.035 [CTD_Seabird] Loaded
2026-03-10T19:56:35.035Z,1773172595.035 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-10T19:56:35.037Z,1773172595.037 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC14E0
2026-03-10T19:56:35.037Z,1773172595.037 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1693
2026-03-10T19:56:35.059Z,1773172595.059 [PAR_Licor] Loaded
2026-03-10T19:56:35.059Z,1773172595.059 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-10T19:56:35.104Z,1773172595.104 [WetLabsBB2FL] Loaded
2026-03-10T19:56:35.105Z,1773172595.105 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-10T19:56:35.106Z,1773172595.106 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2026-03-10T19:56:35.106Z,1773172595.106 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1694
2026-03-10T19:56:35.107Z,1773172595.107 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-10T19:56:35.107Z,1773172595.107 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-10T19:56:35.158Z,1773172595.158 [DepthRateCalculator] Loaded
2026-03-10T19:56:35.158Z,1773172595.158 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-10T19:56:35.163Z,1773172595.163 [PitchRateCalculator] Loaded
2026-03-10T19:56:35.163Z,1773172595.163 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-10T19:56:35.172Z,1773172595.172 [SpeedCalculator] Loaded
2026-03-10T19:56:35.172Z,1773172595.172 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-10T19:56:35.177Z,1773172595.177 [YawRateCalculator] Loaded
2026-03-10T19:56:35.177Z,1773172595.177 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-10T19:56:35.196Z,1773172595.196 [ElevatorOffsetCalculator] Loaded
2026-03-10T19:56:35.197Z,1773172595.197 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-10T19:56:35.197Z,1773172595.197 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-10T19:56:35.198Z,1773172595.198 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-10T19:56:35.256Z,1773172595.256 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-10T19:56:35.262Z,1773172595.262 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-10T19:56:35.265Z,1773172595.265 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-10T19:56:35.277Z,1773172595.277 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-10T19:56:35.278Z,1773172595.278 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C7F4E0
2026-03-10T19:56:35.278Z,1773172595.278 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1695
2026-03-10T19:56:35.283Z,1773172595.283 [Supervisor](INFO): Main Thread ID is 1603
2026-03-10T19:56:35.283Z,1773172595.283 [Supervisor](DEBUG): Running supervisor.
2026-03-10T19:56:35.284Z,1773172595.284 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1696
2026-03-10T19:56:35.284Z,1773172595.284 [CommandExec](INFO): Initializing the command executive.
2026-03-10T19:56:35.286Z,1773172595.286 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1697
2026-03-10T19:56:35.288Z,1773172595.288 [controlThread ThreadHandler](INFO): Handler Thread ID is 1698
2026-03-10T19:56:35.289Z,1773172595.289 [controlThread](DEBUG): Initializing ControlThread
2026-03-10T19:56:35.291Z,1773172595.291 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-10T19:56:35.293Z,1773172595.293 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-10T19:56:35.293Z,1773172595.293 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-10T19:56:35.294Z,1773172595.294 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-10T19:56:35.295Z,1773172595.295 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-10T19:56:35.295Z,1773172595.295 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-10T19:56:35.295Z,1773172595.295 [SBIT](INFO): Initialize SBIT Component.
2026-03-10T19:56:35.296Z,1773172595.296 [SBIT](IMPORTANT): git: 2026-03-10-4-gd32829878
2026-03-10T19:56:35.296Z,1773172595.296 [SBIT](INFO): git hash: d3282987833180dffa14cba482e2d78275c9825f
2026-03-10T19:56:35.296Z,1773172595.296 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-10T19:56:35.298Z,1773172595.298 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Dec 4 01:17:04 UTC 2024
2026-03-10T19:56:35.299Z,1773172595.299 [SBIT](INFO): Beginning SBIT in 69.000000 seconds.
2026-03-10T19:56:35.299Z,1773172595.299 [IBIT](INFO): Initialize IBIT Component.
2026-03-10T19:56:35.300Z,1773172595.300 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-10T19:56:35.301Z,1773172595.301 [logger ThreadHandler](INFO): Handler Thread ID is 1699
2026-03-10T19:56:35.314Z,1773172595.314 [CBIT](DEBUG): Initialized Platform.
2026-03-10T19:56:35.314Z,1773172595.314 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-10T19:56:35.326Z,1773172595.326 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1700
2026-03-10T19:56:35.338Z,1773172595.338 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-10T19:56:35.339Z,1773172595.339 [CBIT](DEBUG): Initializing heartbeat.
2026-03-10T19:56:35.340Z,1773172595.340 [DAT ThreadHandler](INFO): Handler Thread ID is 1701
2026-03-10T19:56:35.340Z,1773172595.340 [DAT](INFO): Powering up
2026-03-10T19:56:35.341Z,1773172595.341 [DAT](DEBUG): Initializing DAT.
2026-03-10T19:56:35.343Z,1773172595.343 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1703
2026-03-10T19:56:35.345Z,1773172595.345 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-10T19:56:35.366Z,1773172595.366 [Onboard ThreadHandler](INFO): Handler Thread ID is 1704
2026-03-10T19:56:35.384Z,1773172595.384 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1705
2026-03-10T19:56:35.402Z,1773172595.402 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1706
2026-03-10T19:56:35.403Z,1773172595.403 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T19:56:35.408Z,1773172595.408 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1708
2026-03-10T19:56:35.411Z,1773172595.411 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-10T19:56:35.411Z,1773172595.411 [WetLabsBB2FL](INFO): Powering up
2026-03-10T19:56:35.413Z,1773172595.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1710
2026-03-10T19:56:35.450Z,1773172595.450 [CBIT](DEBUG): Backplane powered.
2026-03-10T19:56:35.450Z,1773172595.450 [GFScanner](DEBUG): Initializing GFScanner
2026-03-10T19:56:35.450Z,1773172595.450 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-10T19:56:35.458Z,1773172595.458 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-10T19:56:35.459Z,1773172595.459 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-10T19:56:35.459Z,1773172595.459 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-10T19:56:35.459Z,1773172595.459 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-10T19:56:35.460Z,1773172595.460 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-10T19:56:35.461Z,1773172595.461 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-10T19:56:35.462Z,1773172595.462 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-10T19:56:35.462Z,1773172595.462 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-10T19:56:35.463Z,1773172595.463 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-10T19:56:35.509Z,1773172595.509 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2026-03-10T19:56:35.509Z,1773172595.509 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2026-03-10T19:56:35.509Z,1773172595.509 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2026-03-10T19:56:35.509Z,1773172595.509 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2026-03-10T19:56:35.510Z,1773172595.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2026-03-10T19:56:35.510Z,1773172595.510 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2026-03-10T19:56:35.510Z,1773172595.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2026-03-10T19:56:35.510Z,1773172595.510 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2026-03-10T19:56:35.567Z,1773172595.567 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-03-10T19:56:35.579Z,1773172595.579 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T19:56:35.609Z,1773172595.609 [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
}
}
}
2026-03-10T19:56:35.610Z,1773172595.610 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-10T19:56:35.634Z,1773172595.634 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-10T19:56:35.635Z,1773172595.635 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-10T19:56:35.790Z,1773172595.790 [Radio_Surface](INFO): Powering up
2026-03-10T19:56:35.921Z,1773172595.921 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2026-03-10T19:56:35.936Z,1773172595.936 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-10T19:56:35.937Z,1773172595.937 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-10T19:56:35.955Z,1773172595.955 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T19:56:35.990Z,1773172595.990 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-10T19:56:35.992Z,1773172595.992 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-10T19:56:36.027Z,1773172596.027 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-10T19:56:36.039Z,1773172596.039 [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"
}
}
2026-03-10T19:56:36.043Z,1773172596.043 [controlThread](DEBUG): Component order: CycleStarter,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2026-03-10T19:56:36.094Z,1773172596.094 [Depth_Keller](INFO): Initializing.
2026-03-10T19:56:36.096Z,1773172596.096 [Power24vConverter](INFO): Powering up.
2026-03-10T19:56:36.096Z,1773172596.096 [Sonardyne_Nano](INFO): Initializing.
2026-03-10T19:56:36.134Z,1773172596.134 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-10T19:56:36.161Z,1773172596.161 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-10T19:56:36.178Z,1773172596.178 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-10T19:56:36.195Z,1773172596.195 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-10T19:56:36.202Z,1773172596.202 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-10T19:56:36.203Z,1773172596.203 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-10T19:56:36.210Z,1773172596.210 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-10T19:56:40.698Z,1773172600.698 [WetLabsBB2FL] Communications Fault, FailCount= 1
2026-03-10T19:56:40.698Z,1773172600.698 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T19:56:40.924Z,1773172600.924 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T19:56:41.198Z,1773172601.198 [WetLabsBB2FL](INFO): Powering down
2026-03-10T19:56:41.759Z,1773172601.759 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-03-10T19:56:42.299Z,1773172602.299 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T19:56:42.299Z,1773172602.299 [WetLabsBB2FL] No Fault, FailCount= 1
2026-03-10T19:56:42.412Z,1773172602.412 [WetLabsBB2FL](INFO): Powering up
2026-03-10T19:56:45.414Z,1773172605.414 [CBIT](CRITICAL): Environmental Failure. Press:14.755864 PSI. Humidity:41%. Temp:25 C. ABORTING MISSION
2026-03-10T19:56:45.414Z,1773172605.414 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2026-03-10T19:56:45.767Z,1773172605.767 [CommandExec](FAULT): Scheduling is paused
2026-03-10T19:56:45.767Z,1773172605.767 [CBIT](INFO): Critical error at 20260310T195645
2026-03-10T19:56:47.191Z,1773172607.191 [DAT](INFO): DAT read:
2026-03-10T19:56:47.192Z,1773172607.192 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-03-10T19:56:47.718Z,1773172607.718 [WetLabsBB2FL] Communications Fault, FailCount= 2
2026-03-10T19:56:47.718Z,1773172607.718 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T19:56:47.811Z,1773172607.811 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T19:56:48.218Z,1773172608.218 [WetLabsBB2FL](INFO): Powering down
2026-03-10T19:56:48.454Z,1773172608.454 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T19:56:48.454Z,1773172608.454 [CTD_Seabird] Communications Fault, FailCount= 1
2026-03-10T19:56:48.454Z,1773172608.454 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T19:56:48.604Z,1773172608.604 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T19:56:48.859Z,1773172608.859 [CTD_Seabird](INFO): Powering down
2026-03-10T19:56:48.955Z,1773172608.955 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth
2026-03-10T19:56:48.957Z,1773172608.957 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1
2026-03-10T19:56:48.957Z,1773172608.957 [DAT](INFO): DAT read: Mar 10 2026 19:56:14
2026-03-10T19:56:49.039Z,1773172609.039 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T19:56:49.039Z,1773172609.039 [WetLabsBB2FL] No Fault, FailCount= 2
2026-03-10T19:56:49.432Z,1773172609.432 [WetLabsBB2FL](INFO): Powering up
2026-03-10T19:56:49.459Z,1773172609.459 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-03-10T19:56:49.460Z,1773172609.460 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2026-03-10T19:56:49.461Z,1773172609.461 [DAT](INFO): commRate: 800
2026-03-10T19:56:49.831Z,1773172609.831 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2026-03-10T19:56:49.831Z,1773172609.831 [CTD_Seabird] No Fault, FailCount= 1
2026-03-10T19:56:50.215Z,1773172610.215 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T19:56:51.527Z,1773172611.527 [DAT](INFO): entering command mode
2026-03-10T19:56:51.727Z,1773172611.727 [DAT](INFO): DAT read:
2026-03-10T19:56:51.727Z,1773172611.727 [DAT](INFO): DAT read: usr:1>
2026-03-10T19:56:51.728Z,1773172611.728 [DAT](INFO): unknown deviceResponse_: usr:1>
2026-03-10T19:56:51.728Z,1773172611.728 [DAT] Communications Fault, FailCount= 1
2026-03-10T19:56:51.728Z,1773172611.728 [DAT](ERROR): Communications Fault
2026-03-10T19:56:51.728Z,1773172611.728 [DAT](DEBUG): checking for command mode acknowledgment
2026-03-10T19:56:51.838Z,1773172611.838 [CBIT](ERROR): Communications Fault in component: DAT
2026-03-10T19:56:52.130Z,1773172612.130 [DAT](INFO): Powering down
2026-03-10T19:56:53.071Z,1773172613.071 [CBIT](INFO): Clearing failed state for component DAT
2026-03-10T19:56:53.071Z,1773172613.071 [DAT] No Fault, FailCount= 1
2026-03-10T19:56:54.730Z,1773172614.730 [WetLabsBB2FL] Communications Fault, FailCount= 3
2026-03-10T19:56:54.730Z,1773172614.730 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T19:56:55.079Z,1773172615.079 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T19:56:55.202Z,1773172615.202 [DAT](INFO): Powering up
2026-03-10T19:56:55.202Z,1773172615.202 [DAT](DEBUG): Initializing DAT.
2026-03-10T19:56:55.234Z,1773172615.234 [WetLabsBB2FL](INFO): Powering down
2026-03-10T19:56:56.290Z,1773172616.290 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T19:56:56.290Z,1773172616.290 [WetLabsBB2FL] No Fault, FailCount= 3
2026-03-10T19:56:56.447Z,1773172616.447 [WetLabsBB2FL](INFO): Powering up
2026-03-10T19:57:01.763Z,1773172621.763 [WetLabsBB2FL] Communications Fault, FailCount= 4
2026-03-10T19:57:01.763Z,1773172621.763 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T19:57:01.941Z,1773172621.941 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T19:57:02.262Z,1773172622.262 [WetLabsBB2FL](INFO): Powering down
2026-03-10T19:57:03.170Z,1773172623.170 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T19:57:03.170Z,1773172623.170 [WetLabsBB2FL] No Fault, FailCount= 4
2026-03-10T19:57:03.266Z,1773172623.266 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T19:57:03.266Z,1773172623.266 [CTD_Seabird] Communications Fault, FailCount= 2
2026-03-10T19:57:03.266Z,1773172623.266 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T19:57:03.476Z,1773172623.476 [WetLabsBB2FL](INFO): Powering up
2026-03-10T19:57:03.551Z,1773172623.551 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T19:57:03.670Z,1773172623.670 [CTD_Seabird](INFO): Powering down
2026-03-10T19:57:03.931Z,1773172623.931 [NAL9602](INFO): Powering up NAL9602
2026-03-10T19:57:04.779Z,1773172624.779 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2026-03-10T19:57:04.779Z,1773172624.779 [CTD_Seabird] No Fault, FailCount= 2
2026-03-10T19:57:05.014Z,1773172625.014 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T19:57:06.803Z,1773172626.803 [DAT](INFO): DAT read:
2026-03-10T19:57:06.804Z,1773172626.804 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-03-10T19:57:08.567Z,1773172628.567 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth
2026-03-10T19:57:08.569Z,1773172628.569 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1
2026-03-10T19:57:08.569Z,1773172628.569 [DAT](INFO): DAT read: Mar 10 2026 19:56:34
2026-03-10T19:57:08.786Z,1773172628.786 [WetLabsBB2FL] Communications Fault, FailCount= 5
2026-03-10T19:57:08.786Z,1773172628.786 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T19:57:08.797Z,1773172628.797 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T19:57:08.797Z,1773172628.797 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2026-03-10T19:57:09.286Z,1773172629.286 [WetLabsBB2FL](INFO): Powering down
2026-03-10T19:57:09.323Z,1773172629.323 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-03-10T19:57:09.324Z,1773172629.324 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2026-03-10T19:57:09.325Z,1773172629.325 [DAT](INFO): commRate: 800
2026-03-10T19:57:11.390Z,1773172631.390 [DAT](INFO): entering command mode
2026-03-10T19:57:11.590Z,1773172631.590 [DAT](INFO): DAT read:
2026-03-10T19:57:11.591Z,1773172631.591 [DAT](INFO): DAT read: user:1>
2026-03-10T19:57:11.591Z,1773172631.591 [DAT](INFO): setting verbose to 3
2026-03-10T19:57:11.843Z,1773172631.843 [DAT](INFO): DAT read: user:1>
2026-03-10T19:57:11.844Z,1773172631.844 [DAT](INFO): DAT read: Verbose | 3
2026-03-10T19:57:11.844Z,1773172631.844 [DAT](INFO): set ve