2026-03-10T21:27:05.218Z,1773178025.218 [Supervisor](DEBUG): Initializing supervisor.
2026-03-10T21:27:05.222Z,1773178025.222 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-10T21:27:05.223Z,1773178025.223 [SyncHandler](INFO): Protected caller Thread ID is 838
2026-03-10T21:27:05.223Z,1773178025.223 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-10T21:27:05.225Z,1773178025.225 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-10T21:27:05.225Z,1773178025.225 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839
2026-03-10T21:27:05.229Z,1773178025.229 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-10T21:27:05.251Z,1773178025.251 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-10T21:27:05.252Z,1773178025.252 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-10T21:27:05.252Z,1773178025.252 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840
2026-03-10T21:27:05.256Z,1773178025.256 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-10T21:27:05.257Z,1773178025.257 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-10T21:27:05.258Z,1773178025.258 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841
2026-03-10T21:27:05.260Z,1773178025.260 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-10T21:27:05.261Z,1773178025.261 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-10T21:27:05.261Z,1773178025.261 [logger ThreadHandler](INFO): Protected caller Thread ID is 842
2026-03-10T21:27:05.265Z,1773178025.265 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-10T21:27:05.265Z,1773178025.265 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-10T21:27:05.269Z,1773178025.269 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-10T21:27:05.505Z,1773178025.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-10T21:27:05.507Z,1773178025.507 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-10T21:27:05.734Z,1773178025.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-10T21:27:05.736Z,1773178025.736 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-10T21:27:05.839Z,1773178025.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-10T21:27:05.841Z,1773178025.841 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-10T21:27:06.024Z,1773178026.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-10T21:27:06.025Z,1773178026.025 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-10T21:27:06.518Z,1773178026.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-10T21:27:06.520Z,1773178026.520 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-10T21:27:06.635Z,1773178026.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-10T21:27:06.636Z,1773178026.636 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-10T21:27:06.904Z,1773178026.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-10T21:27:06.906Z,1773178026.906 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-10T21:27:07.174Z,1773178027.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-10T21:27:07.175Z,1773178027.175 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-10T21:27:07.700Z,1773178027.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-10T21:27:07.701Z,1773178027.701 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-10T21:27:08.106Z,1773178028.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-10T21:27:08.107Z,1773178028.107 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-10T21:27:08.194Z,1773178028.194 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-10T21:27:08.585Z,1773178028.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-10T21:27:08.586Z,1773178028.586 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-10T21:27:15.947Z,1773178035.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-10T21:27:15.947Z,1773178035.947 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-10T21:27:16.781Z,1773178036.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-10T21:27:16.783Z,1773178036.783 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-10T21:27:17.126Z,1773178037.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-10T21:27:17.127Z,1773178037.127 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-10T21:27:17.228Z,1773178037.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-10T21:27:17.230Z,1773178037.230 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2026-03-10T21:27:17.233Z,1773178037.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2026-03-10T21:27:17.327Z,1773178037.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2026-03-10T21:27:17.444Z,1773178037.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2026-03-10T21:27:17.558Z,1773178037.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2026-03-10T21:27:17.654Z,1773178037.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2026-03-10T21:27:17.762Z,1773178037.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2026-03-10T21:27:17.872Z,1773178037.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2026-03-10T21:27:18.018Z,1773178038.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2026-03-10T21:27:18.188Z,1773178038.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2026-03-10T21:27:18.289Z,1773178038.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2026-03-10T21:27:18.564Z,1773178038.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-10T21:27:18.566Z,1773178038.566 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/
2026-03-10T21:27:18.566Z,1773178038.566 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-10T21:27:18.584Z,1773178038.584 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-10T21:27:19.007Z,1773178039.007 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-10T21:27:19.009Z,1773178039.009 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-10T21:27:20.077Z,1773178040.077 [BackseatComponent] Loaded
2026-03-10T21:27:20.077Z,1773178040.077 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2026-03-10T21:27:20.078Z,1773178040.078 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2026-03-10T21:27:20.078Z,1773178040.078 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2026-03-10T21:27:20.082Z,1773178040.082 [LcmPublisher] Loaded
2026-03-10T21:27:20.082Z,1773178040.082 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2026-03-10T21:27:20.901Z,1773178040.901 [BPC1] Loaded
2026-03-10T21:27:20.901Z,1773178040.901 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-10T21:27:20.997Z,1773178040.997 [DAT] Loaded
2026-03-10T21:27:20.998Z,1773178040.998 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-03-10T21:27:20.999Z,1773178040.999 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2026-03-10T21:27:20.999Z,1773178040.999 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924
2026-03-10T21:27:21.139Z,1773178041.139 [DataOverHttps] Loaded
2026-03-10T21:27:21.139Z,1773178041.139 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-10T21:27:21.140Z,1773178041.140 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2026-03-10T21:27:21.141Z,1773178041.141 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2026-03-10T21:27:21.164Z,1773178041.164 [Depth_Keller] Loaded
2026-03-10T21:27:21.164Z,1773178041.164 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-10T21:27:21.230Z,1773178041.230 [NAL9602] Loaded
2026-03-10T21:27:21.230Z,1773178041.230 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-10T21:27:21.260Z,1773178041.260 [Onboard] Loaded
2026-03-10T21:27:21.260Z,1773178041.260 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-10T21:27:21.261Z,1773178041.261 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2026-03-10T21:27:21.262Z,1773178041.262 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2026-03-10T21:27:21.275Z,1773178041.275 [Power24vConverter] Loaded
2026-03-10T21:27:21.276Z,1773178041.276 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-10T21:27:21.293Z,1773178041.293 [Radio_Surface] Loaded
2026-03-10T21:27:21.293Z,1773178041.293 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-10T21:27:21.294Z,1773178041.294 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2026-03-10T21:27:21.294Z,1773178041.294 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2026-03-10T21:27:21.309Z,1773178041.309 [Sonardyne_Nano] Loaded
2026-03-10T21:27:21.310Z,1773178041.310 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-10T21:27:21.310Z,1773178041.310 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-10T21:27:21.311Z,1773178041.311 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-10T21:27:22.388Z,1773178042.388 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-10T21:27:25.184Z,1773178045.184 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-10T21:27:25.265Z,1773178045.265 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-10T21:27:25.350Z,1773178045.350 [VerticalControl] Loaded
2026-03-10T21:27:25.350Z,1773178045.350 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-10T21:27:25.353Z,1773178045.353 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-10T21:27:25.402Z,1773178045.402 [HorizontalControl] Loaded
2026-03-10T21:27:25.402Z,1773178045.402 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-10T21:27:25.405Z,1773178045.405 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-10T21:27:25.408Z,1773178045.408 [SpeedControl] Loaded
2026-03-10T21:27:25.408Z,1773178045.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-10T21:27:25.411Z,1773178045.411 [LoopControl](DEBUG): Construct LoopControl.
2026-03-10T21:27:25.411Z,1773178045.411 [LoopControl] Loaded
2026-03-10T21:27:25.412Z,1773178045.412 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-10T21:27:25.412Z,1773178045.412 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-10T21:27:25.414Z,1773178045.414 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-10T21:27:25.431Z,1773178045.431 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-10T21:27:25.433Z,1773178045.433 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-10T21:27:25.483Z,1773178045.483 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-10T21:27:25.483Z,1773178045.483 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-10T21:27:25.629Z,1773178045.629 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-10T21:27:25.629Z,1773178045.629 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-10T21:27:25.643Z,1773178045.643 [NavChart] Loaded
2026-03-10T21:27:25.643Z,1773178045.643 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-10T21:27:25.649Z,1773178045.649 [UniversalFixResidualReporter] Loaded
2026-03-10T21:27:25.649Z,1773178045.649 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-10T21:27:25.650Z,1773178045.650 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-10T21:27:25.650Z,1773178045.650 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-10T21:27:25.853Z,1773178045.853 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-10T21:27:25.862Z,1773178045.862 [SBIT] Loaded
2026-03-10T21:27:25.862Z,1773178045.862 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-10T21:27:25.865Z,1773178045.865 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-10T21:27:25.879Z,1773178045.879 [IBIT] Loaded
2026-03-10T21:27:25.879Z,1773178045.879 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-10T21:27:25.882Z,1773178045.882 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-10T21:27:25.966Z,1773178045.966 [CBIT] Loaded
2026-03-10T21:27:25.966Z,1773178045.966 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-10T21:27:25.998Z,1773178045.998 [GFScanner] Loaded
2026-03-10T21:27:25.999Z,1773178045.999 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-10T21:27:25.999Z,1773178045.999 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-10T21:27:25.000Z,1773178046.000 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-10T21:27:26.230Z,1773178046.230 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-10T21:27:26.230Z,1773178046.230 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-10T21:27:26.419Z,1773178046.419 [ElevatorServo] Loaded
2026-03-10T21:27:26.420Z,1773178046.420 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-10T21:27:26.441Z,1773178046.441 [RudderServo] Loaded
2026-03-10T21:27:26.441Z,1773178046.441 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-10T21:27:26.459Z,1773178046.459 [ThrusterHE] Loaded
2026-03-10T21:27:26.459Z,1773178046.459 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-10T21:27:26.459Z,1773178046.459 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-10T21:27:26.460Z,1773178046.460 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-10T21:27:26.711Z,1773178046.711 [CTD_Seabird] Loaded
2026-03-10T21:27:26.711Z,1773178046.711 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-10T21:27:26.712Z,1773178046.712 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC14E0
2026-03-10T21:27:26.713Z,1773178046.713 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2026-03-10T21:27:26.735Z,1773178046.735 [PAR_Licor] Loaded
2026-03-10T21:27:26.736Z,1773178046.736 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-10T21:27:26.781Z,1773178046.781 [WetLabsBB2FL] Loaded
2026-03-10T21:27:26.781Z,1773178046.781 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-10T21:27:26.782Z,1773178046.782 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2026-03-10T21:27:26.783Z,1773178046.783 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2026-03-10T21:27:26.784Z,1773178046.784 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-10T21:27:26.784Z,1773178046.784 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-10T21:27:26.858Z,1773178046.858 [DepthRateCalculator] Loaded
2026-03-10T21:27:26.858Z,1773178046.858 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-10T21:27:26.864Z,1773178046.864 [PitchRateCalculator] Loaded
2026-03-10T21:27:26.864Z,1773178046.864 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-10T21:27:26.873Z,1773178046.873 [SpeedCalculator] Loaded
2026-03-10T21:27:26.873Z,1773178046.873 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-10T21:27:26.878Z,1773178046.878 [YawRateCalculator] Loaded
2026-03-10T21:27:26.878Z,1773178046.878 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-10T21:27:26.898Z,1773178046.898 [ElevatorOffsetCalculator] Loaded
2026-03-10T21:27:26.899Z,1773178046.899 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-10T21:27:26.899Z,1773178046.899 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-10T21:27:26.900Z,1773178046.900 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-10T21:27:26.996Z,1773178046.996 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-10T21:27:27.002Z,1773178047.002 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-10T21:27:27.005Z,1773178047.005 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-10T21:27:27.017Z,1773178047.017 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-10T21:27:27.018Z,1773178047.018 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C7F4E0
2026-03-10T21:27:27.018Z,1773178047.018 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2026-03-10T21:27:27.023Z,1773178047.023 [Supervisor](INFO): Main Thread ID is 829
2026-03-10T21:27:27.023Z,1773178047.023 [Supervisor](DEBUG): Running supervisor.
2026-03-10T21:27:27.024Z,1773178047.024 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2026-03-10T21:27:27.024Z,1773178047.024 [CommandExec](INFO): Initializing the command executive.
2026-03-10T21:27:27.026Z,1773178047.026 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2026-03-10T21:27:27.028Z,1773178047.028 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2026-03-10T21:27:27.029Z,1773178047.029 [controlThread](DEBUG): Initializing ControlThread
2026-03-10T21:27:27.030Z,1773178047.030 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-10T21:27:27.032Z,1773178047.032 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-10T21:27:27.033Z,1773178047.033 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-10T21:27:27.033Z,1773178047.033 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-10T21:27:27.034Z,1773178047.034 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-10T21:27:27.035Z,1773178047.035 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-10T21:27:27.035Z,1773178047.035 [SBIT](INFO): Initialize SBIT Component.
2026-03-10T21:27:27.036Z,1773178047.036 [SBIT](IMPORTANT): git: 2026-03-10-4-gd32829878
2026-03-10T21:27:27.036Z,1773178047.036 [SBIT](INFO): git hash: d3282987833180dffa14cba482e2d78275c9825f
2026-03-10T21:27:27.036Z,1773178047.036 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-10T21:27:27.037Z,1773178047.037 [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-10T21:27:27.038Z,1773178047.038 [SBIT](INFO): Beginning SBIT in 69.000000 seconds.
2026-03-10T21:27:27.039Z,1773178047.039 [IBIT](INFO): Initialize IBIT Component.
2026-03-10T21:27:27.040Z,1773178047.040 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-10T21:27:27.041Z,1773178047.041 [logger ThreadHandler](INFO): Handler Thread ID is 934
2026-03-10T21:27:27.052Z,1773178047.052 [CBIT](DEBUG): Initialized Platform.
2026-03-10T21:27:27.052Z,1773178047.052 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-10T21:27:27.060Z,1773178047.060 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2026-03-10T21:27:27.076Z,1773178047.076 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-10T21:27:27.077Z,1773178047.077 [CBIT](DEBUG): Initializing heartbeat.
2026-03-10T21:27:27.078Z,1773178047.078 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2026-03-10T21:27:27.078Z,1773178047.078 [DAT](INFO): Powering up
2026-03-10T21:27:27.079Z,1773178047.079 [DAT](DEBUG): Initializing DAT.
2026-03-10T21:27:27.082Z,1773178047.082 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938
2026-03-10T21:27:27.083Z,1773178047.083 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-10T21:27:27.100Z,1773178047.100 [Onboard ThreadHandler](INFO): Handler Thread ID is 939
2026-03-10T21:27:27.118Z,1773178047.118 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940
2026-03-10T21:27:27.143Z,1773178047.143 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941
2026-03-10T21:27:27.148Z,1773178047.148 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-10T21:27:27.148Z,1773178047.148 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T21:27:27.152Z,1773178047.152 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943
2026-03-10T21:27:27.156Z,1773178047.156 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2026-03-10T21:27:27.158Z,1773178047.158 [WetLabsBB2FL](INFO): Powering up
2026-03-10T21:27:27.167Z,1773178047.167 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2026-03-10T21:27:27.167Z,1773178047.167 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2026-03-10T21:27:27.167Z,1773178047.167 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2026-03-10T21:27:27.167Z,1773178047.167 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2026-03-10T21:27:27.167Z,1773178047.167 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2026-03-10T21:27:27.168Z,1773178047.168 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2026-03-10T21:27:27.168Z,1773178047.168 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2026-03-10T21:27:27.168Z,1773178047.168 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2026-03-10T21:27:27.188Z,1773178047.188 [CBIT](DEBUG): Backplane powered.
2026-03-10T21:27:27.188Z,1773178047.188 [GFScanner](DEBUG): Initializing GFScanner
2026-03-10T21:27:27.188Z,1773178047.188 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-10T21:27:27.194Z,1773178047.194 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-10T21:27:27.194Z,1773178047.194 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-10T21:27:27.195Z,1773178047.195 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-10T21:27:27.195Z,1773178047.195 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-10T21:27:27.195Z,1773178047.195 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-10T21:27:27.197Z,1773178047.197 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-10T21:27:27.197Z,1773178047.197 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-10T21:27:27.198Z,1773178047.198 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-10T21:27:27.203Z,1773178047.203 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-10T21:27:27.279Z,1773178047.279 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-03-10T21:27:27.283Z,1773178047.283 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T21:27:27.346Z,1773178047.346 [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-10T21:27:27.346Z,1773178047.346 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-10T21:27:27.346Z,1773178047.346 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-10T21:27:27.349Z,1773178047.349 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-10T21:27:27.524Z,1773178047.524 [Radio_Surface](INFO): Powering up
2026-03-10T21:27:27.651Z,1773178047.651 [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-10T21:27:27.674Z,1773178047.674 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-10T21:27:27.675Z,1773178047.675 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-10T21:27:27.677Z,1773178047.677 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T21:27:27.722Z,1773178047.722 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-10T21:27:27.741Z,1773178047.741 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-10T21:27:27.747Z,1773178047.747 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-10T21:27:27.766Z,1773178047.766 [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-10T21:27:27.770Z,1773178047.770 [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-10T21:27:27.832Z,1773178047.832 [Depth_Keller](INFO): Initializing.
2026-03-10T21:27:27.833Z,1773178047.833 [Power24vConverter](INFO): Powering up.
2026-03-10T21:27:27.834Z,1773178047.834 [Sonardyne_Nano](INFO): Initializing.
2026-03-10T21:27:27.877Z,1773178047.877 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-10T21:27:28.104Z,1773178048.104 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-10T21:27:28.112Z,1773178048.112 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-10T21:27:28.113Z,1773178048.113 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-10T21:27:28.120Z,1773178048.120 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-10T21:27:28.121Z,1773178048.121 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-10T21:27:28.128Z,1773178048.128 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-10T21:27:32.448Z,1773178052.448 [WetLabsBB2FL] Communications Fault, FailCount= 1
2026-03-10T21:27:32.448Z,1773178052.448 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T21:27:32.651Z,1773178052.651 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T21:27:32.948Z,1773178052.948 [WetLabsBB2FL](INFO): Powering down
2026-03-10T21:27:34.012Z,1773178054.012 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T21:27:34.012Z,1773178054.012 [WetLabsBB2FL] No Fault, FailCount= 1
2026-03-10T21:27:34.161Z,1773178054.161 [WetLabsBB2FL](INFO): Powering up
2026-03-10T21:27:37.114Z,1773178057.114 [CBIT](CRITICAL): Environmental Failure. Press:14.730000 PSI. Humidity:43%. Temp:25 C. ABORTING MISSION
2026-03-10T21:27:37.130Z,1773178057.130 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2026-03-10T21:27:37.493Z,1773178057.493 [CommandExec](FAULT): Scheduling is paused
2026-03-10T21:27:37.493Z,1773178057.493 [CBIT](INFO): Critical error at 20260310T212737
2026-03-10T21:27:39.452Z,1773178059.452 [WetLabsBB2FL] Communications Fault, FailCount= 2
2026-03-10T21:27:39.452Z,1773178059.452 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T21:27:39.544Z,1773178059.544 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T21:27:39.681Z,1773178059.681 [DAT](INFO): DAT read:
2026-03-10T21:27:39.682Z,1773178059.682 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-03-10T21:27:39.952Z,1773178059.952 [WetLabsBB2FL](INFO): Powering down
2026-03-10T21:27:40.200Z,1773178060.200 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T21:27:40.200Z,1773178060.200 [CTD_Seabird] Communications Fault, FailCount= 1
2026-03-10T21:27:40.200Z,1773178060.200 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T21:27:40.332Z,1773178060.332 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T21:27:40.605Z,1773178060.605 [CTD_Seabird](INFO): Powering down
2026-03-10T21:27:40.772Z,1773178060.772 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T21:27:40.772Z,1773178060.772 [WetLabsBB2FL] No Fault, FailCount= 2
2026-03-10T21:27:41.165Z,1773178061.165 [WetLabsBB2FL](INFO): Powering up
2026-03-10T21:27:41.445Z,1773178061.445 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth
2026-03-10T21:27:41.446Z,1773178061.446 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1
2026-03-10T21:27:41.447Z,1773178061.447 [DAT](INFO): DAT read: Mar 10 2026 21:21:36
2026-03-10T21:27:41.949Z,1773178061.949 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-03-10T21:27:41.950Z,1773178061.950 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2026-03-10T21:27:41.951Z,1773178061.951 [DAT](INFO): commRate: 800
2026-03-10T21:27:41.969Z,1773178061.969 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2026-03-10T21:27:41.969Z,1773178061.969 [CTD_Seabird] No Fault, FailCount= 1
2026-03-10T21:27:42.352Z,1773178062.352 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T21:27:44.016Z,1773178064.016 [DAT](INFO): entering command mode
2026-03-10T21:27:44.217Z,1773178064.217 [DAT](INFO): DAT read:
2026-03-10T21:27:44.217Z,1773178064.217 [DAT](INFO): DAT read: user:1>
2026-03-10T21:27:44.217Z,1773178064.217 [DAT](INFO): setting verbose to 3
2026-03-10T21:27:44.469Z,1773178064.469 [DAT](INFO): DAT read: user:1>
2026-03-10T21:27:44.470Z,1773178064.470 [DAT](INFO): DAT read: Verbose | 3
2026-03-10T21:27:44.470Z,1773178064.470 [DAT](INFO): set verbose to 3
2026-03-10T21:27:44.470Z,1773178064.470 [DAT](INFO): setting DatVerbose to 27440
2026-03-10T21:27:44.721Z,1773178064.721 [DAT](INFO): DAT read: user:2>
2026-03-10T21:27:44.721Z,1773178064.721 [DAT](INFO): DAT read: DatVerbose | 27440
2026-03-10T21:27:44.722Z,1773178064.722 [DAT](INFO): set DatVerbose to 27440
2026-03-10T21:27:44.722Z,1773178064.722 [DAT](INFO): setting transmit power to 8
2026-03-10T21:27:44.973Z,1773178064.973 [DAT](INFO): DAT read: user:3>
2026-03-10T21:27:44.974Z,1773178064.974 [DAT](INFO): DAT read: TxPower | 8 (Max)
2026-03-10T21:27:44.974Z,1773178064.974 [DAT](INFO): set transmit power to 8
2026-03-10T21:27:44.974Z,1773178064.974 [DAT](INFO): setting local address to 2
2026-03-10T21:27:45.225Z,1773178065.225 [DAT](INFO): DAT read: user:4>
2026-03-10T21:27:45.226Z,1773178065.226 [DAT](INFO): DAT read: LocalAddr | 2
2026-03-10T21:27:45.226Z,1773178065.226 [DAT](INFO): set local address to 2
2026-03-10T21:27:45.227Z,1773178065.227 [DAT](INFO): Setting time to: 21:27:45 And date to:3/10/2026
2026-03-10T21:27:45.477Z,1773178065.477 [DAT](INFO): DAT read: user:5>
2026-03-10T21:27:45.478Z,1773178065.478 [DAT](INFO): DAT read: Tue Mar 10, 2026 21:27:45
2026-03-10T21:27:45.478Z,1773178065.478 [DAT](INFO): Local DAT time set to Tue Mar 10, 2026 21:27:45
2026-03-10T21:27:46.459Z,1773178066.459 [WetLabsBB2FL] Communications Fault, FailCount= 3
2026-03-10T21:27:46.459Z,1773178066.459 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T21:27:46.841Z,1773178066.841 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T21:27:46.960Z,1773178066.960 [WetLabsBB2FL](INFO): Powering down
2026-03-10T21:27:48.053Z,1773178068.053 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T21:27:48.053Z,1773178068.053 [WetLabsBB2FL] No Fault, FailCount= 3
2026-03-10T21:27:48.173Z,1773178068.173 [WetLabsBB2FL](INFO): Powering up
2026-03-10T21:27:51.249Z,1773178071.249 [NAL9602](INFO): Powering up NAL9602
2026-03-10T21:27:53.472Z,1773178073.472 [WetLabsBB2FL] Communications Fault, FailCount= 4
2026-03-10T21:27:53.472Z,1773178073.472 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T21:27:53.702Z,1773178073.702 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T21:27:53.972Z,1773178073.972 [WetLabsBB2FL](INFO): Powering down
2026-03-10T21:27:54.923Z,1773178074.923 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T21:27:54.923Z,1773178074.923 [WetLabsBB2FL] No Fault, FailCount= 4
2026-03-10T21:27:55.185Z,1773178075.185 [WetLabsBB2FL](INFO): Powering up
2026-03-10T21:27:55.404Z,1773178075.404 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T21:27:55.404Z,1773178075.404 [CTD_Seabird] Communications Fault, FailCount= 2
2026-03-10T21:27:55.404Z,1773178075.404 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T21:27:55.723Z,1773178075.723 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T21:27:55.810Z,1773178075.810 [CTD_Seabird](INFO): Powering down
2026-03-10T21:27:56.931Z,1773178076.931 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2026-03-10T21:27:56.931Z,1773178076.931 [CTD_Seabird] No Fault, FailCount= 2
2026-03-10T21:27:57.153Z,1773178077.153 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T21:28:00.476Z,1773178080.476 [WetLabsBB2FL] Communications Fault, FailCount= 5
2026-03-10T21:28:00.476Z,1773178080.476 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T21:28:00.584Z,1773178080.584 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T21:28:00.584Z,1773178080.584 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2026-03-10T21:28:00.976Z,1773178080.976 [WetLabsBB2FL](INFO): Powering down
2026-03-10T21:28:02.157Z,1773178082.157 [NAL9602](INFO): NAL9602 initialized
2026-03-10T21:28:10.204Z,1773178090.204 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T21:28:10.204Z,1773178090.204 [CTD_Seabird] Communications Fault, FailCount= 3
2026-03-10T21:28:10.204Z,1773178090.204 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T21:28:10.269Z,1773178090.269 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T21:28:10.270Z,1773178090.270 [CBIT](