2024-01-19T00:23:21.260Z,1705623801.260 [Supervisor](DEBUG): Initializing supervisor. 2024-01-19T00:23:21.265Z,1705623801.265 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-01-19T00:23:21.265Z,1705623801.265 [SyncHandler](INFO): Protected caller Thread ID is 836 2024-01-19T00:23:21.266Z,1705623801.266 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-01-19T00:23:21.267Z,1705623801.267 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-01-19T00:23:21.267Z,1705623801.267 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2024-01-19T00:23:21.271Z,1705623801.271 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-01-19T00:23:21.288Z,1705623801.288 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-01-19T00:23:21.289Z,1705623801.289 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-01-19T00:23:21.290Z,1705623801.290 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838 2024-01-19T00:23:21.294Z,1705623801.294 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-01-19T00:23:21.295Z,1705623801.295 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-01-19T00:23:21.295Z,1705623801.295 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839 2024-01-19T00:23:21.297Z,1705623801.297 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-01-19T00:23:21.298Z,1705623801.298 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-01-19T00:23:21.299Z,1705623801.299 [logger ThreadHandler](INFO): Protected caller Thread ID is 840 2024-01-19T00:23:21.302Z,1705623801.302 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-01-19T00:23:21.302Z,1705623801.302 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-01-19T00:23:21.307Z,1705623801.307 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-01-19T00:23:21.571Z,1705623801.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-01-19T00:23:21.573Z,1705623801.573 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-01-19T00:23:21.655Z,1705623801.655 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-01-19T00:23:22.154Z,1705623802.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-01-19T00:23:22.156Z,1705623802.156 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-01-19T00:23:22.490Z,1705623802.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-01-19T00:23:22.492Z,1705623802.492 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-01-19T00:23:22.589Z,1705623802.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-01-19T00:23:22.591Z,1705623802.591 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-01-19T00:23:22.996Z,1705623802.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-01-19T00:23:22.998Z,1705623802.998 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-01-19T00:23:23.232Z,1705623803.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-01-19T00:23:23.233Z,1705623803.233 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-01-19T00:23:23.766Z,1705623803.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-01-19T00:23:23.766Z,1705623803.766 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-01-19T00:23:23.905Z,1705623803.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-01-19T00:23:23.907Z,1705623803.907 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-01-19T00:23:23.988Z,1705623803.988 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-01-19T00:23:24.800Z,1705623804.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-01-19T00:23:24.801Z,1705623804.801 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-01-19T00:23:25.790Z,1705623805.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-01-19T00:23:25.791Z,1705623805.791 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-01-19T00:23:25.986Z,1705623805.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-01-19T00:23:25.986Z,1705623805.986 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-01-19T00:23:26.100Z,1705623806.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-01-19T00:23:26.102Z,1705623806.102 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-01-19T00:23:26.334Z,1705623806.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-01-19T00:23:26.335Z,1705623806.335 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-01-19T00:23:26.591Z,1705623806.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-01-19T00:23:26.593Z,1705623806.593 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-01-19T00:23:26.597Z,1705623806.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-01-19T00:23:26.690Z,1705623806.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-01-19T00:23:26.777Z,1705623806.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-01-19T00:23:26.882Z,1705623806.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-01-19T00:23:26.967Z,1705623806.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-01-19T00:23:27.072Z,1705623807.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-01-19T00:23:27.176Z,1705623807.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-01-19T00:23:27.291Z,1705623807.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2024-01-19T00:23:27.369Z,1705623807.369 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-01-19T00:23:27.523Z,1705623807.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-01-19T00:23:27.669Z,1705623807.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-01-19T00:23:27.782Z,1705623807.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-01-19T00:23:28.052Z,1705623808.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-01-19T00:23:28.053Z,1705623808.053 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-01-19T00:23:28.069Z,1705623808.069 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-01-19T00:23:28.139Z,1705623808.139 [VerticalControl](DEBUG): Construct VerticalControl. 2024-01-19T00:23:28.198Z,1705623808.198 [VerticalControl] Loaded 2024-01-19T00:23:28.198Z,1705623808.198 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-01-19T00:23:28.201Z,1705623808.201 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-01-19T00:23:28.241Z,1705623808.241 [HorizontalControl] Loaded 2024-01-19T00:23:28.242Z,1705623808.242 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-01-19T00:23:28.244Z,1705623808.244 [SpeedControl](DEBUG): Construct SpeedControl. 2024-01-19T00:23:28.247Z,1705623808.247 [SpeedControl] Loaded 2024-01-19T00:23:28.247Z,1705623808.247 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-01-19T00:23:28.250Z,1705623808.250 [LoopControl](DEBUG): Construct LoopControl. 2024-01-19T00:23:28.250Z,1705623808.250 [LoopControl] Loaded 2024-01-19T00:23:28.251Z,1705623808.251 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-01-19T00:23:28.251Z,1705623808.251 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-01-19T00:23:28.253Z,1705623808.253 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-01-19T00:23:28.268Z,1705623808.268 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-01-19T00:23:28.268Z,1705623808.268 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-01-19T00:23:28.457Z,1705623808.457 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-01-19T00:23:28.458Z,1705623808.458 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-01-19T00:23:28.643Z,1705623808.643 [BuoyancyServo] Loaded 2024-01-19T00:23:28.643Z,1705623808.643 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-01-19T00:23:28.663Z,1705623808.663 [ElevatorServo] Loaded 2024-01-19T00:23:28.663Z,1705623808.663 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-01-19T00:23:28.682Z,1705623808.682 [MassServo] Loaded 2024-01-19T00:23:28.682Z,1705623808.682 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-01-19T00:23:28.700Z,1705623808.700 [RudderServo] Loaded 2024-01-19T00:23:28.700Z,1705623808.700 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-01-19T00:23:28.715Z,1705623808.715 [ThrusterHE] Loaded 2024-01-19T00:23:28.715Z,1705623808.715 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-01-19T00:23:28.715Z,1705623808.715 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-01-19T00:23:28.716Z,1705623808.716 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-01-19T00:23:28.858Z,1705623808.858 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-01-19T00:23:28.858Z,1705623808.858 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-01-19T00:23:28.872Z,1705623808.872 [NavChart] Loaded 2024-01-19T00:23:28.872Z,1705623808.872 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-01-19T00:23:28.877Z,1705623808.877 [UniversalFixResidualReporter] Loaded 2024-01-19T00:23:28.878Z,1705623808.878 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-01-19T00:23:28.878Z,1705623808.878 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-01-19T00:23:28.879Z,1705623808.879 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-01-19T00:23:28.961Z,1705623808.961 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-01-19T00:23:28.962Z,1705623808.962 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-01-19T00:23:29.711Z,1705623809.711 [AHRS_M2] Loaded 2024-01-19T00:23:29.711Z,1705623809.711 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-01-19T00:23:31.014Z,1705623811.014 [BPC1] Loaded 2024-01-19T00:23:31.014Z,1705623811.014 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-01-19T00:23:31.276Z,1705623811.276 [DataOverHttps] Loaded 2024-01-19T00:23:31.276Z,1705623811.276 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-01-19T00:23:31.277Z,1705623811.277 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-01-19T00:23:31.277Z,1705623811.277 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926 2024-01-19T00:23:31.298Z,1705623811.298 [Depth_Keller] Loaded 2024-01-19T00:23:31.298Z,1705623811.298 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-01-19T00:23:31.303Z,1705623811.303 [DropWeight] Loaded 2024-01-19T00:23:31.304Z,1705623811.304 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-01-19T00:23:31.365Z,1705623811.365 [NAL9602] Loaded 2024-01-19T00:23:31.365Z,1705623811.365 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-01-19T00:23:31.396Z,1705623811.396 [Onboard] Loaded 2024-01-19T00:23:31.396Z,1705623811.396 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-01-19T00:23:31.397Z,1705623811.397 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2024-01-19T00:23:31.397Z,1705623811.397 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927 2024-01-19T00:23:31.410Z,1705623811.410 [Power24vConverter] Loaded 2024-01-19T00:23:31.411Z,1705623811.411 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-01-19T00:23:31.424Z,1705623811.424 [Radio_Surface] Loaded 2024-01-19T00:23:31.424Z,1705623811.424 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-01-19T00:23:31.425Z,1705623811.425 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2024-01-19T00:23:31.425Z,1705623811.425 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928 2024-01-19T00:23:31.439Z,1705623811.439 [Sonardyne_Nano] Loaded 2024-01-19T00:23:31.439Z,1705623811.439 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-01-19T00:23:31.440Z,1705623811.440 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-01-19T00:23:31.440Z,1705623811.440 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-01-19T00:23:31.510Z,1705623811.510 [DepthRateCalculator] Loaded 2024-01-19T00:23:31.511Z,1705623811.511 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-01-19T00:23:31.516Z,1705623811.516 [PitchRateCalculator] Loaded 2024-01-19T00:23:31.516Z,1705623811.516 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-01-19T00:23:31.525Z,1705623811.525 [SpeedCalculator] Loaded 2024-01-19T00:23:31.525Z,1705623811.525 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-01-19T00:23:31.529Z,1705623811.529 [YawRateCalculator] Loaded 2024-01-19T00:23:31.530Z,1705623811.530 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-01-19T00:23:31.548Z,1705623811.548 [ElevatorOffsetCalculator] Loaded 2024-01-19T00:23:31.548Z,1705623811.548 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-01-19T00:23:31.549Z,1705623811.549 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-01-19T00:23:31.550Z,1705623811.550 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-01-19T00:23:31.735Z,1705623811.735 [CANONSampler] Loaded 2024-01-19T00:23:31.735Z,1705623811.735 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-01-19T00:23:31.796Z,1705623811.796 [CTD_Seabird] Loaded 2024-01-19T00:23:31.796Z,1705623811.796 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-01-19T00:23:31.798Z,1705623811.798 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0 2024-01-19T00:23:31.798Z,1705623811.798 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929 2024-01-19T00:23:31.864Z,1705623811.864 [PAR_Licor] Loaded 2024-01-19T00:23:31.864Z,1705623811.864 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-01-19T00:23:32.012Z,1705623812.012 [WetLabsBB2FL] Loaded 2024-01-19T00:23:32.012Z,1705623812.012 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-01-19T00:23:32.047Z,1705623812.047 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0 2024-01-19T00:23:32.047Z,1705623812.047 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930 2024-01-19T00:23:32.048Z,1705623812.048 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-01-19T00:23:32.049Z,1705623812.049 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-01-19T00:23:32.455Z,1705623812.455 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-01-19T00:23:32.456Z,1705623812.456 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-01-19T00:23:32.854Z,1705623812.854 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-01-19T00:23:32.854Z,1705623812.854 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-01-19T00:23:33.028Z,1705623813.028 [SBIT](DEBUG): Construct Startup Built In Test. 2024-01-19T00:23:33.037Z,1705623813.037 [SBIT] Loaded 2024-01-19T00:23:33.037Z,1705623813.037 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-01-19T00:23:33.040Z,1705623813.040 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-01-19T00:23:33.054Z,1705623813.054 [IBIT] Loaded 2024-01-19T00:23:33.054Z,1705623813.054 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-01-19T00:23:33.060Z,1705623813.060 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-01-19T00:23:33.164Z,1705623813.164 [CBIT] Loaded 2024-01-19T00:23:33.164Z,1705623813.164 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-01-19T00:23:33.165Z,1705623813.165 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-01-19T00:23:33.165Z,1705623813.165 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-01-19T00:23:33.305Z,1705623813.305 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-01-19T00:23:33.311Z,1705623813.311 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-01-19T00:23:33.314Z,1705623813.314 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-01-19T00:23:33.325Z,1705623813.325 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-01-19T00:23:33.326Z,1705623813.326 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BAF4E0 2024-01-19T00:23:33.327Z,1705623813.327 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 2024-01-19T00:23:33.331Z,1705623813.331 [Supervisor](INFO): Main Thread ID is 829 2024-01-19T00:23:33.331Z,1705623813.331 [Supervisor](DEBUG): Running supervisor. 2024-01-19T00:23:33.332Z,1705623813.332 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 2024-01-19T00:23:33.332Z,1705623813.332 [CommandExec](INFO): Initializing the command executive. 2024-01-19T00:23:33.334Z,1705623813.334 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 2024-01-19T00:23:33.336Z,1705623813.336 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 2024-01-19T00:23:33.337Z,1705623813.337 [controlThread](DEBUG): Initializing ControlThread 2024-01-19T00:23:33.337Z,1705623813.337 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-01-19T00:23:33.339Z,1705623813.339 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-01-19T00:23:33.340Z,1705623813.340 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-01-19T00:23:33.341Z,1705623813.341 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-01-19T00:23:33.342Z,1705623813.342 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-01-19T00:23:33.343Z,1705623813.343 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-01-19T00:23:33.349Z,1705623813.349 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-01-19T00:23:33.349Z,1705623813.349 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-01-19T00:23:33.350Z,1705623813.350 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-01-19T00:23:33.350Z,1705623813.350 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-01-19T00:23:33.350Z,1705623813.350 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-01-19T00:23:33.352Z,1705623813.352 [SBIT](INFO): Initialize SBIT Component. 2024-01-19T00:23:33.353Z,1705623813.353 [SBIT](IMPORTANT): git: 2023-12-07 2024-01-19T00:23:33.353Z,1705623813.353 [SBIT](INFO): git hash: dbc7300d59e166afd06c26988583bb6e3d4bec93 2024-01-19T00:23:33.353Z,1705623813.353 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-01-19T00:23:33.354Z,1705623813.354 [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-01-19T00:23:33.356Z,1705623813.356 [SBIT](INFO): Beginning SBIT in 60.000000 seconds. 2024-01-19T00:23:33.356Z,1705623813.356 [IBIT](INFO): Initialize IBIT Component. 2024-01-19T00:23:33.357Z,1705623813.357 [CBIT](DEBUG): Initialize CBIT Component. 2024-01-19T00:23:33.358Z,1705623813.358 [logger ThreadHandler](INFO): Handler Thread ID is 935 2024-01-19T00:23:33.371Z,1705623813.371 [CBIT](DEBUG): Initialized mux pins. 2024-01-19T00:23:33.371Z,1705623813.371 [CBIT](DEBUG): Initializing the watchdog timer. 2024-01-19T00:23:33.376Z,1705623813.376 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2024-01-19T00:23:33.377Z,1705623813.377 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-01-19T00:23:33.384Z,1705623813.384 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2024-01-19T00:23:33.395Z,1705623813.395 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-01-19T00:23:33.396Z,1705623813.396 [CBIT](DEBUG): Initializing heartbeat. 2024-01-19T00:23:33.401Z,1705623813.401 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2024-01-19T00:23:33.413Z,1705623813.413 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2024-01-19T00:23:33.413Z,1705623813.413 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-01-19T00:23:33.421Z,1705623813.421 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2024-01-19T00:23:33.424Z,1705623813.424 [WetLabsBB2FL](INFO): Powering up 2024-01-19T00:23:33.425Z,1705623813.425 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2024-01-19T00:23:33.433Z,1705623813.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-01-19T00:23:33.433Z,1705623813.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-01-19T00:23:33.433Z,1705623813.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-01-19T00:23:33.433Z,1705623813.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-01-19T00:23:33.433Z,1705623813.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-01-19T00:23:33.434Z,1705623813.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-01-19T00:23:33.434Z,1705623813.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-01-19T00:23:33.434Z,1705623813.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-01-19T00:23:33.467Z,1705623813.467 [CBIT](DEBUG): Deactivating GF circuits. 2024-01-19T00:23:33.467Z,1705623813.467 [CBIT](DEBUG): Deactivating emergency mode. 2024-01-19T00:23:33.507Z,1705623813.507 [CBIT](DEBUG): Backplane powered. 2024-01-19T00:23:33.508Z,1705623813.508 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2024-01-19T00:23:33.535Z,1705623813.535 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-01-19T00:23:33.559Z,1705623813.559 [MissionManager](DEBUG): 2024-01-19T00:23:33.583Z,1705623813.583 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2024-01-19T00:23:33.663Z,1705623813.663 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-01-19T00:23:33.679Z,1705623813.679 [Default:A.Wait](DEBUG): Construct Wait. 2024-01-19T00:23:33.681Z,1705623813.681 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-01-19T00:23:33.701Z,1705623813.701 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-01-19T00:23:33.724Z,1705623813.724 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-01-19T00:23:33.730Z,1705623813.730 [Default:E.Execute](DEBUG): Construct Execute. 2024-01-19T00:23:33.749Z,1705623813.749 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-01-19T00:23:33.754Z,1705623813.754 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,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,Reporter,LogSplitter, 2024-01-19T00:23:33.779Z,1705623813.779 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-01-19T00:23:33.807Z,1705623813.807 [Radio_Surface](INFO): Powering up 2024-01-19T00:23:33.942Z,1705623813.942 [Power24vConverter](INFO): Powering up. 2024-01-19T00:23:33.943Z,1705623813.943 [Sonardyne_Nano](INFO): Initializing. 2024-01-19T00:23:33.963Z,1705623813.963 [CANONSampler](INFO): Powering down 2024-01-19T00:23:33.999Z,1705623813.999 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-01-19T00:23:34.008Z,1705623814.008 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-01-19T00:23:34.009Z,1705623814.009 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-01-19T00:23:34.016Z,1705623814.016 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-01-19T00:23:34.017Z,1705623814.017 [MassServo](DEBUG): Initializing EZServoServo. 2024-01-19T00:23:34.023Z,1705623814.023 [MassServo](DEBUG): Initializing MassServo. 2024-01-19T00:23:34.024Z,1705623814.024 [RudderServo](DEBUG): Initializing EZServoServo. 2024-01-19T00:23:34.031Z,1705623814.031 [RudderServo](DEBUG): Initializing RudderServo. 2024-01-19T00:23:34.032Z,1705623814.032 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-01-19T00:23:34.040Z,1705623814.040 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-01-19T00:23:36.035Z,1705623816.035 [WetLabsBB2FL](INFO): Powering down 2024-01-19T00:23:36.186Z,1705623816.186 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-01-19T00:23:37.429Z,1705623817.429 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2024-01-19T00:23:39.696Z,1705623819.696 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-01-19T00:24:01.641Z,1705623841.641 [NAL9602](INFO): Powering up NAL9602 2024-01-19T00:24:04.428Z,1705623844.428 [CommandExec](IMPORTANT): got command restart system 2024-01-19T00:24:06.527Z,1705623846.527 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-01-19T00:24:06.528Z,1705623846.528 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:06.528Z,1705623846.528 [CommandExec](INFO): Uninitializing the command executive. 2024-01-19T00:24:06.528Z,1705623846.528 [CommandExec](INFO): Uninitializing the command scheduler. 2024-01-19T00:24:06.528Z,1705623846.528 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:06.707Z,1705623846.707 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-01-19T00:24:06.707Z,1705623846.707 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-01-19T00:24:06.708Z,1705623846.708 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:06.708Z,1705623846.708 [NavChartDb](INFO): Join timeout helper Thread ID is 956 2024-01-19T00:24:07.050Z,1705623847.050 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:07.050Z,1705623847.050 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.059Z,1705623847.059 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-01-19T00:24:07.059Z,1705623847.059 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.060Z,1705623847.060 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 957 2024-01-19T00:24:07.199Z,1705623847.199 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:07.200Z,1705623847.200 [WetLabsBB2FL](INFO): Powering down 2024-01-19T00:24:07.200Z,1705623847.200 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.203Z,1705623847.203 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-01-19T00:24:07.203Z,1705623847.203 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.204Z,1705623847.204 [CTD_Seabird](INFO): Join timeout helper Thread ID is 958 2024-01-19T00:24:07.547Z,1705623847.547 [CTD_Seabird](INFO): Powering down 2024-01-19T00:24:07.559Z,1705623847.559 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:07.559Z,1705623847.559 [CTD_Seabird](INFO): Powering down 2024-01-19T00:24:07.571Z,1705623847.571 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.594Z,1705623847.594 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-01-19T00:24:07.594Z,1705623847.594 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.595Z,1705623847.595 [Radio_Surface](INFO): Join timeout helper Thread ID is 959 2024-01-19T00:24:07.784Z,1705623847.784 [Radio_Surface](INFO): Powering down 2024-01-19T00:24:07.788Z,1705623847.788 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:07.788Z,1705623847.788 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.792Z,1705623847.792 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-01-19T00:24:07.792Z,1705623847.792 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:07.792Z,1705623847.792 [Onboard](INFO): Join timeout helper Thread ID is 960 2024-01-19T00:24:08.411Z,1705623848.411 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:08.412Z,1705623848.412 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.416Z,1705623848.416 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-01-19T00:24:08.416Z,1705623848.416 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.417Z,1705623848.417 [DataOverHttps](INFO): Join timeout helper Thread ID is 961 2024-01-19T00:24:08.519Z,1705623848.519 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:08.520Z,1705623848.520 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.521Z,1705623848.521 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-01-19T00:24:08.521Z,1705623848.521 [logger ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.522Z,1705623848.522 [logger](INFO): Join timeout helper Thread ID is 962 2024-01-19T00:24:08.527Z,1705623848.527 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:08.528Z,1705623848.528 [logger ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.542Z,1705623848.542 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-01-19T00:24:08.542Z,1705623848.542 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.542Z,1705623848.542 [CommandLine](INFO): Join timeout helper Thread ID is 963 2024-01-19T00:24:08.579Z,1705623848.579 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:08.580Z,1705623848.580 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.582Z,1705623848.582 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-01-19T00:24:08.582Z,1705623848.582 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.582Z,1705623848.582 [CommandExec](INFO): Join timeout helper Thread ID is 964 2024-01-19T00:24:08.584Z,1705623848.584 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-01-19T00:24:08.584Z,1705623848.584 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:08.585Z,1705623848.585 [controlThread](INFO): Join timeout helper Thread ID is 965 2024-01-19T00:24:08.624Z,1705623848.624 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-19T00:24:08.625Z,1705623848.625 [controlThread](DEBUG): Uninitializing ControlThread 2024-01-19T00:24:08.625Z,1705623848.625 [AHRS_M2](INFO): Powering down 2024-01-19T00:24:08.697Z,1705623848.697 [NAL9602](INFO): Powering down 2024-01-19T00:24:08.698Z,1705623848.698 [Sonardyne_Nano](INFO): Powering down 2024-01-19T00:24:08.817Z,1705623848.817 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-01-19T00:24:08.818Z,1705623848.818 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-01-19T00:24:08.818Z,1705623848.818 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-01-19T00:24:08.819Z,1705623848.819 [MissionManager](INFO): Uninitializing Mission Default 2024-01-19T00:24:08.822Z,1705623848.822 [MissionManager](INFO): Uninitializing Mission Startup 2024-01-19T00:24:08.823Z,1705623848.823 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-01-19T00:24:08.824Z,1705623848.824 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-01-19T00:24:08.824Z,1705623848.824 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-01-19T00:24:08.824Z,1705623848.824 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-01-19T00:24:08.825Z,1705623848.825 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-01-19T00:24:08.825Z,1705623848.825 [BuoyancyServo](INFO): Powering down 2024-01-19T00:24:08.839Z,1705623848.839 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-01-19T00:24:08.839Z,1705623848.839 [ElevatorServo](INFO): Powering down 2024-01-19T00:24:08.840Z,1705623848.840 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-01-19T00:24:08.841Z,1705623848.841 [MassServo](INFO): Powering down 2024-01-19T00:24:08.842Z,1705623848.842 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-01-19T00:24:08.842Z,1705623848.842 [RudderServo](INFO): Powering down 2024-01-19T00:24:08.842Z,1705623848.842 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-01-19T00:24:08.843Z,1705623848.843 [ThrusterHE](INFO): Powering down 2024-01-19T00:24:08.844Z,1705623848.844 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-01-19T00:24:08.844Z,1705623848.844 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-01-19T00:24:08.844Z,1705623848.844 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-01-19T00:24:08.845Z,1705623848.845 [CBIT](DEBUG): Powering off loads. 2024-01-19T00:24:08.855Z,1705623848.855 [CBIT](DEBUG): Disabling WDT. 2024-01-19T00:24:08.867Z,1705623848.867 [CBIT](DEBUG): Opening all GF detection circuits. 2024-01-19T00:24:08.868Z,1705623848.868 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.283Z,1705623849.283 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.285Z,1705623849.285 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.292Z,1705623849.292 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.367Z,1705623849.367 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.371Z,1705623849.371 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.416Z,1705623849.416 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-19T00:24:09.480Z,1705623849.480 [logger ThreadHandler](INFO): Thread cancelled.