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](