2025-03-20T18:06:38.573Z,1742493998.573 [Supervisor](DEBUG): Initializing supervisor. 2025-03-20T18:06:38.578Z,1742493998.578 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-20T18:06:38.578Z,1742493998.578 [SyncHandler](INFO): Protected caller Thread ID is 968 2025-03-20T18:06:38.579Z,1742493998.579 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-20T18:06:38.579Z,1742493998.579 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-20T18:06:38.580Z,1742493998.580 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 969 2025-03-20T18:06:38.584Z,1742493998.584 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-20T18:06:38.604Z,1742493998.604 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-20T18:06:38.605Z,1742493998.605 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-20T18:06:38.605Z,1742493998.605 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 970 2025-03-20T18:06:38.609Z,1742493998.609 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-20T18:06:38.610Z,1742493998.610 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-20T18:06:38.611Z,1742493998.611 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 971 2025-03-20T18:06:38.613Z,1742493998.613 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-20T18:06:38.614Z,1742493998.614 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-20T18:06:38.614Z,1742493998.614 [logger ThreadHandler](INFO): Protected caller Thread ID is 972 2025-03-20T18:06:38.618Z,1742493998.618 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-20T18:06:38.619Z,1742493998.619 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-20T18:06:38.620Z,1742493998.620 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-20T18:06:38.963Z,1742493998.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-20T18:06:38.964Z,1742493998.964 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-20T18:06:39.054Z,1742493999.054 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-20T18:06:39.171Z,1742493999.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-20T18:06:39.172Z,1742493999.172 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-20T18:06:39.311Z,1742493999.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-20T18:06:39.312Z,1742493999.312 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-20T18:06:39.663Z,1742493999.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-20T18:06:39.664Z,1742493999.664 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-20T18:06:39.770Z,1742493999.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-20T18:06:39.771Z,1742493999.771 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-20T18:06:40.191Z,1742494000.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-20T18:06:40.192Z,1742494000.192 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-20T18:06:40.426Z,1742494000.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-20T18:06:40.426Z,1742494000.426 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-20T18:06:40.516Z,1742494000.516 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-20T18:06:40.764Z,1742494000.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-20T18:06:40.765Z,1742494000.765 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-20T18:06:40.992Z,1742494000.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-20T18:06:40.993Z,1742494000.993 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-20T18:06:41.540Z,1742494001.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-20T18:06:41.540Z,1742494001.540 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-20T18:06:41.740Z,1742494001.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-20T18:06:41.740Z,1742494001.740 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-20T18:06:42.033Z,1742494002.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-20T18:06:42.033Z,1742494002.033 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-20T18:06:42.899Z,1742494002.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-20T18:06:42.900Z,1742494002.900 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-20T18:06:43.470Z,1742494003.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-20T18:06:43.472Z,1742494003.472 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-03-20T18:06:43.473Z,1742494003.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-03-20T18:06:43.653Z,1742494003.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-03-20T18:06:43.796Z,1742494003.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-03-20T18:06:43.891Z,1742494003.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-03-20T18:06:44.010Z,1742494004.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-03-20T18:06:44.108Z,1742494004.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-03-20T18:06:44.380Z,1742494004.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-20T18:06:44.380Z,1742494004.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-03-20T18:06:44.503Z,1742494004.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-03-20T18:06:44.614Z,1742494004.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-03-20T18:06:44.769Z,1742494004.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-03-20T18:06:44.865Z,1742494004.865 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-20T18:06:44.867Z,1742494004.867 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-20T18:06:45.001Z,1742494005.001 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-20T18:06:45.001Z,1742494005.001 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-20T18:06:45.056Z,1742494005.056 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-20T18:06:45.117Z,1742494005.117 [VerticalControl] Loaded 2025-03-20T18:06:45.117Z,1742494005.117 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-20T18:06:45.120Z,1742494005.120 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-20T18:06:45.167Z,1742494005.167 [HorizontalControl] Loaded 2025-03-20T18:06:45.167Z,1742494005.167 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-20T18:06:45.169Z,1742494005.169 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-20T18:06:45.172Z,1742494005.172 [SpeedControl] Loaded 2025-03-20T18:06:45.173Z,1742494005.173 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-20T18:06:45.175Z,1742494005.175 [LoopControl](DEBUG): Construct LoopControl. 2025-03-20T18:06:45.176Z,1742494005.176 [LoopControl] Loaded 2025-03-20T18:06:45.176Z,1742494005.176 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-20T18:06:45.176Z,1742494005.176 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-20T18:06:45.177Z,1742494005.177 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-20T18:06:45.236Z,1742494005.236 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-20T18:06:45.237Z,1742494005.237 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-20T18:06:45.713Z,1742494005.713 [AHRS_M2] Loaded 2025-03-20T18:06:45.713Z,1742494005.713 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-20T18:06:45.751Z,1742494005.751 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-03-20T18:06:45.752Z,1742494005.752 [BackseatComponent] Loaded 2025-03-20T18:06:45.752Z,1742494005.752 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-20T18:06:45.754Z,1742494005.754 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-03-20T18:06:45.754Z,1742494005.754 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1052 2025-03-20T18:06:45.757Z,1742494005.757 [LcmUniversalReporter] Loaded 2025-03-20T18:06:45.757Z,1742494005.757 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-20T18:06:46.591Z,1742494006.591 [BPC1] Loaded 2025-03-20T18:06:46.592Z,1742494006.592 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-20T18:06:46.690Z,1742494006.690 [DAT] Loaded 2025-03-20T18:06:46.690Z,1742494006.690 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-03-20T18:06:46.691Z,1742494006.691 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-03-20T18:06:46.691Z,1742494006.691 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1053 2025-03-20T18:06:46.763Z,1742494006.763 [DataOverHttps] Loaded 2025-03-20T18:06:46.764Z,1742494006.764 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-20T18:06:46.765Z,1742494006.765 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-03-20T18:06:46.765Z,1742494006.765 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1054 2025-03-20T18:06:46.788Z,1742494006.788 [Depth_Keller] Loaded 2025-03-20T18:06:46.788Z,1742494006.788 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-20T18:06:46.793Z,1742494006.793 [DropWeight] Loaded 2025-03-20T18:06:46.793Z,1742494006.793 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-20T18:06:46.857Z,1742494006.857 [NAL9602] Loaded 2025-03-20T18:06:46.857Z,1742494006.857 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-20T18:06:46.887Z,1742494006.887 [Onboard] Loaded 2025-03-20T18:06:46.887Z,1742494006.887 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-20T18:06:46.888Z,1742494006.888 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-03-20T18:06:46.888Z,1742494006.888 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1055 2025-03-20T18:06:46.903Z,1742494006.903 [Power24vConverter] Loaded 2025-03-20T18:06:46.903Z,1742494006.903 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-20T18:06:46.919Z,1742494006.919 [Radio_Surface] Loaded 2025-03-20T18:06:46.919Z,1742494006.919 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-20T18:06:46.920Z,1742494006.920 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-03-20T18:06:46.920Z,1742494006.920 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1056 2025-03-20T18:06:46.936Z,1742494006.936 [Sonardyne_Nano] Loaded 2025-03-20T18:06:46.936Z,1742494006.936 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-03-20T18:06:46.960Z,1742494006.960 [Waterlinked] Loaded 2025-03-20T18:06:46.960Z,1742494006.960 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-20T18:06:46.961Z,1742494006.961 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-20T18:06:46.961Z,1742494006.961 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-20T18:06:46.980Z,1742494006.980 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-20T18:06:46.983Z,1742494006.983 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-20T18:06:47.023Z,1742494007.023 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-20T18:06:47.023Z,1742494007.023 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-20T18:06:47.187Z,1742494007.187 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-20T18:06:47.196Z,1742494007.196 [SBIT] Loaded 2025-03-20T18:06:47.196Z,1742494007.196 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-20T18:06:47.199Z,1742494007.199 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-20T18:06:47.211Z,1742494007.211 [IBIT] Loaded 2025-03-20T18:06:47.212Z,1742494007.212 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-20T18:06:47.217Z,1742494007.217 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-20T18:06:47.295Z,1742494007.295 [CBIT] Loaded 2025-03-20T18:06:47.296Z,1742494007.296 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-20T18:06:47.327Z,1742494007.327 [GFScanner] Loaded 2025-03-20T18:06:47.328Z,1742494007.328 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-20T18:06:47.328Z,1742494007.328 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-20T18:06:47.329Z,1742494007.329 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-20T18:06:47.678Z,1742494007.678 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-20T18:06:47.679Z,1742494007.679 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-20T18:06:47.731Z,1742494007.731 [DepthRateCalculator] Loaded 2025-03-20T18:06:47.731Z,1742494007.731 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-20T18:06:47.736Z,1742494007.736 [PitchRateCalculator] Loaded 2025-03-20T18:06:47.736Z,1742494007.736 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-20T18:06:47.747Z,1742494007.747 [SpeedCalculator] Loaded 2025-03-20T18:06:47.747Z,1742494007.747 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-20T18:06:47.751Z,1742494007.751 [YawRateCalculator] Loaded 2025-03-20T18:06:47.751Z,1742494007.751 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-20T18:06:47.770Z,1742494007.770 [ElevatorOffsetCalculator] Loaded 2025-03-20T18:06:47.770Z,1742494007.770 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-20T18:06:47.771Z,1742494007.771 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-20T18:06:47.771Z,1742494007.771 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-20T18:06:47.951Z,1742494007.951 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-20T18:06:47.952Z,1742494007.952 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-20T18:06:48.065Z,1742494008.065 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-20T18:06:48.066Z,1742494008.066 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-20T18:06:48.079Z,1742494008.079 [NavChart] Loaded 2025-03-20T18:06:48.079Z,1742494008.079 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-20T18:06:48.085Z,1742494008.085 [UniversalFixResidualReporter] Loaded 2025-03-20T18:06:48.085Z,1742494008.085 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-20T18:06:48.086Z,1742494008.086 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-20T18:06:48.086Z,1742494008.086 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-20T18:06:48.224Z,1742494008.224 [BuoyancyServo] Loaded 2025-03-20T18:06:48.225Z,1742494008.225 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-20T18:06:48.248Z,1742494008.248 [ElevatorServo] Loaded 2025-03-20T18:06:48.248Z,1742494008.248 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-20T18:06:48.269Z,1742494008.269 [MassServo] Loaded 2025-03-20T18:06:48.270Z,1742494008.270 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-20T18:06:48.290Z,1742494008.290 [RudderServo] Loaded 2025-03-20T18:06:48.291Z,1742494008.291 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-20T18:06:48.307Z,1742494008.307 [ThrusterHE] Loaded 2025-03-20T18:06:48.308Z,1742494008.308 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-20T18:06:48.308Z,1742494008.308 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-20T18:06:48.309Z,1742494008.309 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-20T18:06:48.477Z,1742494008.477 [CTD_Seabird] Loaded 2025-03-20T18:06:48.478Z,1742494008.478 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-20T18:06:48.479Z,1742494008.479 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2025-03-20T18:06:48.479Z,1742494008.479 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1057 2025-03-20T18:06:48.515Z,1742494008.515 [ESPComponent] Loaded 2025-03-20T18:06:48.516Z,1742494008.516 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-03-20T18:06:48.538Z,1742494008.538 [PAR_Licor] Loaded 2025-03-20T18:06:48.538Z,1742494008.538 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-20T18:06:48.583Z,1742494008.583 [WetLabsBB2FL] Loaded 2025-03-20T18:06:48.584Z,1742494008.584 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-20T18:06:48.585Z,1742494008.585 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-03-20T18:06:48.585Z,1742494008.585 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1058 2025-03-20T18:06:48.586Z,1742494008.586 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-20T18:06:48.592Z,1742494008.592 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-20T18:06:48.595Z,1742494008.595 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-20T18:06:48.606Z,1742494008.606 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-20T18:06:48.607Z,1742494008.607 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-03-20T18:06:48.608Z,1742494008.608 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1059 2025-03-20T18:06:48.612Z,1742494008.612 [Supervisor](INFO): Main Thread ID is 967 2025-03-20T18:06:48.612Z,1742494008.612 [Supervisor](DEBUG): Running supervisor. 2025-03-20T18:06:48.613Z,1742494008.613 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1060 2025-03-20T18:06:48.613Z,1742494008.613 [CommandExec](INFO): Initializing the command executive. 2025-03-20T18:06:48.615Z,1742494008.615 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1061 2025-03-20T18:06:48.617Z,1742494008.617 [controlThread ThreadHandler](INFO): Handler Thread ID is 1062 2025-03-20T18:06:48.618Z,1742494008.618 [controlThread](DEBUG): Initializing ControlThread 2025-03-20T18:06:48.619Z,1742494008.619 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-20T18:06:48.620Z,1742494008.620 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-20T18:06:48.621Z,1742494008.621 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-20T18:06:48.621Z,1742494008.621 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-20T18:06:48.627Z,1742494008.627 [SBIT](INFO): Initialize SBIT Component. 2025-03-20T18:06:48.628Z,1742494008.628 [SBIT](IMPORTANT): git: 2025-03-12_C-7-g9e16b3bff 2025-03-20T18:06:48.628Z,1742494008.628 [SBIT](INFO): git hash: 9e16b3bff22d42af46d832863712edbefb75a998 2025-03-20T18:06:48.628Z,1742494008.628 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-20T18:06:48.629Z,1742494008.629 [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-03-20T18:06:48.630Z,1742494008.630 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2025-03-20T18:06:48.631Z,1742494008.631 [IBIT](INFO): Initialize IBIT Component. 2025-03-20T18:06:48.632Z,1742494008.632 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-20T18:06:48.633Z,1742494008.633 [logger ThreadHandler](INFO): Handler Thread ID is 1063 2025-03-20T18:06:48.645Z,1742494008.645 [CBIT](DEBUG): Initialized mux pins. 2025-03-20T18:06:48.645Z,1742494008.645 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-03-20T18:06:48.645Z,1742494008.645 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-20T18:06:48.653Z,1742494008.653 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1064 2025-03-20T18:06:48.665Z,1742494008.665 [DAT ThreadHandler](INFO): Handler Thread ID is 1065 2025-03-20T18:06:48.666Z,1742494008.666 [DAT](INFO): Powering up 2025-03-20T18:06:48.666Z,1742494008.666 [DAT](DEBUG): Initializing DAT. 2025-03-20T18:06:48.669Z,1742494008.669 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-20T18:06:48.669Z,1742494008.669 [CBIT](DEBUG): Initializing heartbeat. 2025-03-20T18:06:48.671Z,1742494008.671 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1067 2025-03-20T18:06:48.673Z,1742494008.673 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-20T18:06:48.682Z,1742494008.682 [Onboard ThreadHandler](INFO): Handler Thread ID is 1068 2025-03-20T18:06:48.713Z,1742494008.713 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1069 2025-03-20T18:06:48.729Z,1742494008.729 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1070 2025-03-20T18:06:48.730Z,1742494008.730 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-20T18:06:48.738Z,1742494008.738 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1072 2025-03-20T18:06:48.741Z,1742494008.741 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-20T18:06:48.742Z,1742494008.742 [WetLabsBB2FL](INFO): Powering up 2025-03-20T18:06:48.743Z,1742494008.743 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1074 2025-03-20T18:06:48.746Z,1742494008.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-20T18:06:48.746Z,1742494008.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-20T18:06:48.747Z,1742494008.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-20T18:06:48.747Z,1742494008.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-20T18:06:48.747Z,1742494008.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-20T18:06:48.747Z,1742494008.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-20T18:06:48.747Z,1742494008.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-20T18:06:48.747Z,1742494008.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-20T18:06:48.781Z,1742494008.781 [CBIT](DEBUG): Backplane powered. 2025-03-20T18:06:48.781Z,1742494008.781 [GFScanner](DEBUG): Initializing GFScanner 2025-03-20T18:06:48.782Z,1742494008.782 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-20T18:06:48.782Z,1742494008.782 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-20T18:06:48.782Z,1742494008.782 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-20T18:06:48.783Z,1742494008.783 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-20T18:06:48.783Z,1742494008.783 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-20T18:06:48.784Z,1742494008.784 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-20T18:06:48.785Z,1742494008.785 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-20T18:06:48.790Z,1742494008.790 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-20T18:06:48.790Z,1742494008.790 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-20T18:06:48.791Z,1742494008.791 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-20T18:06:48.792Z,1742494008.792 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-20T18:06:48.862Z,1742494008.862 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-20T18:06:48.890Z,1742494008.890 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-20T18:06:48.935Z,1742494008.935 [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-03-20T18:06:48.935Z,1742494008.935 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-20T18:06:48.936Z,1742494008.936 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-20T18:06:48.937Z,1742494008.937 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-20T18:06:49.117Z,1742494009.117 [Radio_Surface](INFO): Powering up 2025-03-20T18:06:49.228Z,1742494009.228 [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-03-20T18:06:49.258Z,1742494009.258 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-20T18:06:49.259Z,1742494009.259 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-20T18:06:49.261Z,1742494009.261 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-20T18:06:49.298Z,1742494009.298 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-20T18:06:49.300Z,1742494009.300 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-20T18:06:49.319Z,1742494009.319 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-20T18:06:49.322Z,1742494009.322 [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-03-20T18:06:49.342Z,1742494009.342 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,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-03-20T18:06:49.365Z,1742494009.365 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-20T18:06:49.435Z,1742494009.435 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2025-03-20T18:06:49.436Z,1742494009.436 [Power24vConverter](INFO): Powering up. 2025-03-20T18:06:49.436Z,1742494009.436 [Sonardyne_Nano](INFO): Initializing. 2025-03-20T18:06:49.467Z,1742494009.467 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-20T18:06:49.503Z,1742494009.503 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:06:49.509Z,1742494009.509 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-20T18:06:49.510Z,1742494009.510 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:06:49.517Z,1742494009.517 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-20T18:06:49.518Z,1742494009.518 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:06:49.525Z,1742494009.525 [MassServo](DEBUG): Initializing MassServo. 2025-03-20T18:06:49.526Z,1742494009.526 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:06:49.533Z,1742494009.533 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-20T18:06:49.534Z,1742494009.534 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-20T18:06:49.542Z,1742494009.542 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-20T18:06:49.545Z,1742494009.545 [CBIT](FAULT): Main Battery Failure 2025-03-20T18:06:49.787Z,1742494009.787 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-20T18:06:49.787Z,1742494009.787 [DropWeight] Hardware Fault, FailCount= 1 2025-03-20T18:06:49.787Z,1742494009.787 [DropWeight](ERROR): Hardware Fault 2025-03-20T18:06:49.800Z,1742494009.800 [CommandExec](FAULT): Scheduling is paused 2025-03-20T18:06:49.800Z,1742494009.800 [CBIT](INFO): Critical error at 20250320T180649 2025-03-20T18:06:49.800Z,1742494009.800 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-20T18:06:49.807Z,1742494009.807 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-20T18:06:49.807Z,1742494009.807 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-20T18:06:50.179Z,1742494010.179 [CBIT](INFO): Critical error at 20250320T180649 2025-03-20T18:06:50.877Z,1742494010.877 [WetLabsBB2FL](INFO): Powering down 2025-03-20T18:06:51.372Z,1742494011.372 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-03-20T18:06:52.665Z,1742494012.665 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-03-20T18:06:55.298Z,1742494015.298 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-03-20T18:07:17.719Z,1742494037.719 [NAL9602](INFO): Powering up NAL9602 2025-03-20T18:07:26.405Z,1742494046.405 [Waterlinked](INFO): Powering down 2025-03-20T18:07:29.247Z,1742494049.247 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA1 2025-03-20T18:07:29.471Z,1742494049.471 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA2 2025-03-20T18:07:29.735Z,1742494049.735 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA3 2025-03-20T18:07:30.051Z,1742494050.051 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4 2025-03-20T18:07:30.287Z,1742494050.287 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA5 2025-03-20T18:07:30.497Z,1742494050.497 [MassServo](ERROR): getPosition uart error serial timeout 2025-03-20T18:07:30.498Z,1742494050.498 [MassServo](FAULT): Unknown EZ Servo Error: 14 2025-03-20T18:07:30.498Z,1742494050.498 [MassServo] Hardware Fault, FailCount= 1 2025-03-20T18:07:30.498Z,1742494050.498 [MassServo](ERROR): Hardware Fault 2025-03-20T18:07:30.503Z,1742494050.503 [CBIT](ERROR): Hardware Fault in component: MassServo 2025-03-20T18:07:30.599Z,1742494050.599 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA6 2025-03-20T18:07:30.757Z,1742494050.757 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-20T18:07:30.758Z,1742494050.758 [MassServo](INFO): Powering down 2025-03-20T18:07:30.847Z,1742494050.847 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA7 2025-03-20T18:07:31.051Z,1742494051.051 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA1 2025-03-20T18:07:31.307Z,1742494051.307 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA2 2025-03-20T18:07:31.555Z,1742494051.555 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:07:31.615Z,1742494051.615 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA3 2025-03-20T18:07:31.674Z,1742494051.674 [MassServo](DEBUG): Initializing MassServo. 2025-03-20T18:07:31.680Z,1742494051.680 [CBIT](INFO): Clearing failed state for component MassServo 2025-03-20T18:07:31.680Z,1742494051.680 [MassServo] No Fault, FailCount= 1 2025-03-20T18:07:31.859Z,1742494051.859 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4 2025-03-20T18:07:32.123Z,1742494052.123 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA5 2025-03-20T18:07:32.330Z,1742494052.330 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA6 2025-03-20T18:07:32.570Z,1742494052.570 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA7 2025-03-20T18:07:32.879Z,1742494052.879 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA1 2025-03-20T18:07:33.099Z,1742494053.099 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA2 2025-03-20T18:07:33.382Z,1742494053.382 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA3 2025-03-20T18:07:33.667Z,1742494053.667 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4 2025-03-20T18:07:33.891Z,1742494053.891 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA5 2025-03-20T18:07:34.139Z,1742494054.139 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA6 2025-03-20T18:07:34.297Z,1742494054.297 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2025-03-20T18:07:34.297Z,1742494054.297 [MassServo] Communications Fault, FailCount= 2 2025-03-20T18:07:34.297Z,1742494054.297 [MassServo](ERROR): Communications Fault 2025-03-20T18:07:34.303Z,1742494054.303 [CBIT](ERROR): Communications Fault in component: MassServo 2025-03-20T18:07:34.399Z,1742494054.399 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-20T18:07:34.400Z,1742494054.400 [MassServo](INFO): Powering down 2025-03-20T18:07:35.219Z,1742494055.219 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:07:35.339Z,1742494055.339 [MassServo](DEBUG): Initializing MassServo. 2025-03-20T18:07:35.344Z,1742494055.344 [CBIT](INFO): Clearing failed state for component MassServo 2025-03-20T18:07:35.344Z,1742494055.344 [MassServo] No Fault, FailCount= 2 2025-03-20T18:07:38.370Z,1742494058.370 [NAL9602](ERROR): NAL9602 initialization error. 2025-03-20T18:07:38.370Z,1742494058.370 [NAL9602] Communications Fault, FailCount= 1 2025-03-20T18:07:38.370Z,1742494058.370 [NAL9602](ERROR): Communications Fault 2025-03-20T18:07:38.388Z,1742494058.388 [CBIT](ERROR): Communications Fault in component: NAL9602 2025-03-20T18:07:38.778Z,1742494058.778 [NAL9602](INFO): Powering down 2025-03-20T18:07:39.645Z,1742494059.645 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-20T18:07:39.645Z,1742494059.645 [NAL9602] No Fault, FailCount= 1 2025-03-20T18:07:48.845Z,1742494068.845 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-03-20T18:07:48.846Z,1742494068.846 [DAT] Communications Fault, FailCount= 1 2025-03-20T18:07:48.846Z,1742494068.846 [DAT](ERROR): Communications Fault 2025-03-20T18:07:48.965Z,1742494068.965 [CBIT](ERROR): Communications Fault in component: DAT 2025-03-20T18:07:49.249Z,1742494069.249 [DAT](INFO): Powering down 2025-03-20T18:07:50.182Z,1742494070.182 [CBIT](INFO): Clearing failed state for component DAT 2025-03-20T18:07:50.182Z,1742494070.182 [DAT] No Fault, FailCount= 1 2025-03-20T18:07:52.301Z,1742494072.301 [DAT](INFO): Powering up 2025-03-20T18:07:52.302Z,1742494072.302 [DAT](DEBUG): Initializing DAT. 2025-03-20T18:07:58.907Z,1742494078.907 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004607 2025-03-20T18:08:00.305Z,1742494080.305 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-20T18:08:00.941Z,1742494080.941 [ElevatorServo](ERROR): getPosition uart error serial timeout 2025-03-20T18:08:00.941Z,1742494080.941 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2025-03-20T18:08:00.941Z,1742494080.941 [ElevatorServo] Communications Fault, FailCount= 1 2025-03-20T18:08:00.941Z,1742494080.941 [ElevatorServo](ERROR): Communications Fault 2025-03-20T18:08:01.082Z,1742494081.082 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2025-03-20T18:08:01.120Z,1742494081.120 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-20T18:08:01.120Z,1742494081.120 [ElevatorServo](INFO): Powering down 2025-03-20T18:08:01.955Z,1742494081.955 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-20T18:08:02.074Z,1742494082.074 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-20T18:08:02.242Z,1742494082.242 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-03-20T18:08:02.242Z,1742494082.242 [ElevatorServo] No Fault, FailCount= 1 2025-03-20T18:08:04.404Z,1742494084.404 [DAT](INFO): DAT read: 2025-03-20T18:08:04.405Z,1742494084.405 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-03-20T18:08:06.170Z,1742494086.170 [DAT](INFO): DAT read: MF Frequency Band 2025-03-20T18:08:06.171Z,1742494086.171 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-03-20T18:08:06.172Z,1742494086.172 [DAT](INFO): DAT read: Mar 20 2025 18:08:00 2025-03-20T18:08:07.178Z,1742494087.178 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-03-20T18:08:07.179Z,1742494087.179 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-03-20T18:08:07.180Z,1742494087.180 [DAT](INFO): commRate: 800 2025-03-20T18:08:09.174Z,1742494089.174 [NAL9602](INFO): Powering up NAL9602 2025-03-20T18:08:09.245Z,1742494089.245 [DAT](INFO): entering command mode 2025-03-20T18:08:09.446Z,1742494089.446 [DAT](INFO): DAT read: 2025-03-20T18:08:09.446Z,1742494089.446 [DAT](INFO): DAT read: user:1> 2025-03-20T18:08:09.447Z,1742494089.447 [DAT](INFO): setting verbose to 3 2025-03-20T18:08:09.699Z,1742494089.699 [DAT](INFO): DAT read: user:1> 2025-03-20T18:08:09.700Z,1742494089.700 [DAT](INFO): DAT read: Verbose | 3 2025-03-20T18:08:09.700Z,1742494089.700 [DAT](INFO): set verbose to 3 2025-03-20T18:08:09.700Z,1742494089.700 [DAT](INFO): setting DatVerbose to 27440 2025-03-20T18:08:09.950Z,1742494089.950 [DAT](INFO): DAT read: user:2> 2025-03-20T18:08:09.951Z,1742494089.951 [DAT](INFO): DAT read: DatVerbose | 27440 2025-03-20T18:08:09.951Z,1742494089.951 [DAT](INFO): set DatVerbose to 27440 2025-03-20T18:08:09.951Z,1742494089.951 [DAT](INFO): setting transmit power to 8 2025-03-20T18:08:10.202Z,1742494090.202 [DAT](INFO): DAT read: user:3> 2025-03-20T18:08:10.203Z,1742494090.203 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-03-20T18:08:10.203Z,1742494090.203 [DAT](INFO): set transmit power to 8 2025-03-20T18:08:10.203Z,1742494090.203 [DAT](INFO): setting local address to 1 2025-03-20T18:08:10.456Z,1742494090.456 [DAT](INFO): DAT read: user:4> 2025-03-20T18:08:10.457Z,1742494090.457 [DAT](INFO): DAT read: LocalAddr | 1 2025-03-20T18:08:10.458Z,1742494090.458 [DAT](INFO): set local address to 1 2025-03-20T18:08:10.459Z,1742494090.459 [DAT](INFO): Setting time to: 18:8:10 And date to:3/20/2025 2025-03-20T18:08:10.710Z,1742494090.710 [DAT](INFO): DAT read: user:5> 2025-03-20T18:08:10.711Z,1742494090.711 [DAT](INFO): DAT read: Thu Mar 20, 2025 18:08:10 2025-03-20T18:08:10.711Z,1742494090.711 [DAT](INFO): Local DAT time set to Thu Mar 20, 2025 18:08:10 2025-03-20T18:08:20.082Z,1742494100.082 [NAL9602](INFO): NAL9602 initialized 2025-03-20T18:08:54.107Z,1742494134.107 [SBIT](IMPORTANT): SBIT PASSED 2025-03-20T18:08:54.108Z,1742494134.108 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-20T18:08:54.474Z,1742494134.474 [MissionManager](IMPORTANT): Started mission Startup 2025-03-20T18:08:54.474Z,1742494134.474 [Startup] Running Loop=1 2025-03-20T18:08:54.474Z,1742494134.474 [Startup](DEBUG): Aggregate::initialize Startup 2025-03-20T18:08:54.475Z,1742494134.475 [Startup:A.GoToSurface] Running Loop=1 2025-03-20T18:08:54.475Z,1742494134.475 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-20T18:08:54.475Z,1742494134.475 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-20T18:08:54.476Z,1742494134.476 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-20T18:08:54.476Z,1742494134.476 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-20T18:08:54.476Z,1742494134.476 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-20T18:08:54.485Z,1742494134.485 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-20T18:08:54.485Z,1742494134.485 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-20T18:08:54.487Z,1742494134.487 [Startup:StartupSatComms] Running Loop=1 2025-03-20T18:08:54.487Z,1742494134.487 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-03-20T18:08:54.487Z,1742494134.487 [Startup:StartupSatComms:A] Running Loop=1 2025-03-20T18:08:54.887Z,1742494134.887 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-03-20T18:09:48.977Z,1742494188.977 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-03-20T18:09:48.977Z,1742494188.977 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-20T18:09:49.027Z,1742494189.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-20T18:09:49.453Z,1742494189.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-20T18:09:49.453Z,1742494189.453 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-03-20T18:09:54.639Z,1742494194.639 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-20T18:08:54.5Z 2025-03-20T18:09:54.639Z,1742494194.639 [Startup:StartupSatComms:A] Stopped 2025-03-20T18:09:54.639Z,1742494194.639 [Startup:StartupSatComms:B] Running Loop=1 2025-03-20T18:09:55.047Z,1742494195.047 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-20T18:10:00.832Z,1742494200.832 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250320T175203/Courier0007.lzma 2025-03-20T18:10:01.843Z,1742494201.843 [DataOverHttps](INFO): Moved sent file to Logs/20250320T175203/Courier0007.lzma.bak 2025-03-20T18:10:01.843Z,1742494201.843 [DataOverHttps](INFO): SBD MOMSN=24428718 2025-03-20T18:10:27.833Z,1742494227.833 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250320T180437/Courier0000.lzma 2025-03-20T18:10:28.835Z,1742494228.835 [DataOverHttps](INFO): Moved sent file to Logs/20250320T180437/Courier0000.lzma.bak 2025-03-20T18:10:28.835Z,1742494228.835 [DataOverHttps](INFO): SBD MOMSN=24428720 2025-03-20T18:10:54.409Z,1742494254.409 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250320T180638/Courier0000.lzma 2025-03-20T18:10:54.823Z,1742494254.823 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-20T18:09:54.6Z 2025-03-20T18:10:54.823Z,1742494254.823 [Startup:StartupSatComms:B] Stopped 2025-03-20T18:10:54.823Z,1742494254.823 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-03-20T18: