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 [