2022-04-21T20:23:08.806Z,1650572588.806 [Supervisor](DEBUG): Initializing supervisor. 2022-04-21T20:23:08.810Z,1650572588.810 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-04-21T20:23:08.811Z,1650572588.811 [SyncHandler](INFO): Protected caller Thread ID is 843 2022-04-21T20:23:08.811Z,1650572588.811 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-04-21T20:23:08.812Z,1650572588.812 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-04-21T20:23:08.813Z,1650572588.813 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 844 2022-04-21T20:23:08.817Z,1650572588.817 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-04-21T20:23:08.836Z,1650572588.836 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-04-21T20:23:08.837Z,1650572588.837 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-04-21T20:23:08.837Z,1650572588.837 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 845 2022-04-21T20:23:08.841Z,1650572588.841 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-04-21T20:23:08.842Z,1650572588.842 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-04-21T20:23:08.843Z,1650572588.843 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 846 2022-04-21T20:23:08.845Z,1650572588.845 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-04-21T20:23:08.846Z,1650572588.846 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-04-21T20:23:08.846Z,1650572588.846 [logger ThreadHandler](INFO): Protected caller Thread ID is 847 2022-04-21T20:23:08.850Z,1650572588.850 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-04-21T20:23:08.850Z,1650572588.850 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-04-21T20:23:08.854Z,1650572588.854 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-04-21T20:23:09.350Z,1650572589.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-04-21T20:23:09.352Z,1650572589.352 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-04-21T20:23:09.576Z,1650572589.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-04-21T20:23:09.578Z,1650572589.578 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-04-21T20:23:09.660Z,1650572589.660 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-04-21T20:23:09.891Z,1650572589.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-04-21T20:23:09.893Z,1650572589.893 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-04-21T20:23:09.974Z,1650572589.974 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-04-21T20:23:10.073Z,1650572590.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-04-21T20:23:10.075Z,1650572590.075 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-04-21T20:23:10.433Z,1650572590.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-04-21T20:23:10.435Z,1650572590.435 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-04-21T20:23:10.565Z,1650572590.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-04-21T20:23:10.567Z,1650572590.567 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-04-21T20:23:11.149Z,1650572591.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-04-21T20:23:11.151Z,1650572591.151 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-04-21T20:23:11.358Z,1650572591.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-04-21T20:23:11.359Z,1650572591.359 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-04-21T20:23:11.815Z,1650572591.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-04-21T20:23:12.095Z,1650572592.095 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-04-21T20:23:12.558Z,1650572592.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-04-21T20:23:12.560Z,1650572592.560 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-04-21T20:23:12.674Z,1650572592.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-04-21T20:23:12.675Z,1650572592.675 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-04-21T20:23:13.417Z,1650572593.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-04-21T20:23:13.417Z,1650572593.417 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-04-21T20:23:13.779Z,1650572593.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-04-21T20:23:13.779Z,1650572593.779 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-04-21T20:23:14.005Z,1650572594.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-04-21T20:23:14.007Z,1650572594.007 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2022-04-21T20:23:14.011Z,1650572594.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2022-04-21T20:23:14.209Z,1650572594.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2022-04-21T20:23:14.294Z,1650572594.294 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2022-04-21T20:23:14.435Z,1650572594.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2022-04-21T20:23:14.522Z,1650572594.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2022-04-21T20:23:14.789Z,1650572594.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-04-21T20:23:14.790Z,1650572594.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2022-04-21T20:23:14.899Z,1650572594.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2022-04-21T20:23:15.016Z,1650572595.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2022-04-21T20:23:15.114Z,1650572595.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2022-04-21T20:23:15.265Z,1650572595.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2022-04-21T20:23:15.369Z,1650572595.369 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2022-04-21T20:23:15.465Z,1650572595.465 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-04-21T20:23:15.491Z,1650572595.491 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-04-21T20:23:15.507Z,1650572595.507 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-04-21T20:23:15.508Z,1650572595.508 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-04-21T20:23:15.629Z,1650572595.629 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-04-21T20:23:15.630Z,1650572595.630 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-04-21T20:23:15.700Z,1650572595.700 [VerticalControl](DEBUG): Construct VerticalControl. 2022-04-21T20:23:15.761Z,1650572595.761 [VerticalControl] Loaded 2022-04-21T20:23:15.762Z,1650572595.762 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-04-21T20:23:15.764Z,1650572595.764 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-04-21T20:23:15.804Z,1650572595.804 [HorizontalControl] Loaded 2022-04-21T20:23:15.805Z,1650572595.805 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-04-21T20:23:15.807Z,1650572595.807 [SpeedControl](DEBUG): Construct SpeedControl. 2022-04-21T20:23:15.810Z,1650572595.810 [SpeedControl] Loaded 2022-04-21T20:23:15.811Z,1650572595.811 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-04-21T20:23:15.813Z,1650572595.813 [LoopControl](DEBUG): Construct LoopControl. 2022-04-21T20:23:15.814Z,1650572595.814 [LoopControl] Loaded 2022-04-21T20:23:15.814Z,1650572595.814 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-04-21T20:23:15.814Z,1650572595.814 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-04-21T20:23:15.816Z,1650572595.816 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-04-21T20:23:15.993Z,1650572595.993 [BuoyancyServo] Loaded 2022-04-21T20:23:15.993Z,1650572595.993 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-04-21T20:23:16.012Z,1650572596.012 [ElevatorServo] Loaded 2022-04-21T20:23:16.013Z,1650572596.013 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-04-21T20:23:16.031Z,1650572596.031 [MassServo] Loaded 2022-04-21T20:23:16.031Z,1650572596.031 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-04-21T20:23:16.049Z,1650572596.049 [RudderServo] Loaded 2022-04-21T20:23:16.049Z,1650572596.049 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-04-21T20:23:16.065Z,1650572596.065 [ThrusterHE] Loaded 2022-04-21T20:23:16.065Z,1650572596.065 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-04-21T20:23:16.065Z,1650572596.065 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-04-21T20:23:16.066Z,1650572596.066 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-04-21T20:23:16.138Z,1650572596.138 [DepthRateCalculator] Loaded 2022-04-21T20:23:16.138Z,1650572596.138 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-04-21T20:23:16.143Z,1650572596.143 [PitchRateCalculator] Loaded 2022-04-21T20:23:16.143Z,1650572596.143 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-04-21T20:23:16.153Z,1650572596.153 [SpeedCalculator] Loaded 2022-04-21T20:23:16.154Z,1650572596.154 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-04-21T20:23:16.170Z,1650572596.170 [VerticalHomogeneityIndexCalculator] Loaded 2022-04-21T20:23:16.170Z,1650572596.170 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread. 2022-04-21T20:23:16.174Z,1650572596.174 [YawRateCalculator] Loaded 2022-04-21T20:23:16.175Z,1650572596.175 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-04-21T20:23:16.192Z,1650572596.192 [ElevatorOffsetCalculator] Loaded 2022-04-21T20:23:16.193Z,1650572596.193 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-04-21T20:23:16.193Z,1650572596.193 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-04-21T20:23:16.194Z,1650572596.194 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-04-21T20:23:16.334Z,1650572596.334 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-04-21T20:23:16.334Z,1650572596.334 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-04-21T20:23:16.348Z,1650572596.348 [NavChart] Loaded 2022-04-21T20:23:16.349Z,1650572596.349 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-04-21T20:23:16.354Z,1650572596.354 [UniversalFixResidualReporter] Loaded 2022-04-21T20:23:16.355Z,1650572596.355 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-04-21T20:23:16.355Z,1650572596.355 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-04-21T20:23:16.356Z,1650572596.356 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-04-21T20:23:17.242Z,1650572597.242 [AHRS_M2] Loaded 2022-04-21T20:23:17.243Z,1650572597.243 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-04-21T20:23:17.661Z,1650572597.661 [BackseatComponent] Loaded 2022-04-21T20:23:17.662Z,1650572597.662 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-04-21T20:23:17.663Z,1650572597.663 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0 2022-04-21T20:23:17.663Z,1650572597.663 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 929 2022-04-21T20:23:17.666Z,1650572597.666 [LcmUniversalReporter] Loaded 2022-04-21T20:23:17.666Z,1650572597.666 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-04-21T20:23:18.757Z,1650572598.757 [BPC1] Loaded 2022-04-21T20:23:18.757Z,1650572598.757 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-04-21T20:23:19.085Z,1650572599.085 [DataOverHttps] Loaded 2022-04-21T20:23:19.085Z,1650572599.085 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-04-21T20:23:19.086Z,1650572599.086 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0 2022-04-21T20:23:19.087Z,1650572599.087 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 930 2022-04-21T20:23:19.107Z,1650572599.107 [Depth_Keller] Loaded 2022-04-21T20:23:19.107Z,1650572599.107 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-04-21T20:23:19.112Z,1650572599.112 [DropWeight] Loaded 2022-04-21T20:23:19.112Z,1650572599.112 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-04-21T20:23:19.172Z,1650572599.172 [NAL9602] Loaded 2022-04-21T20:23:19.173Z,1650572599.173 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-04-21T20:23:19.202Z,1650572599.202 [Onboard] Loaded 2022-04-21T20:23:19.202Z,1650572599.202 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-04-21T20:23:19.203Z,1650572599.203 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0 2022-04-21T20:23:19.204Z,1650572599.204 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 931 2022-04-21T20:23:19.215Z,1650572599.215 [Power24vConverter] Loaded 2022-04-21T20:23:19.216Z,1650572599.216 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-04-21T20:23:19.229Z,1650572599.229 [Radio_Surface] Loaded 2022-04-21T20:23:19.229Z,1650572599.229 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-04-21T20:23:19.230Z,1650572599.230 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0 2022-04-21T20:23:19.230Z,1650572599.230 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 932 2022-04-21T20:23:19.231Z,1650572599.231 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-04-21T20:23:19.232Z,1650572599.232 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-04-21T20:23:19.332Z,1650572599.332 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC 2022-04-21T20:23:19.332Z,1650572599.332 [StratificationFrontDetector](DEBUG): (re)initializing 2022-04-21T20:23:19.332Z,1650572599.332 [StratificationFrontDetector] Loaded 2022-04-21T20:23:19.332Z,1650572599.332 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2022-04-21T20:23:19.333Z,1650572599.333 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-04-21T20:23:19.334Z,1650572599.334 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-04-21T20:23:19.476Z,1650572599.476 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-04-21T20:23:19.476Z,1650572599.476 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-04-21T20:23:19.518Z,1650572599.518 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-04-21T20:23:19.519Z,1650572599.519 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-04-21T20:23:19.739Z,1650572599.739 [CTD_Seabird] Loaded 2022-04-21T20:23:19.740Z,1650572599.740 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-04-21T20:23:19.741Z,1650572599.741 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A8D4E0 2022-04-21T20:23:19.741Z,1650572599.741 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 933 2022-04-21T20:23:19.762Z,1650572599.762 [PAR_Licor] Loaded 2022-04-21T20:23:19.762Z,1650572599.762 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-04-21T20:23:19.793Z,1650572599.793 [WetLabsBB2FL] Loaded 2022-04-21T20:23:19.794Z,1650572599.794 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-04-21T20:23:19.795Z,1650572599.795 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ABD4E0 2022-04-21T20:23:19.795Z,1650572599.795 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 934 2022-04-21T20:23:19.796Z,1650572599.796 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-04-21T20:23:19.797Z,1650572599.797 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-04-21T20:23:20.244Z,1650572600.244 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-04-21T20:23:20.245Z,1650572600.245 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-04-21T20:23:20.417Z,1650572600.417 [SBIT](DEBUG): Construct Startup Built In Test. 2022-04-21T20:23:20.427Z,1650572600.427 [SBIT] Loaded 2022-04-21T20:23:20.427Z,1650572600.427 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-04-21T20:23:20.430Z,1650572600.430 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-04-21T20:23:20.443Z,1650572600.443 [IBIT] Loaded 2022-04-21T20:23:20.443Z,1650572600.443 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-04-21T20:23:20.449Z,1650572600.449 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-04-21T20:23:20.555Z,1650572600.555 [CBIT] Loaded 2022-04-21T20:23:20.556Z,1650572600.556 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-04-21T20:23:20.556Z,1650572600.556 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-04-21T20:23:20.562Z,1650572600.562 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-04-21T20:23:20.565Z,1650572600.565 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-04-21T20:23:20.576Z,1650572600.576 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-04-21T20:23:20.577Z,1650572600.577 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA74E0 2022-04-21T20:23:20.577Z,1650572600.577 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 936 2022-04-21T20:23:20.582Z,1650572600.582 [Supervisor](INFO): Main Thread ID is 839 2022-04-21T20:23:20.582Z,1650572600.582 [Supervisor](DEBUG): Running supervisor. 2022-04-21T20:23:20.583Z,1650572600.583 [CommandExec ThreadHandler](INFO): Handler Thread ID is 937 2022-04-21T20:23:20.583Z,1650572600.583 [CommandExec](INFO): Initializing the command executive. 2022-04-21T20:23:20.584Z,1650572600.584 [CommandLine ThreadHandler](INFO): Handler Thread ID is 938 2022-04-21T20:23:20.587Z,1650572600.587 [controlThread ThreadHandler](INFO): Handler Thread ID is 939 2022-04-21T20:23:20.587Z,1650572600.587 [controlThread](DEBUG): Initializing ControlThread 2022-04-21T20:23:20.588Z,1650572600.588 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-04-21T20:23:20.590Z,1650572600.590 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-04-21T20:23:20.590Z,1650572600.590 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-04-21T20:23:20.591Z,1650572600.591 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-04-21T20:23:20.592Z,1650572600.592 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-04-21T20:23:20.593Z,1650572600.593 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-04-21T20:23:20.593Z,1650572600.593 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-04-21T20:23:20.593Z,1650572600.593 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing 2022-04-21T20:23:20.594Z,1650572600.594 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-04-21T20:23:20.594Z,1650572600.594 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-04-21T20:23:20.596Z,1650572600.596 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-04-21T20:23:20.596Z,1650572600.596 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-04-21T20:23:20.604Z,1650572600.604 [SBIT](INFO): Initialize SBIT Component. 2022-04-21T20:23:20.605Z,1650572600.605 [SBIT](IMPORTANT): git: 2022-04-20 2022-04-21T20:23:20.605Z,1650572600.605 [SBIT](INFO): git hash: 17b9e0401aa943d1c74ec99193b02e6257598627 2022-04-21T20:23:20.605Z,1650572600.605 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-04-21T20:23:20.606Z,1650572600.606 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-04-21T20:23:20.607Z,1650572600.607 [SBIT](INFO): Beginning SBIT in 52.000000 seconds. 2022-04-21T20:23:20.608Z,1650572600.608 [IBIT](INFO): Initialize IBIT Component. 2022-04-21T20:23:20.609Z,1650572600.609 [CBIT](DEBUG): Initialize CBIT Component. 2022-04-21T20:23:20.610Z,1650572600.610 [logger ThreadHandler](INFO): Handler Thread ID is 940 2022-04-21T20:23:20.620Z,1650572600.620 [CBIT](DEBUG): Initialized mux pins. 2022-04-21T20:23:20.620Z,1650572600.620 [CBIT](DEBUG): Initializing the watchdog timer. 2022-04-21T20:23:20.628Z,1650572600.628 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 941 2022-04-21T20:23:20.640Z,1650572600.640 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 942 2022-04-21T20:23:20.641Z,1650572600.641 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-04-21T20:23:20.644Z,1650572600.644 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-04-21T20:23:20.644Z,1650572600.644 [CBIT](DEBUG): Initializing heartbeat. 2022-04-21T20:23:20.652Z,1650572600.652 [Onboard ThreadHandler](INFO): Handler Thread ID is 943 2022-04-21T20:23:20.670Z,1650572600.670 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 944 2022-04-21T20:23:20.688Z,1650572600.688 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 945 2022-04-21T20:23:20.689Z,1650572600.689 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-04-21T20:23:20.693Z,1650572600.693 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 947 2022-04-21T20:23:20.696Z,1650572600.696 [WetLabsBB2FL](INFO): Powering up 2022-04-21T20:23:20.697Z,1650572600.697 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 949 2022-04-21T20:23:20.705Z,1650572600.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-04-21T20:23:20.706Z,1650572600.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-04-21T20:23:20.706Z,1650572600.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-04-21T20:23:20.706Z,1650572600.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-04-21T20:23:20.706Z,1650572600.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-04-21T20:23:20.706Z,1650572600.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-04-21T20:23:20.706Z,1650572600.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2022-04-21T20:23:20.707Z,1650572600.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2022-04-21T20:23:20.707Z,1650572600.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-04-21T20:23:20.707Z,1650572600.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-04-21T20:23:20.707Z,1650572600.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2022-04-21T20:23:20.707Z,1650572600.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2022-04-21T20:23:20.707Z,1650572600.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2022-04-21T20:23:20.708Z,1650572600.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2022-04-21T20:23:20.708Z,1650572600.708 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2022-04-21T20:23:20.708Z,1650572600.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2022-04-21T20:23:20.716Z,1650572600.716 [CBIT](DEBUG): Deactivating GF circuits. 2022-04-21T20:23:20.716Z,1650572600.716 [CBIT](DEBUG): Deactivating emergency mode. 2022-04-21T20:23:20.752Z,1650572600.752 [CBIT](DEBUG): Backplane powered. 2022-04-21T20:23:20.753Z,1650572600.753 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-04-21T20:23:20.788Z,1650572600.788 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-04-21T20:23:20.816Z,1650572600.816 [MissionManager](DEBUG): 2022-04-21T20:23:20.817Z,1650572600.817 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-04-21T20:23:20.912Z,1650572600.912 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-04-21T20:23:20.914Z,1650572600.914 [Default:A.Wait](DEBUG): Construct Wait. 2022-04-21T20:23:20.916Z,1650572600.916 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-04-21T20:23:20.966Z,1650572600.966 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-04-21T20:23:20.969Z,1650572600.969 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-04-21T20:23:20.995Z,1650572600.995 [Default:E.Execute](DEBUG): Construct Execute. 2022-04-21T20:23:21.006Z,1650572601.006 [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 2022-04-21T20:23:21.011Z,1650572601.011 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-04-21T20:23:21.024Z,1650572601.024 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-04-21T20:23:21.076Z,1650572601.076 [Radio_Surface](INFO): Powering up 2022-04-21T20:23:21.143Z,1650572601.143 [Power24vConverter](INFO): Powering up. 2022-04-21T20:23:21.186Z,1650572601.186 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:23:21.192Z,1650572601.192 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-04-21T20:23:21.193Z,1650572601.193 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:23:21.200Z,1650572601.200 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-04-21T20:23:21.201Z,1650572601.201 [MassServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:23:21.209Z,1650572601.209 [MassServo](DEBUG): Initializing MassServo. 2022-04-21T20:23:21.209Z,1650572601.209 [RudderServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:23:21.216Z,1650572601.216 [RudderServo](DEBUG): Initializing RudderServo. 2022-04-21T20:23:21.217Z,1650572601.217 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-04-21T20:23:21.224Z,1650572601.224 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-04-21T20:23:21.441Z,1650572601.441 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-04-21T20:23:21.441Z,1650572601.441 [DropWeight] Hardware Fault, FailCount= 1 2022-04-21T20:23:21.441Z,1650572601.441 [DropWeight](ERROR): Hardware Fault 2022-04-21T20:23:21.491Z,1650572601.491 [CommandExec](FAULT): Scheduling is paused 2022-04-21T20:23:21.491Z,1650572601.491 [CBIT](INFO): Critical error at 20220421T202321 2022-04-21T20:23:21.504Z,1650572601.504 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-04-21T20:23:21.506Z,1650572601.506 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-04-21T20:23:21.507Z,1650572601.507 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-04-21T20:23:22.144Z,1650572602.144 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-04-21T20:23:22.144Z,1650572602.144 [RudderServo](FAULT): Rudder failed to initialize 2022-04-21T20:23:22.144Z,1650572602.144 [RudderServo] Communications Fault, FailCount= 1 2022-04-21T20:23:22.144Z,1650572602.144 [RudderServo](ERROR): Communications Fault 2022-04-21T20:23:22.146Z,1650572602.146 [CBIT](INFO): Critical error at 20220421T202321 2022-04-21T20:23:22.148Z,1650572602.148 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-04-21T20:23:22.377Z,1650572602.377 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-04-21T20:23:22.377Z,1650572602.377 [RudderServo](INFO): Powering down 2022-04-21T20:23:22.804Z,1650572602.804 [WetLabsBB2FL](INFO): Powering down 2022-04-21T20:23:23.057Z,1650572603.057 [RudderServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:23:23.177Z,1650572603.177 [RudderServo](DEBUG): Initializing RudderServo. 2022-04-21T20:23:23.181Z,1650572603.181 [CBIT](INFO): Clearing failed state for component RudderServo 2022-04-21T20:23:23.181Z,1650572603.181 [RudderServo] No Fault, FailCount= 1 2022-04-21T20:23:26.957Z,1650572606.957 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-04-21T20:23:27.720Z,1650572607.720 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2022-04-21T20:23:49.297Z,1650572629.297 [NAL9602](INFO): Powering up NAL9602 2022-04-21T20:24:00.217Z,1650572640.217 [NAL9602](INFO): NAL9602 initialized 2022-04-21T20:24:09.105Z,1650572649.105 [CommandExec](IMPORTANT): got command strobe off 2022-04-21T20:24:09.105Z,1650572649.105 [CommandExec](IMPORTANT): Deactivating strobe 2022-04-21T20:24:13.214Z,1650572653.214 [SBIT](IMPORTANT): Beginning Startup BIT 2022-04-21T20:24:13.218Z,1650572653.218 [CBIT](IMPORTANT): Beginning ground fault scan 2022-04-21T20:24:24.180Z,1650572664.180 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.024776 CHAN A1 (24V): 0.168769 CHAN A2 (12V): -0.003781 CHAN A3 (5V): -0.003313 CHAN B0 (3.3V): -0.000424 CHAN B1 (3.15aV): -0.001388 CHAN B2 (3.15bV): -0.001618 CHAN B3 (GND): -0.000872 OPEN: 0.004517 Full Scale: +/- 1 mA 2022-04-21T20:24:24.832Z,1650572664.832 [RudderServo](ERROR): getPosition uart error serial timeout 2022-04-21T20:24:24.832Z,1650572664.832 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2022-04-21T20:24:24.832Z,1650572664.832 [RudderServo] Communications Fault, FailCount= 2 2022-04-21T20:24:24.832Z,1650572664.832 [RudderServo](ERROR): Communications Fault 2022-04-21T20:24:24.837Z,1650572664.837 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-04-21T20:24:24.897Z,1650572664.897 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-04-21T20:24:24.897Z,1650572664.897 [RudderServo](INFO): Powering down 2022-04-21T20:24:25.682Z,1650572665.682 [RudderServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:24:25.801Z,1650572665.801 [RudderServo](DEBUG): Initializing RudderServo. 2022-04-21T20:24:25.805Z,1650572665.805 [CBIT](INFO): Clearing failed state for component RudderServo 2022-04-21T20:24:25.806Z,1650572665.806 [RudderServo] No Fault, FailCount= 2 2022-04-21T20:24:26.804Z,1650572666.804 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-04-21T20:24:26.804Z,1650572666.804 [RudderServo](FAULT): Rudder failed to initialize 2022-04-21T20:24:26.804Z,1650572666.804 [RudderServo] Communications Fault, FailCount= 3 2022-04-21T20:24:26.804Z,1650572666.804 [RudderServo](ERROR): Communications Fault 2022-04-21T20:24:26.805Z,1650572666.805 [SBIT](ERROR): Could not read rudderAngleReader_. 2022-04-21T20:24:26.809Z,1650572666.809 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-04-21T20:24:26.809Z,1650572666.809 [CBIT](CRITICAL): Communications Fault in component: RudderServo 2022-04-21T20:24:26.895Z,1650572666.895 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-04-21T20:24:26.895Z,1650572666.895 [RudderServo](INFO): Powering down 2022-04-21T20:24:26.899Z,1650572666.899 [CBIT](INFO): Critical error at 20220421T202426 2022-04-21T20:24:27.720Z,1650572667.720 [RudderServo](DEBUG): Initializing EZServoServo. 2022-04-21T20:24:27.841Z,1650572667.841 [RudderServo](DEBUG): Initializing RudderServo. 2022-04-21T20:24:53.147Z,1650572693.147 [SBIT](ERROR): Could not read rudderAngleReader_. 2022-04-21T20:24:53.147Z,1650572693.147 [SBIT](FAULT): Control surface position failure. 2022-04-21T20:25:06.966Z,1650572706.966 [SBIT](ERROR): Could not read rudderAngleReader_. 2022-04-21T20:25:06.966Z,1650572706.966 [SBIT](FAULT): Control surface position failure. 2022-04-21T20:25:07.353Z,1650572707.353 [SBIT](CRITICAL): SBIT FAILED 2022-04-21T20:25:07.353Z,1650572707.353 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-04-21T20:25:07.354Z,1650572707.354 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=32 count; 2022-04-21T20:25:07.354Z,1650572707.354 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 hour; 2022-04-21T20:25:07.354Z,1650572707.354 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool; 2022-04-21T20:25:07.354Z,1650572707.354 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): CBIT.gf24Offset=-18.106 microampere; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2022-04-21T20:25:07.355Z,1650572707.355 [SBIT](IMPORTANT): HorizontalControl.rudDeadband=0.001 degree; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): PAR_Licor.parCal=1.38207 none; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): RudderServo.deviationAngle=0.1 degree; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=255.152614 cubic_centimeter; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.001 degree; 2022-04-21T20:25:07.356Z,1650572707.356 [SBIT](IMPORTANT): VerticalControl.massDefault=8.108730 millimeter; 2022-04-21T20:25:07.357Z,1650572707.357 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool; 2022-04-21T20:25:07.357Z,1650572707.357 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool; 2022-04-21T20:25:07.361Z,1650572707.361 [CBIT](INFO): Critical error at 20220421T202507 2022-04-21T20:25:07.693Z,1650572707.693 [MissionManager](IMPORTANT): Started mission Startup 2022-04-21T20:25:07.693Z,1650572707.693 [Startup] Running Loop=1 2022-04-21T20:25:07.693Z,1650572707.693 [Startup](DEBUG): Aggregate::initialize Startup 2022-04-21T20:25:07.694Z,1650572707.694 [Startup:A.GoToSurface] Running Loop=1 2022-04-21T20:25:07.694Z,1650572707.694 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-04-21T20:25:07.694Z,1650572707.694 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-04-21T20:25:07.695Z,1650572707.695 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-04-21T20:25:07.695Z,1650572707.695 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-04-21T20:25:07.695Z,1650572707.695 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-04-21T20:25:07.696Z,1650572707.696 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-04-21T20:25:07.697Z,1650572707.697 [Startup:StartupSatComms] Running Loop=1 2022-04-21T20:25:07.697Z,1650572707.697 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-04-21T20:25:07.698Z,1650572707.698 [Startup:StartupSatComms:A] Running Loop=1 2022-04-21T20:25:08.154Z,1650572708.154 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-04-21T20:25:46.770Z,1650572746.770 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004985 2022-04-21T20:26:01.024Z,1650572761.024 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 2. 2022-04-21T20:26:01.027Z,1650572761.027 [BPC1](INFO): Received data from all battery sticks. 2022-04-21T20:26:07.890Z,1650572767.890 [Startup:StartupSatComms:A](INFO): Timed out from 2022-04-21T20:25:07.7Z 2022-04-21T20:26:07.890Z,1650572767.890 [Startup:StartupSatComms:A] Stopped 2022-04-21T20:26:07.890Z,1650572767.890 [Startup:StartupSatComms:B] Running Loop=1 2022-04-21T20:26:08.285Z,1650572768.285 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-04-21T20:26:15.121Z,1650572775.121 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220420T203659/Courier0046.lzma 2022-04-21T20:26:16.122Z,1650572776.122 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Courier0046.lzma.bak 2022-04-21T20:26:16.122Z,1650572776.122 [DataOverHttps](INFO): SBD MOMSN=16654039 2022-04-21T20:26:20.851Z,1650572780.851 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-04-21T20:26:20.851Z,1650572780.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-04-21T20:26:20.865Z,1650572780.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-04-21T20:26:21.282Z,1650572781.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-04-21T20:26:21.282Z,1650572781.282 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-04-21T20:26:31.443Z,1650572791.443 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220421T201801/Courier0000.lzma 2022-04-21T20:26:32.446Z,1650572792.446 [DataOverHttps](INFO): Moved sent file to Logs/20220421T201801/Courier0000.lzma.bak 2022-04-21T20:26:32.446Z,1650572792.446 [DataOverHttps](INFO): SBD MOMSN=16654041 2022-04-21T20:26:46.968Z,1650572806.968 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20220421T202308/Courier0000.lzma 2022-04-21T20:26:48.340Z,1650572808.340 [DataOverHttps](INFO): Moved sent file to Logs/20220421T202308/Courier0000.lzma.bak 2022-04-21T20:26:48.340Z,1650572808.340 [DataOverHttps](INFO): SBD MOMSN=16654046 2022-04-21T20:27:03.769Z,1650572823.769 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20220420T203659/Express0047.lzma 2022-04-21T20:27:04.770Z,1650572824.770 [DataOverHttps](INFO): Moved sent file to Logs/20220420T203659/Express0047.lzma.bak 2022-04-21T20:27:04.770Z,1650572824.770 [DataOverHttps](INFO): SBD MOMSN=16654057 2022-04-21T20:27:08.092Z,1650572828.092 [Startup:StartupSatComms:B](INFO): Timed out from 2022-04-21T20:26:07.9Z 2022-04-21T20:27:08.092Z,1650572828.092 [Startup:StartupSatComms:B] Stopped 2022-04-21T20:27:08.092Z,1650572828.092 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-04-21T20:27:08.092Z,1650572828.092 [Startup:StartupSatComms] Stopped 2022-04-21T20:27:08.092Z,1650572828.092 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-04-21T20:27:08.093Z,1650572828.093 [Startup](INFO): Completed Startup 2022-04-21T20:27:08.093Z,1650572828.093 [MissionManager](INFO): Startup is completed. 2022-04-21T20:27:08.093Z,1650572828.093 [MissionManager](INFO): Uninitializing Mission Startup 2022-04-21T20:27:08.094Z,1650572828.094 [Startup] Stopped 2022-04-21T20:27:08.094Z,1650572828.094 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-04-21T20:27:08.094Z,1650572828.094 [Startup:A.GoToSurface] Stopped 2022-04-21T20:27:08.094Z,1650572828.094 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-04-21T20:27:08.498Z,1650572828.498 [MissionManager](IMPORTANT): Started mission Default 2022-04-21T20:27:08.498Z,1650572828.498 [Default] Running Loop=1 2022-04-21T20:27:08.498Z,1650572828.498 [Default](DEBUG): Aggregate::initialize Default 2022-04-21T20:27:08.498Z,1650572828.498 [Default:B.GoToSurface] Running Loop=1 2022-04-21T20:27:08.498Z,1650572828.498 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-04-21T20:27:08.499Z,1650572828.499 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-04-21T20:27:08.499Z,1650572828.499 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-04-21T20:27:08.499Z,1650572828.499 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-04-21T20:27:08.500Z,1650572828.500 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-04-21T20:27:08.500Z,1650572828.500 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-04-21T20:27:08.500Z,1650572828.500 [Default:A.Wait] Running Loop=1 2022-04-21T20:27:08.500Z,1650572828.500 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-04-21T20:27:21.535Z,1650572841.535 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220421T201801/Express0001.lzma 2022-04-21T20:27:21.826Z,1650572841.826 [Default:A.Wait](INFO): Done Waiting. 2022-04-21T20:27:21.826Z,1650572841.826 [Default:A.Wait] Stopped 2022-04-21T20:27:21.826Z,1650572841.826 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-04-21T20:27:22.219Z,1650572842.219 [Default:CheckIn] Running Loop=1 2022-04-21T20:27:22.219Z,1650572842.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-04-21T20:27:22.219Z,1650572842.219 [Default:CheckIn:Read_GPS] Running Loop=1 2022-04-21T20:27:22.537Z,1650572842.537 [DataOverHttps](INFO): Moved sent file to Logs/20220421T201801/Express0001.lzma.bak 2022-04-21T20:27:22.538Z,1650572842.538 [DataOverHttps](INFO): SBD MOMSN=16654063 2022-04-21T20:27:22.639Z,1650572842.639 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-04-21T20:29:03.609Z,1650572943.609 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-04-21T20:29:21.817Z,1650572961.817 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-04-21T20:29:21.817Z,1650572961.817 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-04-21T20:29:21.827Z,1650572961.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-04-21T20:29:22.235Z,1650572962.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-04-21T20:29:22.235Z,1650572962.235 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-04-21T20:29:43.105Z,1650572983.105 [CommandExec](IMPORTANT): got command quit 2022-04-21T20:29:44.108Z,1650572984.108 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:44.108Z,1650572984.108 [CommandExec](INFO): Uninitializing the command executive. 2022-04-21T20:29:44.108Z,1650572984.108 [CommandExec](INFO): Uninitializing the command scheduler. 2022-04-21T20:29:44.108Z,1650572984.108 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:44.220Z,1650572984.220 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-04-21T20:29:44.220Z,1650572984.220 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-04-21T20:29:44.220Z,1650572984.220 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:44.221Z,1650572984.221 [NavChartDb](INFO): Join timeout helper Thread ID is 980 2022-04-21T20:29:44.621Z,1650572984.621 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:44.621Z,1650572984.621 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:44.624Z,1650572984.624 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-04-21T20:29:44.624Z,1650572984.624 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:44.624Z,1650572984.624 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 981 2022-04-21T20:29:44.824Z,1650572984.824 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:44.824Z,1650572984.824 [WetLabsBB2FL](INFO): Powering down 2022-04-21T20:29:44.825Z,1650572984.825 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:44.840Z,1650572984.840 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-04-21T20:29:44.840Z,1650572984.840 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:44.840Z,1650572984.840 [CTD_Seabird](INFO): Join timeout helper Thread ID is 982 2022-04-21T20:29:45.428Z,1650572985.428 [CTD_Seabird](INFO): Powering down 2022-04-21T20:29:45.440Z,1650572985.440 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:45.440Z,1650572985.440 [CTD_Seabird](INFO): Powering down 2022-04-21T20:29:45.452Z,1650572985.452 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:45.464Z,1650572985.464 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-04-21T20:29:45.464Z,1650572985.464 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:45.465Z,1650572985.465 [Radio_Surface](INFO): Join timeout helper Thread ID is 983 2022-04-21T20:29:45.832Z,1650572985.832 [Radio_Surface](INFO): Powering down 2022-04-21T20:29:45.833Z,1650572985.833 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:45.833Z,1650572985.833 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:45.836Z,1650572985.836 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-04-21T20:29:45.836Z,1650572985.836 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:45.836Z,1650572985.836 [Onboard](INFO): Join timeout helper Thread ID is 984 2022-04-21T20:29:45.972Z,1650572985.972 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:45.972Z,1650572985.972 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:45.977Z,1650572985.977 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-04-21T20:29:45.977Z,1650572985.977 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:45.977Z,1650572985.977 [DataOverHttps](INFO): Join timeout helper Thread ID is 985 2022-04-21T20:29:46.728Z,1650572986.728 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:46.728Z,1650572986.728 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.741Z,1650572986.741 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-04-21T20:29:46.741Z,1650572986.741 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.741Z,1650572986.741 [BackseatComponent](INFO): Join timeout helper Thread ID is 986 2022-04-21T20:29:46.785Z,1650572986.785 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:46.785Z,1650572986.785 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.801Z,1650572986.801 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-04-21T20:29:46.801Z,1650572986.801 [logger ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.801Z,1650572986.801 [logger](INFO): Join timeout helper Thread ID is 987 2022-04-21T20:29:46.821Z,1650572986.821 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:46.821Z,1650572986.821 [logger ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.825Z,1650572986.825 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-04-21T20:29:46.825Z,1650572986.825 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.825Z,1650572986.825 [CommandLine](INFO): Join timeout helper Thread ID is 988 2022-04-21T20:29:46.856Z,1650572986.856 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:46.856Z,1650572986.856 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.865Z,1650572986.865 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-04-21T20:29:46.865Z,1650572986.865 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.865Z,1650572986.865 [CommandExec](INFO): Join timeout helper Thread ID is 989 2022-04-21T20:29:46.877Z,1650572986.877 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-04-21T20:29:46.877Z,1650572986.877 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:46.878Z,1650572986.878 [controlThread](INFO): Join timeout helper Thread ID is 990 2022-04-21T20:29:47.232Z,1650572987.232 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-04-21T20:29:47.232Z,1650572987.232 [controlThread](DEBUG): Uninitializing ControlThread 2022-04-21T20:29:47.233Z,1650572987.233 [AHRS_M2](INFO): Powering down 2022-04-21T20:29:47.305Z,1650572987.305 [NAL9602](INFO): Powering down 2022-04-21T20:29:47.306Z,1650572987.306 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-04-21T20:29:47.307Z,1650572987.307 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-04-21T20:29:47.309Z,1650572987.309 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-04-21T20:29:47.309Z,1650572987.309 [MissionManager](INFO): Uninitializing Mission Default 2022-04-21T20:29:47.309Z,1650572987.309 [Default] Stopped 2022-04-21T20:29:47.309Z,1650572987.309 [Default](DEBUG): Aggregate::uninitialize Default 2022-04-21T20:29:47.309Z,1650572987.309 [Default:B.GoToSurface] Stopped 2022-04-21T20:29:47.310Z,1650572987.310 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-04-21T20:29:47.310Z,1650572987.310 [Default:CheckIn] Stopped 2022-04-21T20:29:47.310Z,1650572987.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-04-21T20:29:47.310Z,1650572987.310 [Default:CheckIn:Read_GPS] Stopped 2022-04-21T20:29:47.313Z,1650572987.313 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-04-21T20:29:47.313Z,1650572987.313 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-04-21T20:29:47.313Z,1650572987.313 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-04-21T20:29:47.313Z,1650572987.313 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-04-21T20:29:47.314Z,1650572987.314 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-04-21T20:29:47.314Z,1650572987.314 [BuoyancyServo](INFO): Powering down 2022-04-21T20:29:47.328Z,1650572987.328 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-04-21T20:29:47.328Z,1650572987.328 [ElevatorServo](INFO): Powering down 2022-04-21T20:29:47.329Z,1650572987.329 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-04-21T20:29:47.329Z,1650572987.329 [MassServo](INFO): Powering down 2022-04-21T20:29:47.330Z,1650572987.330 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-04-21T20:29:47.330Z,1650572987.330 [RudderServo](INFO): Powering down 2022-04-21T20:29:47.331Z,1650572987.331 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2022-04-21T20:29:47.331Z,1650572987.331 [ThrusterHE](INFO): Powering down 2022-04-21T20:29:47.332Z,1650572987.332 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-04-21T20:29:47.332Z,1650572987.332 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-04-21T20:29:47.332Z,1650572987.332 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-04-21T20:29:47.333Z,1650572987.333 [CBIT](DEBUG): Powering off loads. 2022-04-21T20:29:47.344Z,1650572987.344 [CBIT](DEBUG): Disabling WDT. 2022-04-21T20:29:47.356Z,1650572987.356 [CBIT](DEBUG): Opening all GF detection circuits. 2022-04-21T20:29:47.356Z,1650572987.356 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.513Z,1650572987.513 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.516Z,1650572987.516 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.522Z,1650572987.522 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.566Z,1650572987.566 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.601Z,1650572987.601 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.604Z,1650572987.604 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.636Z,1650572987.636 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-04-21T20:29:47.701Z,1650572987.701 [logger ThreadHandler](INFO): Thread cancelled.