2025-04-14T15:24:56.500Z,1744644296.500 [Supervisor](DEBUG): Initializing supervisor. 2025-04-14T15:24:56.505Z,1744644296.505 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-14T15:24:56.505Z,1744644296.505 [SyncHandler](INFO): Protected caller Thread ID is 9673 2025-04-14T15:24:56.506Z,1744644296.506 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-14T15:24:56.506Z,1744644296.506 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-14T15:24:56.507Z,1744644296.507 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9674 2025-04-14T15:24:56.511Z,1744644296.511 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-14T15:24:56.531Z,1744644296.531 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-14T15:24:56.532Z,1744644296.532 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-14T15:24:56.532Z,1744644296.532 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9675 2025-04-14T15:24:56.536Z,1744644296.536 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-14T15:24:56.537Z,1744644296.537 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-14T15:24:56.538Z,1744644296.538 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9676 2025-04-14T15:24:56.540Z,1744644296.540 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-14T15:24:56.541Z,1744644296.541 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-14T15:24:56.541Z,1744644296.541 [logger ThreadHandler](INFO): Protected caller Thread ID is 9677 2025-04-14T15:24:56.545Z,1744644296.545 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-14T15:24:56.545Z,1744644296.545 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-14T15:24:56.549Z,1744644296.549 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-14T15:24:56.958Z,1744644296.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-14T15:24:56.960Z,1744644296.960 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-14T15:24:57.053Z,1744644297.053 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-14T15:24:57.171Z,1744644297.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-14T15:24:57.173Z,1744644297.173 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-14T15:24:57.313Z,1744644297.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-14T15:24:57.315Z,1744644297.315 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-14T15:24:57.670Z,1744644297.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-14T15:24:57.671Z,1744644297.671 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-14T15:24:57.779Z,1744644297.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-14T15:24:57.780Z,1744644297.780 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-14T15:24:58.206Z,1744644298.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-14T15:24:58.208Z,1744644298.208 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-14T15:24:58.443Z,1744644298.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-14T15:24:58.445Z,1744644298.445 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-14T15:24:58.537Z,1744644298.537 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-14T15:24:58.790Z,1744644298.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-14T15:24:58.791Z,1744644298.791 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-14T15:24:59.022Z,1744644299.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-14T15:24:59.023Z,1744644299.023 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-14T15:24:59.561Z,1744644299.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-14T15:24:59.561Z,1744644299.561 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-14T15:24:59.764Z,1744644299.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-14T15:24:59.766Z,1744644299.766 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-14T15:25:00.065Z,1744644300.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-14T15:25:00.065Z,1744644300.065 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-14T15:25:01.044Z,1744644301.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-14T15:25:01.045Z,1744644301.045 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-14T15:25:01.620Z,1744644301.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-14T15:25:01.622Z,1744644301.622 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-04-14T15:25:01.624Z,1744644301.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-04-14T15:25:01.732Z,1744644301.732 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-04-14T15:25:01.877Z,1744644301.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-04-14T15:25:01.973Z,1744644301.973 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-04-14T15:25:02.093Z,1744644302.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-04-14T15:25:02.192Z,1744644302.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-04-14T15:25:02.463Z,1744644302.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-14T15:25:02.464Z,1744644302.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-04-14T15:25:02.586Z,1744644302.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-04-14T15:25:02.697Z,1744644302.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-04-14T15:25:02.852Z,1744644302.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-04-14T15:25:02.950Z,1744644302.950 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2025-04-14T15:25:02.950Z,1744644302.950 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-14T15:25:02.958Z,1744644302.958 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-14T15:25:03.124Z,1744644303.124 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-14T15:25:03.125Z,1744644303.125 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-14T15:25:03.197Z,1744644303.197 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-14T15:25:03.257Z,1744644303.257 [VerticalControl] Loaded 2025-04-14T15:25:03.258Z,1744644303.258 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-14T15:25:03.260Z,1744644303.260 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-14T15:25:03.307Z,1744644303.307 [HorizontalControl] Loaded 2025-04-14T15:25:03.307Z,1744644303.307 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-14T15:25:03.309Z,1744644303.309 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-14T15:25:03.313Z,1744644303.313 [SpeedControl] Loaded 2025-04-14T15:25:03.313Z,1744644303.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-14T15:25:03.315Z,1744644303.315 [LoopControl](DEBUG): Construct LoopControl. 2025-04-14T15:25:03.316Z,1744644303.316 [LoopControl] Loaded 2025-04-14T15:25:03.316Z,1744644303.316 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-14T15:25:03.317Z,1744644303.317 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-14T15:25:03.318Z,1744644303.318 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-14T15:25:03.404Z,1744644303.404 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-14T15:25:03.404Z,1744644303.404 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-14T15:25:04.180Z,1744644304.180 [AHRS_M2] Loaded 2025-04-14T15:25:04.180Z,1744644304.180 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-14T15:25:04.433Z,1744644304.433 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-04-14T15:25:04.434Z,1744644304.434 [BackseatComponent] Loaded 2025-04-14T15:25:04.434Z,1744644304.434 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-04-14T15:25:04.435Z,1744644304.435 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-04-14T15:25:04.435Z,1744644304.435 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9757 2025-04-14T15:25:04.438Z,1744644304.438 [LcmUniversalReporter] Loaded 2025-04-14T15:25:04.438Z,1744644304.438 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-04-14T15:25:05.236Z,1744644305.236 [BPC1] Loaded 2025-04-14T15:25:05.236Z,1744644305.236 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-04-14T15:25:05.331Z,1744644305.331 [DAT] Loaded 2025-04-14T15:25:05.331Z,1744644305.331 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-04-14T15:25:05.332Z,1744644305.332 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-04-14T15:25:05.333Z,1744644305.333 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9758 2025-04-14T15:25:05.531Z,1744644305.531 [DataOverHttps] Loaded 2025-04-14T15:25:05.531Z,1744644305.531 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-14T15:25:05.532Z,1744644305.532 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-04-14T15:25:05.533Z,1744644305.533 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9759 2025-04-14T15:25:05.556Z,1744644305.556 [Depth_Keller] Loaded 2025-04-14T15:25:05.556Z,1744644305.556 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-04-14T15:25:05.561Z,1744644305.561 [DropWeight] Loaded 2025-04-14T15:25:05.561Z,1744644305.561 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-04-14T15:25:05.625Z,1744644305.625 [NAL9602] Loaded 2025-04-14T15:25:05.626Z,1744644305.626 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-14T15:25:05.655Z,1744644305.655 [Onboard] Loaded 2025-04-14T15:25:05.655Z,1744644305.655 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-14T15:25:05.656Z,1744644305.656 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-04-14T15:25:05.657Z,1744644305.657 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9760 2025-04-14T15:25:05.671Z,1744644305.671 [Power24vConverter] Loaded 2025-04-14T15:25:05.671Z,1744644305.671 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-14T15:25:05.687Z,1744644305.687 [Radio_Surface] Loaded 2025-04-14T15:25:05.687Z,1744644305.687 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-14T15:25:05.688Z,1744644305.688 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-04-14T15:25:05.689Z,1744644305.689 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9761 2025-04-14T15:25:05.704Z,1744644305.704 [Sonardyne_Nano] Loaded 2025-04-14T15:25:05.704Z,1744644305.704 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-04-14T15:25:05.705Z,1744644305.705 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-14T15:25:05.705Z,1744644305.705 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-14T15:25:05.721Z,1744644305.721 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-14T15:25:05.721Z,1744644305.721 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-14T15:25:05.769Z,1744644305.769 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-14T15:25:05.769Z,1744644305.769 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-14T15:25:05.962Z,1744644305.962 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-14T15:25:05.971Z,1744644305.971 [SBIT] Loaded 2025-04-14T15:25:05.972Z,1744644305.972 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-14T15:25:05.974Z,1744644305.974 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-14T15:25:05.987Z,1744644305.987 [IBIT] Loaded 2025-04-14T15:25:05.987Z,1744644305.987 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-14T15:25:05.992Z,1744644305.992 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-14T15:25:06.071Z,1744644306.071 [CBIT] Loaded 2025-04-14T15:25:06.071Z,1744644306.071 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-14T15:25:06.103Z,1744644306.103 [GFScanner] Loaded 2025-04-14T15:25:06.104Z,1744644306.104 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-04-14T15:25:06.104Z,1744644306.104 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-14T15:25:06.105Z,1744644306.105 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-14T15:25:06.529Z,1744644306.529 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-14T15:25:06.529Z,1744644306.529 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-14T15:25:06.600Z,1744644306.600 [DepthRateCalculator] Loaded 2025-04-14T15:25:06.601Z,1744644306.601 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-14T15:25:06.606Z,1744644306.606 [PitchRateCalculator] Loaded 2025-04-14T15:25:06.606Z,1744644306.606 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-14T15:25:06.616Z,1744644306.616 [SpeedCalculator] Loaded 2025-04-14T15:25:06.616Z,1744644306.616 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-14T15:25:06.621Z,1744644306.621 [YawRateCalculator] Loaded 2025-04-14T15:25:06.621Z,1744644306.621 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-14T15:25:06.639Z,1744644306.639 [ElevatorOffsetCalculator] Loaded 2025-04-14T15:25:06.640Z,1744644306.640 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-14T15:25:06.640Z,1744644306.640 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-14T15:25:06.641Z,1744644306.641 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-14T15:25:06.854Z,1744644306.854 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-14T15:25:06.855Z,1744644306.855 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-14T15:25:06.992Z,1744644306.992 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-14T15:25:06.993Z,1744644306.993 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-14T15:25:07.006Z,1744644307.006 [NavChart] Loaded 2025-04-14T15:25:07.007Z,1744644307.007 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-14T15:25:07.012Z,1744644307.012 [UniversalFixResidualReporter] Loaded 2025-04-14T15:25:07.013Z,1744644307.013 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-14T15:25:07.013Z,1744644307.013 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-14T15:25:07.014Z,1744644307.014 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-14T15:25:07.185Z,1744644307.185 [BuoyancyServo] Loaded 2025-04-14T15:25:07.185Z,1744644307.185 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-14T15:25:07.208Z,1744644307.208 [ElevatorServo] Loaded 2025-04-14T15:25:07.209Z,1744644307.209 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-14T15:25:07.230Z,1744644307.230 [MassServo] Loaded 2025-04-14T15:25:07.231Z,1744644307.231 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-14T15:25:07.251Z,1744644307.251 [RudderServo] Loaded 2025-04-14T15:25:07.251Z,1744644307.251 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-14T15:25:07.268Z,1744644307.268 [ThrusterHE] Loaded 2025-04-14T15:25:07.269Z,1744644307.269 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-14T15:25:07.269Z,1744644307.269 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-14T15:25:07.270Z,1744644307.270 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-14T15:25:07.485Z,1744644307.485 [CTD_Seabird] Loaded 2025-04-14T15:25:07.485Z,1744644307.485 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-14T15:25:07.487Z,1744644307.487 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2025-04-14T15:25:07.487Z,1744644307.487 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9762 2025-04-14T15:25:07.523Z,1744644307.523 [ESPComponent] Loaded 2025-04-14T15:25:07.524Z,1744644307.524 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-04-14T15:25:07.545Z,1744644307.545 [PAR_Licor] Loaded 2025-04-14T15:25:07.546Z,1744644307.546 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-04-14T15:25:07.591Z,1744644307.591 [WetLabsBB2FL] Loaded 2025-04-14T15:25:07.592Z,1744644307.592 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-04-14T15:25:07.593Z,1744644307.593 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-04-14T15:25:07.593Z,1744644307.593 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9763 2025-04-14T15:25:07.594Z,1744644307.594 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-14T15:25:07.600Z,1744644307.600 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-14T15:25:07.603Z,1744644307.603 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-14T15:25:07.614Z,1744644307.614 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-14T15:25:07.615Z,1744644307.615 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-04-14T15:25:07.616Z,1744644307.616 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9764 2025-04-14T15:25:07.620Z,1744644307.620 [Supervisor](INFO): Main Thread ID is 9672 2025-04-14T15:25:07.620Z,1744644307.620 [Supervisor](DEBUG): Running supervisor. 2025-04-14T15:25:07.621Z,1744644307.621 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9765 2025-04-14T15:25:07.621Z,1744644307.621 [CommandExec](INFO): Initializing the command executive. 2025-04-14T15:25:07.623Z,1744644307.623 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9766 2025-04-14T15:25:07.625Z,1744644307.625 [controlThread ThreadHandler](INFO): Handler Thread ID is 9767 2025-04-14T15:25:07.626Z,1744644307.626 [controlThread](DEBUG): Initializing ControlThread 2025-04-14T15:25:07.626Z,1744644307.626 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-14T15:25:07.628Z,1744644307.628 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-14T15:25:07.629Z,1744644307.629 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-14T15:25:07.629Z,1744644307.629 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-14T15:25:07.632Z,1744644307.632 [SBIT](INFO): Initialize SBIT Component. 2025-04-14T15:25:07.633Z,1744644307.633 [SBIT](IMPORTANT): git: 2025-04-02-2-g1635786d1 2025-04-14T15:25:07.633Z,1744644307.633 [SBIT](INFO): git hash: 1635786d188a1b3aba7073e6184020eb8609012e 2025-04-14T15:25:07.633Z,1744644307.633 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-14T15:25:07.634Z,1744644307.634 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2025-04-14T15:25:07.635Z,1744644307.635 [SBIT](INFO): Beginning SBIT in 109.000000 seconds. 2025-04-14T15:25:07.636Z,1744644307.636 [IBIT](INFO): Initialize IBIT Component. 2025-04-14T15:25:07.637Z,1744644307.637 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-14T15:25:07.638Z,1744644307.638 [logger ThreadHandler](INFO): Handler Thread ID is 9768 2025-04-14T15:25:07.651Z,1744644307.651 [CBIT](DEBUG): Initialized mux pins. 2025-04-14T15:25:07.651Z,1744644307.651 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-14T15:25:07.663Z,1744644307.663 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9769 2025-04-14T15:25:07.675Z,1744644307.675 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-14T15:25:07.675Z,1744644307.675 [CBIT](DEBUG): Initializing heartbeat. 2025-04-14T15:25:07.676Z,1744644307.676 [DAT ThreadHandler](INFO): Handler Thread ID is 9770 2025-04-14T15:25:07.677Z,1744644307.677 [DAT](INFO): Powering up 2025-04-14T15:25:07.677Z,1744644307.677 [DAT](DEBUG): Initializing DAT. 2025-04-14T15:25:07.680Z,1744644307.680 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9772 2025-04-14T15:25:07.682Z,1744644307.682 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-14T15:25:07.703Z,1744644307.703 [Onboard ThreadHandler](INFO): Handler Thread ID is 9773 2025-04-14T15:25:07.721Z,1744644307.721 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9774 2025-04-14T15:25:07.740Z,1744644307.740 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9775 2025-04-14T15:25:07.740Z,1744644307.740 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-14T15:25:07.744Z,1744644307.744 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9777 2025-04-14T15:25:07.747Z,1744644307.747 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-14T15:25:07.748Z,1744644307.748 [WetLabsBB2FL](INFO): Powering up 2025-04-14T15:25:07.749Z,1744644307.749 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9779 2025-04-14T15:25:07.755Z,1744644307.755 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-04-14T15:25:07.756Z,1744644307.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-04-14T15:25:07.756Z,1744644307.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-04-14T15:25:07.756Z,1744644307.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-04-14T15:25:07.756Z,1744644307.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-04-14T15:25:07.756Z,1744644307.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-04-14T15:25:07.756Z,1744644307.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-04-14T15:25:07.757Z,1744644307.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-04-14T15:25:07.787Z,1744644307.787 [CBIT](DEBUG): Backplane powered. 2025-04-14T15:25:07.787Z,1744644307.787 [GFScanner](DEBUG): Initializing GFScanner 2025-04-14T15:25:07.788Z,1744644307.788 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-14T15:25:07.788Z,1744644307.788 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-14T15:25:07.788Z,1744644307.788 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-14T15:25:07.789Z,1744644307.789 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-14T15:25:07.789Z,1744644307.789 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-14T15:25:07.790Z,1744644307.790 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-14T15:25:07.791Z,1744644307.791 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-14T15:25:07.796Z,1744644307.796 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-14T15:25:07.796Z,1744644307.796 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-14T15:25:07.797Z,1744644307.797 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-14T15:25:07.804Z,1744644307.804 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-14T15:25:07.868Z,1744644307.868 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-14T15:25:07.873Z,1744644307.873 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-14T15:25:07.931Z,1744644307.931 [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 } } } 2025-04-14T15:25:07.939Z,1744644307.939 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-14T15:25:07.940Z,1744644307.940 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-14T15:25:07.945Z,1744644307.945 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-14T15:25:08.131Z,1744644308.131 [Radio_Surface](INFO): Powering up 2025-04-14T15:25:08.236Z,1744644308.236 [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 2025-04-14T15:25:08.261Z,1744644308.261 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-14T15:25:08.262Z,1744644308.262 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-14T15:25:08.264Z,1744644308.264 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-14T15:25:08.310Z,1744644308.310 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-14T15:25:08.312Z,1744644308.312 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-14T15:25:08.334Z,1744644308.334 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-14T15:25:08.337Z,1744644308.337 [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" } } 2025-04-14T15:25:08.348Z,1744644308.348 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-14T15:25:08.371Z,1744644308.371 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-14T15:25:08.459Z,1744644308.459 [Depth_Keller](INFO): Initializing. 2025-04-14T15:25:08.461Z,1744644308.461 [Power24vConverter](INFO): Powering up. 2025-04-14T15:25:08.461Z,1744644308.461 [Sonardyne_Nano](INFO): Initializing. 2025-04-14T15:25:08.499Z,1744644308.499 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-14T15:25:08.526Z,1744644308.526 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-14T15:25:08.531Z,1744644308.531 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-14T15:25:08.532Z,1744644308.532 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-14T15:25:08.539Z,1744644308.539 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-14T15:25:08.540Z,1744644308.540 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-14T15:25:08.548Z,1744644308.548 [MassServo](DEBUG): Initializing MassServo. 2025-04-14T15:25:08.548Z,1744644308.548 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-14T15:25:08.556Z,1744644308.556 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-14T15:25:08.557Z,1744644308.557 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-14T15:25:08.563Z,1744644308.563 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-14T15:25:09.883Z,1744644309.883 [WetLabsBB2FL](INFO): Powering down 2025-04-14T15:25:11.662Z,1744644311.662 [Sonardyne_Nano](FAULT): Failed hardware self-test:>CKHW:FAIL;LBK 2025-04-14T15:25:11.662Z,1744644311.662 [Sonardyne_Nano] Hardware Fault, FailCount= 1 2025-04-14T15:25:11.662Z,1744644311.662 [Sonardyne_Nano](ERROR): Hardware Fault 2025-04-14T15:25:11.730Z,1744644311.730 [CBIT](ERROR): Hardware Fault in component: Sonardyne_Nano 2025-04-14T15:25:12.021Z,1744644312.021 [Sonardyne_Nano](INFO): Powering down 2025-04-14T15:25:13.115Z,1744644313.115 [CBIT](INFO): Clearing failed state for component Sonardyne_Nano 2025-04-14T15:25:13.115Z,1744644313.115 [Sonardyne_Nano] No Fault, FailCount= 1 2025-04-14T15:25:13.345Z,1744644313.345 [Sonardyne_Nano](INFO): Initializing. 2025-04-14T15:25:15.365Z,1744644315.365 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-14T15:25:16.568Z,1744644316.568 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-04-14T15:25:20.782Z,1744644320.782 [DAT](INFO): DAT read: 2025-04-14T15:25:20.784Z,1744644320.784 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-14T15:25:21.913Z,1744644321.913 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004567 2025-04-14T15:25:22.544Z,1744644322.544 [DAT](INFO): DAT read: MF Frequency Band 2025-04-14T15:25:22.545Z,1744644322.545 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-04-14T15:25:22.546Z,1744644322.546 [DAT](INFO): DAT read: Apr 11 2025 19:29:08 2025-04-14T15:25:23.804Z,1744644323.804 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-04-14T15:25:23.805Z,1744644323.805 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-14T15:25:23.806Z,1744644323.806 [DAT](INFO): commRate: 800 2025-04-14T15:25:25.871Z,1744644325.871 [DAT](INFO): entering command mode 2025-04-14T15:25:26.072Z,1744644326.072 [DAT](INFO): DAT read: 2025-04-14T15:25:26.072Z,1744644326.072 [DAT](INFO): DAT read: user:1> 2025-04-14T15:25:26.073Z,1744644326.073 [DAT](INFO): setting verbose to 3 2025-04-14T15:25:26.324Z,1744644326.324 [DAT](INFO): DAT read: user:1> 2025-04-14T15:25:26.325Z,1744644326.325 [DAT](INFO): DAT read: Verbose | 3 2025-04-14T15:25:26.326Z,1744644326.326 [DAT](INFO): set verbose to 3 2025-04-14T15:25:26.326Z,1744644326.326 [DAT](INFO): setting DatVerbose to 27440 2025-04-14T15:25:26.576Z,1744644326.576 [DAT](INFO): DAT read: user:2> 2025-04-14T15:25:26.577Z,1744644326.577 [DAT](INFO): DAT read: DatVerbose | 27440 2025-04-14T15:25:26.577Z,1744644326.577 [DAT](INFO): set DatVerbose to 27440 2025-04-14T15:25:26.577Z,1744644326.577 [DAT](INFO): setting transmit power to 8 2025-04-14T15:25:26.828Z,1744644326.828 [DAT](INFO): DAT read: user:3> 2025-04-14T15:25:26.829Z,1744644326.829 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-04-14T15:25:26.829Z,1744644326.829 [DAT](INFO): set transmit power to 8 2025-04-14T15:25:26.829Z,1744644326.829 [DAT](INFO): setting local address to 1 2025-04-14T15:25:27.080Z,1744644327.080 [DAT](INFO): DAT read: user:4> 2025-04-14T15:25:27.081Z,1744644327.081 [DAT](INFO): DAT read: LocalAddr | 1 2025-04-14T15:25:27.081Z,1744644327.081 [DAT](INFO): set local address to 1 2025-04-14T15:25:27.082Z,1744644327.082 [DAT](INFO): Setting time to: 15:25:27 And date to:4/14/2025 2025-04-14T15:25:27.332Z,1744644327.332 [DAT](INFO): DAT read: user:5> 2025-04-14T15:25:27.333Z,1744644327.333 [DAT](INFO): DAT read: Mon Apr 14, 2025 15:25:27 2025-04-14T15:25:27.333Z,1744644327.333 [DAT](INFO): Local DAT time set to Mon Apr 14, 2025 15:25:27 2025-04-14T15:25:35.708Z,1744644335.708 [CommandExec](IMPORTANT): got command restart system 2025-04-14T15:25:35.954Z,1744644335.954 [NAL9602](INFO): Powering up NAL9602 2025-04-14T15:25:37.875Z,1744644337.875 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-04-14T15:25:37.875Z,1744644337.875 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-14T15:25:37.876Z,1744644337.876 [CommandExec](INFO): Uninitializing the command executive. 2025-04-14T15:25:37.876Z,1744644337.876 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-14T15:25:37.876Z,1744644337.876 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:37.947Z,1744644337.947 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-14T15:25:37.947Z,1744644337.947 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-14T15:25:37.947Z,1744644337.947 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:37.948Z,1744644337.948 [NavChartDb](INFO): Join timeout helper Thread ID is 9791 2025-04-14T15:25:39.090Z,1744644339.090 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-14T15:25:39.090Z,1744644339.090 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:39.103Z,1744644339.103 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-14T15:25:39.103Z,1744644339.103 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:39.103Z,1744644339.103 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9792 2025-04-14T15:25:39.531Z,1744644339.531 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-14T15:25:39.532Z,1744644339.532 [WetLabsBB2FL](INFO): Powering down 2025-04-14T15:25:39.532Z,1744644339.532 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:39.543Z,1744644339.543 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-14T15:25:39.543Z,1744644339.543 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:39.543Z,1744644339.543 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9793 2025-04-14T15:25:39.867Z,1744644339.867 [CTD_Seabird](INFO): Powering down 2025-04-14T15:25:39.879Z,1744644339.879 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-14T15:25:39.879Z,1744644339.879 [CTD_Seabird](INFO): Powering down 2025-04-14T15:25:39.891Z,1744644339.891 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:39.907Z,1744644339.907 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-14T15:25:39.907Z,1744644339.907 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-14T15:25:39.907Z,1744644339.907 [Radio_Surfa