2026-03-10T22:48:25.740Z,1773182905.740 [Supervisor](DEBUG): Initializing supervisor.
2026-03-10T22:48:25.745Z,1773182905.745 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-10T22:48:25.746Z,1773182905.746 [SyncHandler](INFO): Protected caller Thread ID is 1238
2026-03-10T22:48:25.747Z,1773182905.747 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-10T22:48:25.748Z,1773182905.748 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-10T22:48:25.748Z,1773182905.748 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1239
2026-03-10T22:48:25.753Z,1773182905.753 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-10T22:48:25.773Z,1773182905.773 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-10T22:48:25.774Z,1773182905.774 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-10T22:48:25.775Z,1773182905.775 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1240
2026-03-10T22:48:25.779Z,1773182905.779 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-10T22:48:25.780Z,1773182905.780 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-10T22:48:25.780Z,1773182905.780 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1241
2026-03-10T22:48:25.783Z,1773182905.783 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-10T22:48:25.783Z,1773182905.783 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-10T22:48:25.784Z,1773182905.784 [logger ThreadHandler](INFO): Protected caller Thread ID is 1242
2026-03-10T22:48:25.787Z,1773182905.787 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-10T22:48:25.788Z,1773182905.788 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-10T22:48:25.790Z,1773182905.790 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-10T22:48:26.016Z,1773182906.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-10T22:48:26.017Z,1773182906.017 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-10T22:48:26.222Z,1773182906.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-10T22:48:26.222Z,1773182906.222 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-10T22:48:26.325Z,1773182906.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-10T22:48:26.325Z,1773182906.325 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-10T22:48:26.501Z,1773182906.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-10T22:48:26.501Z,1773182906.501 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-10T22:48:26.977Z,1773182906.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-10T22:48:26.977Z,1773182906.977 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-10T22:48:27.090Z,1773182907.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-10T22:48:27.090Z,1773182907.090 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-10T22:48:27.370Z,1773182907.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-10T22:48:27.371Z,1773182907.371 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-10T22:48:27.588Z,1773182907.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-10T22:48:27.589Z,1773182907.589 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-10T22:48:28.194Z,1773182908.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-10T22:48:28.195Z,1773182908.195 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-10T22:48:28.588Z,1773182908.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-10T22:48:28.589Z,1773182908.589 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-10T22:48:28.675Z,1773182908.675 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-10T22:48:29.038Z,1773182909.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-10T22:48:29.038Z,1773182909.038 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-10T22:48:29.196Z,1773182909.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-10T22:48:29.197Z,1773182909.197 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-10T22:48:30.096Z,1773182910.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-10T22:48:30.097Z,1773182910.097 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-10T22:48:30.428Z,1773182910.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-10T22:48:30.429Z,1773182910.429 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-10T22:48:30.527Z,1773182910.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-10T22:48:30.950Z,1773182910.950 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2026-03-10T22:48:30.951Z,1773182910.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2026-03-10T22:48:31.043Z,1773182911.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2026-03-10T22:48:31.155Z,1773182911.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2026-03-10T22:48:31.268Z,1773182911.268 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2026-03-10T22:48:33.183Z,1773182913.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2026-03-10T22:48:33.274Z,1773182913.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2026-03-10T22:48:33.382Z,1773182913.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2026-03-10T22:48:33.524Z,1773182913.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2026-03-10T22:48:33.687Z,1773182913.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2026-03-10T22:48:33.786Z,1773182913.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2026-03-10T22:48:34.162Z,1773182914.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-10T22:48:34.164Z,1773182914.164 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/
2026-03-10T22:48:34.164Z,1773182914.164 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-10T22:48:34.177Z,1773182914.177 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-10T22:48:34.528Z,1773182914.528 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-10T22:48:34.528Z,1773182914.528 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-10T22:48:35.093Z,1773182915.093 [BackseatComponent] Loaded
2026-03-10T22:48:35.094Z,1773182915.094 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2026-03-10T22:48:35.095Z,1773182915.095 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2026-03-10T22:48:35.095Z,1773182915.095 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1321
2026-03-10T22:48:35.098Z,1773182915.098 [LcmPublisher] Loaded
2026-03-10T22:48:35.098Z,1773182915.098 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2026-03-10T22:48:35.938Z,1773182915.938 [BPC1] Loaded
2026-03-10T22:48:35.939Z,1773182915.939 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-10T22:48:36.036Z,1773182916.036 [DAT] Loaded
2026-03-10T22:48:36.036Z,1773182916.036 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-03-10T22:48:36.037Z,1773182916.037 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2026-03-10T22:48:36.037Z,1773182916.037 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1322
2026-03-10T22:48:36.110Z,1773182916.110 [DataOverHttps] Loaded
2026-03-10T22:48:36.110Z,1773182916.110 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-10T22:48:36.111Z,1773182916.111 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409164E0
2026-03-10T22:48:36.111Z,1773182916.111 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1323
2026-03-10T22:48:36.135Z,1773182916.135 [Depth_Keller] Loaded
2026-03-10T22:48:36.136Z,1773182916.136 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-10T22:48:36.201Z,1773182916.201 [NAL9602] Loaded
2026-03-10T22:48:36.201Z,1773182916.201 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-10T22:48:36.231Z,1773182916.231 [Onboard] Loaded
2026-03-10T22:48:36.231Z,1773182916.231 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-10T22:48:36.232Z,1773182916.232 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2026-03-10T22:48:36.232Z,1773182916.232 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1324
2026-03-10T22:48:36.246Z,1773182916.246 [Power24vConverter] Loaded
2026-03-10T22:48:36.246Z,1773182916.246 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-10T22:48:36.263Z,1773182916.263 [Radio_Surface] Loaded
2026-03-10T22:48:36.263Z,1773182916.263 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-10T22:48:36.264Z,1773182916.264 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2026-03-10T22:48:36.265Z,1773182916.265 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1325
2026-03-10T22:48:36.280Z,1773182916.280 [Sonardyne_Nano] Loaded
2026-03-10T22:48:36.280Z,1773182916.280 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-10T22:48:36.281Z,1773182916.281 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-10T22:48:36.281Z,1773182916.281 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-10T22:48:36.417Z,1773182916.417 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-10T22:48:36.417Z,1773182916.417 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-10T22:48:36.475Z,1773182916.475 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-10T22:48:36.535Z,1773182916.535 [VerticalControl] Loaded
2026-03-10T22:48:36.536Z,1773182916.536 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-10T22:48:36.538Z,1773182916.538 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-10T22:48:36.587Z,1773182916.587 [HorizontalControl] Loaded
2026-03-10T22:48:36.587Z,1773182916.587 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-10T22:48:36.590Z,1773182916.590 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-10T22:48:36.593Z,1773182916.593 [SpeedControl] Loaded
2026-03-10T22:48:36.593Z,1773182916.593 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-10T22:48:36.596Z,1773182916.596 [LoopControl](DEBUG): Construct LoopControl.
2026-03-10T22:48:36.596Z,1773182916.596 [LoopControl] Loaded
2026-03-10T22:48:36.597Z,1773182916.597 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-10T22:48:36.597Z,1773182916.597 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-10T22:48:36.598Z,1773182916.598 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-10T22:48:36.608Z,1773182916.608 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-10T22:48:36.608Z,1773182916.608 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-10T22:48:36.637Z,1773182916.637 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-10T22:48:36.638Z,1773182916.638 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-10T22:48:36.754Z,1773182916.754 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-10T22:48:36.755Z,1773182916.755 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-10T22:48:36.769Z,1773182916.769 [NavChart] Loaded
2026-03-10T22:48:36.769Z,1773182916.769 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-10T22:48:36.774Z,1773182916.774 [UniversalFixResidualReporter] Loaded
2026-03-10T22:48:36.775Z,1773182916.775 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-10T22:48:36.775Z,1773182916.775 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-10T22:48:36.776Z,1773182916.776 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-10T22:48:36.951Z,1773182916.951 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-10T22:48:36.960Z,1773182916.960 [SBIT] Loaded
2026-03-10T22:48:36.960Z,1773182916.960 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-10T22:48:36.963Z,1773182916.963 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-10T22:48:36.976Z,1773182916.976 [IBIT] Loaded
2026-03-10T22:48:36.977Z,1773182916.977 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-10T22:48:36.980Z,1773182916.980 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-10T22:48:37.062Z,1773182917.062 [CBIT] Loaded
2026-03-10T22:48:37.062Z,1773182917.062 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-10T22:48:37.094Z,1773182917.094 [GFScanner] Loaded
2026-03-10T22:48:37.095Z,1773182917.095 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-10T22:48:37.095Z,1773182917.095 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-10T22:48:37.096Z,1773182917.096 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-10T22:48:37.280Z,1773182917.280 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-10T22:48:37.280Z,1773182917.280 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-10T22:48:37.431Z,1773182917.431 [ElevatorServo] Loaded
2026-03-10T22:48:37.431Z,1773182917.431 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-10T22:48:37.452Z,1773182917.452 [RudderServo] Loaded
2026-03-10T22:48:37.453Z,1773182917.453 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-10T22:48:37.470Z,1773182917.470 [ThrusterHE] Loaded
2026-03-10T22:48:37.470Z,1773182917.470 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-10T22:48:37.470Z,1773182917.470 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-10T22:48:37.471Z,1773182917.471 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-10T22:48:37.739Z,1773182917.739 [CTD_Seabird] Loaded
2026-03-10T22:48:37.739Z,1773182917.739 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-10T22:48:37.741Z,1773182917.741 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC14E0
2026-03-10T22:48:37.741Z,1773182917.741 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1326
2026-03-10T22:48:37.764Z,1773182917.764 [PAR_Licor] Loaded
2026-03-10T22:48:37.764Z,1773182917.764 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-10T22:48:37.809Z,1773182917.809 [WetLabsBB2FL] Loaded
2026-03-10T22:48:37.809Z,1773182917.809 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-10T22:48:37.810Z,1773182917.810 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2026-03-10T22:48:37.811Z,1773182917.811 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1327
2026-03-10T22:48:37.811Z,1773182917.811 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-10T22:48:37.812Z,1773182917.812 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-10T22:48:37.863Z,1773182917.863 [DepthRateCalculator] Loaded
2026-03-10T22:48:37.864Z,1773182917.864 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-10T22:48:37.869Z,1773182917.869 [PitchRateCalculator] Loaded
2026-03-10T22:48:37.869Z,1773182917.869 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-10T22:48:37.878Z,1773182917.878 [SpeedCalculator] Loaded
2026-03-10T22:48:37.878Z,1773182917.878 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-10T22:48:37.883Z,1773182917.883 [YawRateCalculator] Loaded
2026-03-10T22:48:37.883Z,1773182917.883 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-10T22:48:37.903Z,1773182917.903 [ElevatorOffsetCalculator] Loaded
2026-03-10T22:48:37.903Z,1773182917.903 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-10T22:48:37.904Z,1773182917.904 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-10T22:48:37.904Z,1773182917.904 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-10T22:48:37.964Z,1773182917.964 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-10T22:48:37.970Z,1773182917.970 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-10T22:48:37.973Z,1773182917.973 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-10T22:48:37.985Z,1773182917.985 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-10T22:48:37.986Z,1773182917.986 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C7F4E0
2026-03-10T22:48:37.986Z,1773182917.986 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1328
2026-03-10T22:48:37.991Z,1773182917.991 [Supervisor](INFO): Main Thread ID is 828
2026-03-10T22:48:37.991Z,1773182917.991 [Supervisor](DEBUG): Running supervisor.
2026-03-10T22:48:37.992Z,1773182917.992 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1329
2026-03-10T22:48:37.992Z,1773182917.992 [CommandExec](INFO): Initializing the command executive.
2026-03-10T22:48:37.994Z,1773182917.994 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1330
2026-03-10T22:48:37.996Z,1773182917.996 [controlThread ThreadHandler](INFO): Handler Thread ID is 1331
2026-03-10T22:48:37.997Z,1773182917.997 [controlThread](DEBUG): Initializing ControlThread
2026-03-10T22:48:37.999Z,1773182917.999 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-10T22:48:38.001Z,1773182918.001 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-10T22:48:38.001Z,1773182918.001 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-10T22:48:38.002Z,1773182918.002 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-10T22:48:38.002Z,1773182918.002 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-10T22:48:38.003Z,1773182918.003 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-10T22:48:38.003Z,1773182918.003 [SBIT](INFO): Initialize SBIT Component.
2026-03-10T22:48:38.004Z,1773182918.004 [SBIT](IMPORTANT): git: 2026-03-10-4-gd32829878
2026-03-10T22:48:38.004Z,1773182918.004 [SBIT](INFO): git hash: d3282987833180dffa14cba482e2d78275c9825f
2026-03-10T22:48:38.004Z,1773182918.004 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-10T22:48:38.005Z,1773182918.005 [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-10T22:48:38.006Z,1773182918.006 [SBIT](INFO): Beginning SBIT in 69.000000 seconds.
2026-03-10T22:48:38.007Z,1773182918.007 [IBIT](INFO): Initialize IBIT Component.
2026-03-10T22:48:38.008Z,1773182918.008 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-10T22:48:38.009Z,1773182918.009 [logger ThreadHandler](INFO): Handler Thread ID is 1332
2026-03-10T22:48:38.021Z,1773182918.021 [CBIT](DEBUG): Initialized Platform.
2026-03-10T22:48:38.021Z,1773182918.021 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-10T22:48:38.033Z,1773182918.033 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1333
2026-03-10T22:48:38.045Z,1773182918.045 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-10T22:48:38.046Z,1773182918.046 [CBIT](DEBUG): Initializing heartbeat.
2026-03-10T22:48:38.046Z,1773182918.046 [DAT ThreadHandler](INFO): Handler Thread ID is 1334
2026-03-10T22:48:38.047Z,1773182918.047 [DAT](INFO): Powering up
2026-03-10T22:48:38.047Z,1773182918.047 [DAT](DEBUG): Initializing DAT.
2026-03-10T22:48:38.050Z,1773182918.050 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1336
2026-03-10T22:48:38.052Z,1773182918.052 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-10T22:48:38.073Z,1773182918.073 [Onboard ThreadHandler](INFO): Handler Thread ID is 1337
2026-03-10T22:48:38.090Z,1773182918.090 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1338
2026-03-10T22:48:38.109Z,1773182918.109 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1339
2026-03-10T22:48:38.109Z,1773182918.109 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T22:48:38.114Z,1773182918.114 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1341
2026-03-10T22:48:38.117Z,1773182918.117 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-10T22:48:38.117Z,1773182918.117 [WetLabsBB2FL](INFO): Powering up
2026-03-10T22:48:38.119Z,1773182918.119 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1343
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2026-03-10T22:48:38.122Z,1773182918.122 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2026-03-10T22:48:38.123Z,1773182918.123 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2026-03-10T22:48:38.157Z,1773182918.157 [CBIT](DEBUG): Backplane powered.
2026-03-10T22:48:38.157Z,1773182918.157 [GFScanner](DEBUG): Initializing GFScanner
2026-03-10T22:48:38.157Z,1773182918.157 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-10T22:48:38.165Z,1773182918.165 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-10T22:48:38.165Z,1773182918.165 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-10T22:48:38.166Z,1773182918.166 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-10T22:48:38.166Z,1773182918.166 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-10T22:48:38.166Z,1773182918.166 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-10T22:48:38.167Z,1773182918.167 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-10T22:48:38.168Z,1773182918.168 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-10T22:48:38.173Z,1773182918.173 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-10T22:48:38.174Z,1773182918.174 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-10T22:48:38.252Z,1773182918.252 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-03-10T22:48:38.256Z,1773182918.256 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T22:48:38.321Z,1773182918.321 [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-10T22:48:38.322Z,1773182918.322 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-10T22:48:38.322Z,1773182918.322 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-10T22:48:38.323Z,1773182918.323 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-10T22:48:38.497Z,1773182918.497 [Radio_Surface](INFO): Powering up
2026-03-10T22:48:38.688Z,1773182918.688 [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-10T22:48:38.698Z,1773182918.698 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-10T22:48:38.699Z,1773182918.699 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-10T22:48:38.722Z,1773182918.722 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-10T22:48:38.744Z,1773182918.744 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-10T22:48:38.771Z,1773182918.771 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-10T22:48:38.777Z,1773182918.777 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-10T22:48:38.780Z,1773182918.780 [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-10T22:48:38.809Z,1773182918.809 [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-10T22:48:38.861Z,1773182918.861 [Depth_Keller](INFO): Initializing.
2026-03-10T22:48:38.862Z,1773182918.862 [Power24vConverter](INFO): Powering up.
2026-03-10T22:48:38.863Z,1773182918.863 [Sonardyne_Nano](INFO): Initializing.
2026-03-10T22:48:38.909Z,1773182918.909 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-10T22:48:38.936Z,1773182918.936 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-10T22:48:38.945Z,1773182918.945 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-10T22:48:38.946Z,1773182918.946 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-10T22:48:38.953Z,1773182918.953 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-10T22:48:38.954Z,1773182918.954 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-10T22:48:38.961Z,1773182918.961 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-10T22:48:43.409Z,1773182923.409 [WetLabsBB2FL] Communications Fault, FailCount= 1
2026-03-10T22:48:43.409Z,1773182923.409 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T22:48:43.711Z,1773182923.711 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T22:48:43.909Z,1773182923.909 [WetLabsBB2FL](INFO): Powering down
2026-03-10T22:48:44.510Z,1773182924.510 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-03-10T22:48:45.045Z,1773182925.045 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T22:48:45.045Z,1773182925.045 [WetLabsBB2FL] No Fault, FailCount= 1
2026-03-10T22:48:45.122Z,1773182925.122 [WetLabsBB2FL](INFO): Powering up
2026-03-10T22:48:48.156Z,1773182928.156 [CBIT](CRITICAL): Environmental Failure. Press:14.750089 PSI. Humidity:42%. Temp:25 C. ABORTING MISSION
2026-03-10T22:48:48.165Z,1773182928.165 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2026-03-10T22:48:48.534Z,1773182928.534 [CommandExec](FAULT): Scheduling is paused
2026-03-10T22:48:48.534Z,1773182928.534 [CBIT](INFO): Critical error at 20260310T224848
2026-03-10T22:48:49.642Z,1773182929.642 [DAT](INFO): DAT read:
2026-03-10T22:48:49.643Z,1773182929.643 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-03-10T22:48:50.413Z,1773182930.413 [WetLabsBB2FL] Communications Fault, FailCount= 2
2026-03-10T22:48:50.413Z,1773182930.413 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T22:48:50.576Z,1773182930.576 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T22:48:50.913Z,1773182930.913 [WetLabsBB2FL](INFO): Powering down
2026-03-10T22:48:51.161Z,1773182931.161 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T22:48:51.161Z,1773182931.161 [CTD_Seabird] Communications Fault, FailCount= 1
2026-03-10T22:48:51.161Z,1773182931.161 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T22:48:51.372Z,1773182931.372 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T22:48:51.408Z,1773182931.408 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth
2026-03-10T22:48:51.412Z,1773182931.412 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1
2026-03-10T22:48:51.414Z,1773182931.414 [DAT](INFO): DAT read: Mar 10 2026 22:48:46
2026-03-10T22:48:51.566Z,1773182931.566 [CTD_Seabird](INFO): Powering down
2026-03-10T22:48:51.797Z,1773182931.797 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T22:48:51.797Z,1773182931.797 [WetLabsBB2FL] No Fault, FailCount= 2
2026-03-10T22:48:52.126Z,1773182932.126 [WetLabsBB2FL](INFO): Powering up
2026-03-10T22:48:52.162Z,1773182932.162 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-03-10T22:48:52.163Z,1773182932.163 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2026-03-10T22:48:52.163Z,1773182932.163 [DAT](INFO): commRate: 800
2026-03-10T22:48:52.572Z,1773182932.572 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2026-03-10T22:48:52.572Z,1773182932.572 [CTD_Seabird] No Fault, FailCount= 1
2026-03-10T22:48:52.909Z,1773182932.909 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T22:48:54.233Z,1773182934.233 [DAT](INFO): entering command mode
2026-03-10T22:48:54.429Z,1773182934.429 [DAT](INFO): DAT read:
2026-03-10T22:48:54.430Z,1773182934.430 [DAT](INFO): DAT read: user:1>
2026-03-10T22:48:54.430Z,1773182934.430 [DAT](INFO): setting verbose to 3
2026-03-10T22:48:54.681Z,1773182934.681 [DAT](INFO): DAT read: user:1>
2026-03-10T22:48:54.683Z,1773182934.683 [DAT](INFO): DAT read: Verbose | 3
2026-03-10T22:48:54.683Z,1773182934.683 [DAT](INFO): set verbose to 3
2026-03-10T22:48:54.683Z,1773182934.683 [DAT](INFO): setting DatVerbose to 27440
2026-03-10T22:48:54.933Z,1773182934.933 [DAT](INFO): DAT read: user:2>
2026-03-10T22:48:54.934Z,1773182934.934 [DAT](INFO): DAT read: DatVerbose | 27440
2026-03-10T22:48:54.935Z,1773182934.935 [DAT](INFO): set DatVerbose to 27440
2026-03-10T22:48:54.935Z,1773182934.935 [DAT](INFO): setting transmit power to 8
2026-03-10T22:48:55.186Z,1773182935.186 [DAT](INFO): DAT read: user:3>
2026-03-10T22:48:55.187Z,1773182935.187 [DAT](INFO): DAT read: TxPower | 8 (Max)
2026-03-10T22:48:55.187Z,1773182935.187 [DAT](INFO): set transmit power to 8
2026-03-10T22:48:55.187Z,1773182935.187 [DAT](INFO): setting local address to 2
2026-03-10T22:48:55.437Z,1773182935.437 [DAT](INFO): DAT read: user:4>
2026-03-10T22:48:55.438Z,1773182935.438 [DAT](INFO): DAT read: LocalAddr | 2
2026-03-10T22:48:55.439Z,1773182935.439 [DAT](INFO): set local address to 2
2026-03-10T22:48:55.440Z,1773182935.440 [DAT](INFO): Setting time to: 22:48:55 And date to:3/10/2026
2026-03-10T22:48:55.689Z,1773182935.689 [DAT](INFO): DAT read: user:5>
2026-03-10T22:48:55.690Z,1773182935.690 [DAT](INFO): DAT read: Tue Mar 10, 2026 22:48:55
2026-03-10T22:48:55.691Z,1773182935.691 [DAT](INFO): Local DAT time set to Tue Mar 10, 2026 22:48:55
2026-03-10T22:48:57.417Z,1773182937.417 [WetLabsBB2FL] Communications Fault, FailCount= 3
2026-03-10T22:48:57.417Z,1773182937.417 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T22:48:57.451Z,1773182937.451 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T22:48:57.913Z,1773182937.913 [WetLabsBB2FL](INFO): Powering down
2026-03-10T22:48:59.060Z,1773182939.060 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T22:48:59.065Z,1773182939.065 [WetLabsBB2FL] No Fault, FailCount= 3
2026-03-10T22:48:59.126Z,1773182939.126 [WetLabsBB2FL](INFO): Powering up
2026-03-10T22:49:04.416Z,1773182944.416 [WetLabsBB2FL] Communications Fault, FailCount= 4
2026-03-10T22:49:04.417Z,1773182944.417 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T22:49:04.705Z,1773182944.705 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T22:49:04.917Z,1773182944.917 [WetLabsBB2FL](INFO): Powering down
2026-03-10T22:49:05.914Z,1773182945.914 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2026-03-10T22:49:05.914Z,1773182945.914 [WetLabsBB2FL] No Fault, FailCount= 4
2026-03-10T22:49:05.961Z,1773182945.961 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T22:49:05.961Z,1773182945.961 [CTD_Seabird] Communications Fault, FailCount= 2
2026-03-10T22:49:05.961Z,1773182945.961 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T22:49:06.131Z,1773182946.131 [WetLabsBB2FL](INFO): Powering up
2026-03-10T22:49:06.294Z,1773182946.294 [NAL9602](INFO): Powering up NAL9602
2026-03-10T22:49:06.332Z,1773182946.332 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2026-03-10T22:49:06.365Z,1773182946.365 [CTD_Seabird](INFO): Powering down
2026-03-10T22:49:07.550Z,1773182947.550 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2026-03-10T22:49:07.550Z,1773182947.550 [CTD_Seabird] No Fault, FailCount= 2
2026-03-10T22:49:07.709Z,1773182947.709 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-10T22:49:11.436Z,1773182951.436 [WetLabsBB2FL] Communications Fault, FailCount= 5
2026-03-10T22:49:11.437Z,1773182951.437 [WetLabsBB2FL](ERROR): Communications Fault
2026-03-10T22:49:11.575Z,1773182951.575 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2026-03-10T22:49:11.576Z,1773182951.576 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2026-03-10T22:49:11.937Z,1773182951.937 [WetLabsBB2FL](INFO): Powering down
2026-03-10T22:49:17.219Z,1773182957.219 [NAL9602](INFO): NAL9602 initialized
2026-03-10T22:49:20.761Z,1773182960.761 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2026-03-10T22:49:20.761Z,1773182960.761 [CTD_Seabird] Communications Fault, FailCount= 3
2026-03-10T22:49:20.761Z,1773182960.761 [CTD_Seabird](ERROR): Communications Fault
2026-03-10T22:49:20.879Z,1773182960.879 [