2024-03-01T23:26:22.535Z,1709335582.535 [Supervisor](DEBUG): Initializing supervisor. 2024-03-01T23:26:22.539Z,1709335582.539 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-03-01T23:26:22.540Z,1709335582.540 [SyncHandler](INFO): Protected caller Thread ID is 1155 2024-03-01T23:26:22.540Z,1709335582.540 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-03-01T23:26:22.541Z,1709335582.541 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-03-01T23:26:22.541Z,1709335582.541 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1156 2024-03-01T23:26:22.545Z,1709335582.545 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-03-01T23:26:22.564Z,1709335582.564 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-03-01T23:26:22.565Z,1709335582.565 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-03-01T23:26:22.565Z,1709335582.565 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1157 2024-03-01T23:26:22.571Z,1709335582.571 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-03-01T23:26:22.571Z,1709335582.571 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-03-01T23:26:22.572Z,1709335582.572 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1158 2024-03-01T23:26:22.574Z,1709335582.574 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-03-01T23:26:22.576Z,1709335582.576 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-03-01T23:26:22.576Z,1709335582.576 [logger ThreadHandler](INFO): Protected caller Thread ID is 1159 2024-03-01T23:26:22.580Z,1709335582.580 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-03-01T23:26:22.580Z,1709335582.580 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-03-01T23:26:22.582Z,1709335582.582 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-03-01T23:26:22.908Z,1709335582.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-03-01T23:26:22.909Z,1709335582.909 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-03-01T23:26:23.231Z,1709335583.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-03-01T23:26:23.232Z,1709335583.232 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-03-01T23:26:23.341Z,1709335583.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-03-01T23:26:23.342Z,1709335583.342 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-03-01T23:26:23.681Z,1709335583.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-03-01T23:26:23.681Z,1709335583.681 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-03-01T23:26:24.411Z,1709335584.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-03-01T23:26:24.412Z,1709335584.412 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-03-01T23:26:24.548Z,1709335584.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-03-01T23:26:24.548Z,1709335584.548 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-03-01T23:26:24.927Z,1709335584.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-03-01T23:26:24.927Z,1709335584.927 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-03-01T23:26:25.205Z,1709335585.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-03-01T23:26:25.205Z,1709335585.205 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-03-01T23:26:25.874Z,1709335585.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-03-01T23:26:25.874Z,1709335585.874 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-03-01T23:26:26.478Z,1709335586.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-03-01T23:26:26.478Z,1709335586.478 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-03-01T23:26:26.594Z,1709335586.594 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-03-01T23:26:27.356Z,1709335587.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-03-01T23:26:27.357Z,1709335587.357 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-03-01T23:26:27.712Z,1709335587.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-03-01T23:26:27.713Z,1709335587.713 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-03-01T23:26:30.104Z,1709335590.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-03-01T23:26:30.104Z,1709335590.104 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-03-01T23:26:30.967Z,1709335590.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-03-01T23:26:30.967Z,1709335590.967 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-03-01T23:26:31.201Z,1709335591.201 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2024-03-01T23:26:31.202Z,1709335591.202 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2024-03-01T23:26:31.360Z,1709335591.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2024-03-01T23:26:31.624Z,1709335591.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2024-03-01T23:26:31.829Z,1709335591.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg 2024-03-01T23:26:32.178Z,1709335592.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2024-03-01T23:26:32.389Z,1709335592.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2024-03-01T23:26:32.553Z,1709335592.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2024-03-01T23:26:32.860Z,1709335592.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2024-03-01T23:26:33.281Z,1709335593.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2024-03-01T23:26:33.732Z,1709335593.732 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2024-03-01T23:26:33.906Z,1709335593.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2024-03-01T23:26:34.652Z,1709335594.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-03-01T23:26:34.652Z,1709335594.652 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2024-03-01T23:26:34.654Z,1709335594.654 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-03-01T23:26:35.478Z,1709335595.478 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-03-01T23:26:35.479Z,1709335595.479 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-03-01T23:26:36.017Z,1709335596.017 [AHRS_M2] Loaded 2024-03-01T23:26:36.017Z,1709335596.017 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-03-01T23:26:37.379Z,1709335597.379 [BPC1] Loaded 2024-03-01T23:26:37.380Z,1709335597.380 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-03-01T23:26:37.504Z,1709335597.504 [DataOverHttps] Loaded 2024-03-01T23:26:37.504Z,1709335597.504 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-03-01T23:26:37.523Z,1709335597.523 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408854E0 2024-03-01T23:26:37.523Z,1709335597.523 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1247 2024-03-01T23:26:37.560Z,1709335597.560 [Depth_Keller] Loaded 2024-03-01T23:26:37.560Z,1709335597.560 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-03-01T23:26:37.567Z,1709335597.567 [DropWeight] Loaded 2024-03-01T23:26:37.567Z,1709335597.567 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-03-01T23:26:37.639Z,1709335597.639 [NAL9602] Loaded 2024-03-01T23:26:37.639Z,1709335597.639 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-03-01T23:26:37.672Z,1709335597.672 [Onboard] Loaded 2024-03-01T23:26:37.672Z,1709335597.672 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-03-01T23:26:37.673Z,1709335597.673 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0 2024-03-01T23:26:37.674Z,1709335597.674 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1248 2024-03-01T23:26:37.688Z,1709335597.688 [Power24vConverter] Loaded 2024-03-01T23:26:37.689Z,1709335597.689 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-03-01T23:26:37.704Z,1709335597.704 [Radio_Surface] Loaded 2024-03-01T23:26:37.704Z,1709335597.704 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-03-01T23:26:37.705Z,1709335597.705 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0 2024-03-01T23:26:37.706Z,1709335597.706 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1249 2024-03-01T23:26:37.722Z,1709335597.722 [Sonardyne_Nano] Loaded 2024-03-01T23:26:37.723Z,1709335597.723 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-03-01T23:26:37.825Z,1709335597.825 [DAT] Loaded 2024-03-01T23:26:37.826Z,1709335597.826 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-03-01T23:26:37.827Z,1709335597.827 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409154E0 2024-03-01T23:26:37.827Z,1709335597.827 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1250 2024-03-01T23:26:37.828Z,1709335597.828 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-03-01T23:26:37.828Z,1709335597.828 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-03-01T23:26:38.030Z,1709335598.030 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-03-01T23:26:38.031Z,1709335598.031 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-03-01T23:26:38.086Z,1709335598.086 [VerticalControl](DEBUG): Construct VerticalControl. 2024-03-01T23:26:38.158Z,1709335598.158 [VerticalControl] Loaded 2024-03-01T23:26:38.158Z,1709335598.158 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-03-01T23:26:38.161Z,1709335598.161 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-03-01T23:26:38.223Z,1709335598.223 [HorizontalControl] Loaded 2024-03-01T23:26:38.224Z,1709335598.224 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-03-01T23:26:38.227Z,1709335598.227 [SpeedControl](DEBUG): Construct SpeedControl. 2024-03-01T23:26:38.230Z,1709335598.230 [SpeedControl] Loaded 2024-03-01T23:26:38.230Z,1709335598.230 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-03-01T23:26:38.233Z,1709335598.233 [LoopControl](DEBUG): Construct LoopControl. 2024-03-01T23:26:38.233Z,1709335598.233 [LoopControl] Loaded 2024-03-01T23:26:38.234Z,1709335598.234 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-03-01T23:26:38.235Z,1709335598.235 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-03-01T23:26:38.235Z,1709335598.235 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-03-01T23:26:38.247Z,1709335598.247 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-03-01T23:26:38.248Z,1709335598.248 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-03-01T23:26:38.308Z,1709335598.308 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-03-01T23:26:38.308Z,1709335598.308 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-03-01T23:26:38.479Z,1709335598.479 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-03-01T23:26:38.479Z,1709335598.479 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-03-01T23:26:38.526Z,1709335598.526 [DeadReckonUsingSpeedCalculator] Loaded 2024-03-01T23:26:38.526Z,1709335598.526 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-03-01T23:26:38.573Z,1709335598.573 [DeadReckonWithRespectToSeafloor] Loaded 2024-03-01T23:26:38.574Z,1709335598.574 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-03-01T23:26:38.590Z,1709335598.590 [NavChart] Loaded 2024-03-01T23:26:38.590Z,1709335598.590 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-03-01T23:26:38.597Z,1709335598.597 [UniversalFixResidualReporter] Loaded 2024-03-01T23:26:38.597Z,1709335598.597 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-03-01T23:26:38.597Z,1709335598.597 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-03-01T23:26:38.598Z,1709335598.598 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-03-01T23:26:38.875Z,1709335598.875 [SBIT](DEBUG): Construct Startup Built In Test. 2024-03-01T23:26:38.912Z,1709335598.912 [SBIT] Loaded 2024-03-01T23:26:38.912Z,1709335598.912 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-03-01T23:26:38.936Z,1709335598.936 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-03-01T23:26:38.955Z,1709335598.955 [IBIT] Loaded 2024-03-01T23:26:38.955Z,1709335598.955 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-03-01T23:26:38.961Z,1709335598.961 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-03-01T23:26:39.080Z,1709335599.080 [CBIT] Loaded 2024-03-01T23:26:39.081Z,1709335599.081 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-03-01T23:26:39.081Z,1709335599.081 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-03-01T23:26:39.081Z,1709335599.081 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-03-01T23:26:39.441Z,1709335599.441 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-03-01T23:26:39.441Z,1709335599.441 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-03-01T23:26:39.658Z,1709335599.658 [BuoyancyServo] Loaded 2024-03-01T23:26:39.658Z,1709335599.658 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-03-01T23:26:39.681Z,1709335599.681 [ElevatorServo] Loaded 2024-03-01T23:26:39.681Z,1709335599.681 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-03-01T23:26:39.704Z,1709335599.704 [MassServo] Loaded 2024-03-01T23:26:39.704Z,1709335599.704 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-03-01T23:26:39.725Z,1709335599.725 [RudderServo] Loaded 2024-03-01T23:26:39.725Z,1709335599.725 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-03-01T23:26:39.741Z,1709335599.741 [ThrusterHE] Loaded 2024-03-01T23:26:39.742Z,1709335599.742 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-03-01T23:26:39.742Z,1709335599.742 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-03-01T23:26:39.743Z,1709335599.743 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-03-01T23:26:39.995Z,1709335599.995 [CTD_Seabird] Loaded 2024-03-01T23:26:39.995Z,1709335599.995 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-03-01T23:26:39.996Z,1709335599.996 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B254E0 2024-03-01T23:26:39.997Z,1709335599.997 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1251 2024-03-01T23:26:40.035Z,1709335600.035 [PAR_Licor] Loaded 2024-03-01T23:26:40.035Z,1709335600.035 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-03-01T23:26:40.080Z,1709335600.080 [WetLabsBB2FL] Loaded 2024-03-01T23:26:40.080Z,1709335600.080 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-03-01T23:26:40.081Z,1709335600.081 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B554E0 2024-03-01T23:26:40.081Z,1709335600.081 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1252 2024-03-01T23:26:40.082Z,1709335600.082 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-03-01T23:26:40.083Z,1709335600.083 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-03-01T23:26:40.140Z,1709335600.140 [DepthRateCalculator] Loaded 2024-03-01T23:26:40.140Z,1709335600.140 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-03-01T23:26:40.146Z,1709335600.146 [PitchRateCalculator] Loaded 2024-03-01T23:26:40.146Z,1709335600.146 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-03-01T23:26:40.156Z,1709335600.156 [SpeedCalculator] Loaded 2024-03-01T23:26:40.156Z,1709335600.156 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-03-01T23:26:40.160Z,1709335600.160 [YawRateCalculator] Loaded 2024-03-01T23:26:40.161Z,1709335600.161 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-03-01T23:26:40.183Z,1709335600.183 [ElevatorOffsetCalculator] Loaded 2024-03-01T23:26:40.183Z,1709335600.183 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-03-01T23:26:40.184Z,1709335600.184 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-03-01T23:26:40.184Z,1709335600.184 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-03-01T23:26:40.247Z,1709335600.247 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-03-01T23:26:40.254Z,1709335600.254 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-03-01T23:26:40.257Z,1709335600.257 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-03-01T23:26:40.270Z,1709335600.270 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-03-01T23:26:40.271Z,1709335600.271 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BE14E0 2024-03-01T23:26:40.272Z,1709335600.272 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1253 2024-03-01T23:26:40.277Z,1709335600.277 [Supervisor](INFO): Main Thread ID is 1154 2024-03-01T23:26:40.277Z,1709335600.277 [Supervisor](DEBUG): Running supervisor. 2024-03-01T23:26:40.278Z,1709335600.278 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1254 2024-03-01T23:26:40.278Z,1709335600.278 [CommandExec](INFO): Initializing the command executive. 2024-03-01T23:26:40.280Z,1709335600.280 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1255 2024-03-01T23:26:40.282Z,1709335600.282 [controlThread ThreadHandler](INFO): Handler Thread ID is 1256 2024-03-01T23:26:40.283Z,1709335600.283 [controlThread](DEBUG): Initializing ControlThread 2024-03-01T23:26:40.288Z,1709335600.288 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-03-01T23:26:40.290Z,1709335600.290 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-03-01T23:26:40.291Z,1709335600.291 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-03-01T23:26:40.291Z,1709335600.291 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-03-01T23:26:40.292Z,1709335600.292 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-03-01T23:26:40.293Z,1709335600.293 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-03-01T23:26:40.293Z,1709335600.293 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-03-01T23:26:40.293Z,1709335600.293 [SBIT](INFO): Initialize SBIT Component. 2024-03-01T23:26:40.294Z,1709335600.294 [SBIT](IMPORTANT): git: 2024-02-15-11-g938a8443c 2024-03-01T23:26:40.294Z,1709335600.294 [SBIT](INFO): git hash: 938a8443c99493f6074473dd0552d31c6d48c406 2024-03-01T23:26:40.295Z,1709335600.295 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-03-01T23:26:40.296Z,1709335600.296 [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 Mar 17 08:23:48 PDT 2021 2024-03-01T23:26:40.297Z,1709335600.297 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2024-03-01T23:26:40.298Z,1709335600.298 [IBIT](INFO): Initialize IBIT Component. 2024-03-01T23:26:40.299Z,1709335600.299 [CBIT](DEBUG): Initialize CBIT Component. 2024-03-01T23:26:40.300Z,1709335600.300 [logger ThreadHandler](INFO): Handler Thread ID is 1257 2024-03-01T23:26:40.315Z,1709335600.315 [CBIT](DEBUG): Initialized mux pins. 2024-03-01T23:26:40.315Z,1709335600.315 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2024-03-01T23:26:40.315Z,1709335600.315 [CBIT](DEBUG): Initializing the watchdog timer. 2024-03-01T23:26:40.339Z,1709335600.339 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1258 2024-03-01T23:26:40.341Z,1709335600.341 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-03-01T23:26:40.344Z,1709335600.344 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2024-03-01T23:26:40.344Z,1709335600.344 [CBIT] Hardware Fault, FailCount= 1 2024-03-01T23:26:40.344Z,1709335600.344 [CBIT](ERROR): Hardware Fault 2024-03-01T23:26:40.345Z,1709335600.345 [CBIT](DEBUG): Initializing heartbeat. 2024-03-01T23:26:40.359Z,1709335600.359 [Onboard ThreadHandler](INFO): Handler Thread ID is 1259 2024-03-01T23:26:40.394Z,1709335600.394 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1260 2024-03-01T23:26:40.403Z,1709335600.403 [DAT ThreadHandler](INFO): Handler Thread ID is 1261 2024-03-01T23:26:40.404Z,1709335600.404 [DAT](INFO): Powering up 2024-03-01T23:26:40.404Z,1709335600.404 [DAT](DEBUG): Initializing DAT. 2024-03-01T23:26:40.414Z,1709335600.414 [CBIT](DEBUG): Deactivating GF circuits. 2024-03-01T23:26:40.415Z,1709335600.415 [CBIT](DEBUG): Deactivating emergency mode. 2024-03-01T23:26:40.420Z,1709335600.420 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1262 2024-03-01T23:26:40.420Z,1709335600.420 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-03-01T23:26:40.435Z,1709335600.435 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1263 2024-03-01T23:26:40.443Z,1709335600.443 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1265 2024-03-01T23:26:40.445Z,1709335600.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-03-01T23:26:40.446Z,1709335600.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-03-01T23:26:40.446Z,1709335600.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-03-01T23:26:40.447Z,1709335600.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-03-01T23:26:40.447Z,1709335600.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-03-01T23:26:40.447Z,1709335600.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-03-01T23:26:40.447Z,1709335600.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-03-01T23:26:40.447Z,1709335600.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-03-01T23:26:40.448Z,1709335600.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-03-01T23:26:40.448Z,1709335600.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-03-01T23:26:40.448Z,1709335600.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-03-01T23:26:40.448Z,1709335600.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-03-01T23:26:40.452Z,1709335600.452 [WetLabsBB2FL](INFO): Powering up 2024-03-01T23:26:40.454Z,1709335600.454 [CBIT](DEBUG): Backplane powered. 2024-03-01T23:26:40.459Z,1709335600.459 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-03-01T23:26:40.459Z,1709335600.459 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-03-01T23:26:40.459Z,1709335600.459 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-03-01T23:26:40.460Z,1709335600.460 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-03-01T23:26:40.460Z,1709335600.460 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-03-01T23:26:40.462Z,1709335600.462 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-03-01T23:26:40.462Z,1709335600.462 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-03-01T23:26:40.488Z,1709335600.488 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-01T23:26:40.548Z,1709335600.548 [MissionManager](DEBUG): 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 } } } 2024-03-01T23:26:40.548Z,1709335600.548 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-03-01T23:26:40.549Z,1709335600.549 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-03-01T23:26:40.667Z,1709335600.667 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-03-01T23:26:40.668Z,1709335600.668 [Default:A.Wait](DEBUG): Construct Wait. 2024-03-01T23:26:40.678Z,1709335600.678 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-01T23:26:40.707Z,1709335600.707 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-03-01T23:26:40.710Z,1709335600.710 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-03-01T23:26:40.725Z,1709335600.725 [Default:E.Execute](DEBUG): Construct Execute. 2024-03-01T23:26:40.728Z,1709335600.728 [MissionManager](DEBUG): 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" } } 2024-03-01T23:26:40.735Z,1709335600.735 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-03-01T23:26:40.743Z,1709335600.743 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-01T23:26:40.798Z,1709335600.798 [Radio_Surface](INFO): Powering up 2024-03-01T23:26:40.801Z,1709335600.801 [Power24vConverter](INFO): Powering up. 2024-03-01T23:26:40.804Z,1709335600.804 [Sonardyne_Nano](INFO): Initializing. 2024-03-01T23:26:40.849Z,1709335600.849 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:40.855Z,1709335600.855 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-03-01T23:26:40.856Z,1709335600.856 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:40.867Z,1709335600.867 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-01T23:26:40.868Z,1709335600.868 [MassServo](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:40.875Z,1709335600.875 [MassServo](DEBUG): Initializing MassServo. 2024-03-01T23:26:40.876Z,1709335600.876 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:40.883Z,1709335600.883 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-01T23:26:40.883Z,1709335600.883 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:40.891Z,1709335600.891 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-03-01T23:26:40.892Z,1709335600.892 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-03-01T23:26:40.892Z,1709335600.892 [CBIT](DEBUG): Powering off loads. 2024-03-01T23:26:40.908Z,1709335600.908 [CBIT](DEBUG): Disabling WDT. 2024-03-01T23:26:40.923Z,1709335600.923 [CBIT](DEBUG): Opening all GF detection circuits. 2024-03-01T23:26:41.271Z,1709335601.271 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-03-01T23:26:41.271Z,1709335601.271 [DropWeight] Hardware Fault, FailCount= 1 2024-03-01T23:26:41.271Z,1709335601.271 [DropWeight](ERROR): Hardware Fault 2024-03-01T23:26:42.306Z,1709335602.306 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2024-03-01T23:26:42.307Z,1709335602.307 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2024-03-01T23:26:42.307Z,1709335602.307 [BuoyancyServo] Communications Fault, FailCount= 1 2024-03-01T23:26:42.307Z,1709335602.307 [BuoyancyServo](ERROR): Communications Fault 2024-03-01T23:26:42.611Z,1709335602.611 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2024-03-01T23:26:42.611Z,1709335602.611 [ElevatorServo](FAULT): Elevator failed to initialize 2024-03-01T23:26:42.612Z,1709335602.612 [ElevatorServo] Communications Fault, FailCount= 1 2024-03-01T23:26:42.612Z,1709335602.612 [ElevatorServo](ERROR): Communications Fault 2024-03-01T23:26:42.915Z,1709335602.915 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2024-03-01T23:26:42.915Z,1709335602.915 [RudderServo](FAULT): Rudder failed to initialize 2024-03-01T23:26:42.915Z,1709335602.915 [RudderServo] Communications Fault, FailCount= 1 2024-03-01T23:26:42.915Z,1709335602.915 [RudderServo](ERROR): Communications Fault 2024-03-01T23:26:42.916Z,1709335602.916 [ThrusterHE](FAULT): Thruster failed to initialize 2024-03-01T23:26:42.916Z,1709335602.916 [ThrusterHE] Communications Fault, FailCount= 1 2024-03-01T23:26:42.916Z,1709335602.916 [ThrusterHE](ERROR): Communications Fault 2024-03-01T23:26:43.030Z,1709335603.030 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-01T23:26:43.038Z,1709335603.038 [ElevatorServo](INFO): Powering down 2024-03-01T23:26:43.040Z,1709335603.040 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-01T23:26:43.040Z,1709335603.040 [RudderServo](INFO): Powering down 2024-03-01T23:26:43.041Z,1709335603.041 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-03-01T23:26:43.041Z,1709335603.041 [ThrusterHE](INFO): Powering down 2024-03-01T23:26:43.368Z,1709335603.368 [ElevatorServo](FAULT): LCB fault: Current Limiter Activated. 2024-03-01T23:26:43.369Z,1709335603.369 [ElevatorServo] Hardware Fault, FailCount= 1 2024-03-01T23:26:43.369Z,1709335603.369 [ElevatorServo](ERROR): Hardware Fault 2024-03-01T23:26:43.369Z,1709335603.369 [RudderServo](FAULT): LCB fault: Current Limiter Activated. 2024-03-01T23:26:43.370Z,1709335603.370 [RudderServo] Hardware Fault, FailCount= 1 2024-03-01T23:26:43.370Z,1709335603.370 [RudderServo](ERROR): Hardware Fault 2024-03-01T23:26:43.380Z,1709335603.380 [ThrusterHE](FAULT): LCB fault: Current Limiter Activated. 2024-03-01T23:26:43.380Z,1709335603.380 [ThrusterHE] Hardware Fault, FailCount= 1 2024-03-01T23:26:43.380Z,1709335603.380 [ThrusterHE](ERROR): Hardware Fault 2024-03-01T23:26:43.772Z,1709335603.772 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:43.896Z,1709335603.896 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-01T23:26:44.202Z,1709335604.202 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2024-03-01T23:26:44.203Z,1709335604.203 [MassServo] Communications Fault, FailCount= 1 2024-03-01T23:26:44.203Z,1709335604.203 [MassServo](ERROR): Communications Fault 2024-03-01T23:26:44.204Z,1709335604.204 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-01T23:26:44.324Z,1709335604.324 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-01T23:26:44.763Z,1709335604.763 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:44.828Z,1709335604.828 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-03-01T23:26:44.829Z,1709335604.829 [MassServo](INFO): Powering down 2024-03-01T23:26:45.162Z,1709335605.162 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:45.216Z,1709335605.216 [MassServo](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2024-03-01T23:26:45.216Z,1709335605.216 [MassServo] Hardware Fault, FailCount= 1 2024-03-01T23:26:45.216Z,1709335605.216 [MassServo](ERROR): Hardware Fault 2024-03-01T23:26:45.567Z,1709335605.567 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:45.758Z,1709335605.758 [WetLabsBB2FL] Communications Fault, FailCount= 1 2024-03-01T23:26:45.758Z,1709335605.758 [WetLabsBB2FL](ERROR): Communications Fault 2024-03-01T23:26:46.078Z,1709335606.078 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:46.255Z,1709335606.255 [WetLabsBB2FL](INFO): Powering down 2024-03-01T23:26:46.482Z,1709335606.482 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:46.659Z,1709335606.659 [WetLabsBB2FL](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2024-03-01T23:26:46.659Z,1709335606.659 [WetLabsBB2FL] Hardware Fault, FailCount= 1 2024-03-01T23:26:46.659Z,1709335606.659 [WetLabsBB2FL](ERROR): Hardware Fault 2024-03-01T23:26:46.890Z,1709335606.890 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:47.295Z,1709335607.295 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:47.694Z,1709335607.694 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:48.110Z,1709335608.110 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:48.526Z,1709335608.526 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:48.918Z,1709335608.918 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:49.326Z,1709335609.326 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:49.734Z,1709335609.734 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:50.130Z,1709335610.130 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:50.534Z,1709335610.534 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-03-01T23:26:50.943Z,1709335610.943 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout