2024-04-11T00:33:26.229Z,1712795606.229 [Supervisor](DEBUG): Initializing supervisor. 2024-04-11T00:33:26.234Z,1712795606.234 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-11T00:33:26.234Z,1712795606.234 [SyncHandler](INFO): Protected caller Thread ID is 9843 2024-04-11T00:33:26.234Z,1712795606.234 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-11T00:33:26.235Z,1712795606.235 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-11T00:33:26.236Z,1712795606.236 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9844 2024-04-11T00:33:26.240Z,1712795606.240 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-11T00:33:26.258Z,1712795606.258 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-11T00:33:26.259Z,1712795606.259 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-11T00:33:26.259Z,1712795606.259 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9845 2024-04-11T00:33:26.263Z,1712795606.263 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-11T00:33:26.264Z,1712795606.264 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-11T00:33:26.265Z,1712795606.265 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9846 2024-04-11T00:33:26.267Z,1712795606.267 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-11T00:33:26.268Z,1712795606.268 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-11T00:33:26.268Z,1712795606.268 [logger ThreadHandler](INFO): Protected caller Thread ID is 9847 2024-04-11T00:33:26.272Z,1712795606.272 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-11T00:33:26.272Z,1712795606.272 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-11T00:33:26.273Z,1712795606.273 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-11T00:33:26.552Z,1712795606.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-11T00:33:26.552Z,1712795606.552 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-11T00:33:26.646Z,1712795606.646 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-11T00:33:27.242Z,1712795607.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-11T00:33:27.242Z,1712795607.242 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-11T00:33:27.728Z,1712795607.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-11T00:33:27.729Z,1712795607.729 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-11T00:33:27.837Z,1712795607.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-11T00:33:27.838Z,1712795607.838 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-11T00:33:28.259Z,1712795608.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-11T00:33:28.260Z,1712795608.260 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-11T00:33:28.488Z,1712795608.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-11T00:33:28.489Z,1712795608.489 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-11T00:33:29.022Z,1712795609.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-11T00:33:29.023Z,1712795609.023 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-11T00:33:29.170Z,1712795609.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-11T00:33:29.171Z,1712795609.171 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-11T00:33:29.262Z,1712795609.262 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-11T00:33:30.095Z,1712795610.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-11T00:33:30.096Z,1712795610.096 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-11T00:33:30.489Z,1712795610.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-11T00:33:30.489Z,1712795610.489 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-11T00:33:30.698Z,1712795610.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-11T00:33:30.698Z,1712795610.698 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-11T00:33:30.822Z,1712795610.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-11T00:33:30.823Z,1712795610.823 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-11T00:33:31.064Z,1712795611.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-11T00:33:31.065Z,1712795611.065 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-11T00:33:31.329Z,1712795611.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-11T00:33:31.331Z,1712795611.331 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-04-11T00:33:31.332Z,1712795611.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-04-11T00:33:31.435Z,1712795611.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-04-11T00:33:31.533Z,1712795611.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-04-11T00:33:31.647Z,1712795611.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-04-11T00:33:31.743Z,1712795611.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-04-11T00:33:31.859Z,1712795611.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-04-11T00:33:31.964Z,1712795611.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-04-11T00:33:32.089Z,1712795612.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-04-11T00:33:32.258Z,1712795612.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-04-11T00:33:32.619Z,1712795612.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-04-11T00:33:32.887Z,1712795612.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-04-11T00:33:33.461Z,1712795613.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-11T00:33:33.461Z,1712795613.461 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-04-11T00:33:33.467Z,1712795613.467 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-11T00:33:33.522Z,1712795613.522 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-11T00:33:33.581Z,1712795613.581 [VerticalControl] Loaded 2024-04-11T00:33:33.581Z,1712795613.581 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-11T00:33:33.584Z,1712795613.584 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-11T00:33:33.626Z,1712795613.626 [HorizontalControl] Loaded 2024-04-11T00:33:33.626Z,1712795613.626 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-11T00:33:33.628Z,1712795613.628 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-11T00:33:33.631Z,1712795613.631 [SpeedControl] Loaded 2024-04-11T00:33:33.631Z,1712795613.631 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-11T00:33:33.634Z,1712795613.634 [LoopControl](DEBUG): Construct LoopControl. 2024-04-11T00:33:33.634Z,1712795613.634 [LoopControl] Loaded 2024-04-11T00:33:33.635Z,1712795613.635 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-11T00:33:33.635Z,1712795613.635 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-11T00:33:33.636Z,1712795613.636 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-11T00:33:33.646Z,1712795613.646 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-11T00:33:33.647Z,1712795613.647 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-11T00:33:33.822Z,1712795613.822 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-11T00:33:33.823Z,1712795613.823 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-11T00:33:33.978Z,1712795613.978 [BuoyancyServo] Loaded 2024-04-11T00:33:33.979Z,1712795613.979 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-11T00:33:33.998Z,1712795613.998 [ElevatorServo] Loaded 2024-04-11T00:33:33.998Z,1712795613.998 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-11T00:33:34.017Z,1712795614.017 [MassServo] Loaded 2024-04-11T00:33:34.017Z,1712795614.017 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-11T00:33:34.035Z,1712795614.035 [RudderServo] Loaded 2024-04-11T00:33:34.035Z,1712795614.035 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-11T00:33:34.049Z,1712795614.049 [ThrusterHE] Loaded 2024-04-11T00:33:34.050Z,1712795614.050 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-11T00:33:34.050Z,1712795614.050 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-11T00:33:34.050Z,1712795614.050 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-11T00:33:34.163Z,1712795614.163 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-11T00:33:34.164Z,1712795614.164 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-11T00:33:34.177Z,1712795614.177 [NavChart] Loaded 2024-04-11T00:33:34.177Z,1712795614.177 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-11T00:33:34.183Z,1712795614.183 [UniversalFixResidualReporter] Loaded 2024-04-11T00:33:34.183Z,1712795614.183 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-11T00:33:34.184Z,1712795614.184 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-11T00:33:34.184Z,1712795614.184 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-11T00:33:34.241Z,1712795614.241 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-11T00:33:34.241Z,1712795614.241 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-11T00:33:34.682Z,1712795614.682 [AHRS_M2] Loaded 2024-04-11T00:33:34.682Z,1712795614.682 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-11T00:33:35.483Z,1712795615.483 [BPC1] Loaded 2024-04-11T00:33:35.483Z,1712795615.483 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-11T00:33:35.558Z,1712795615.558 [DataOverHttps] Loaded 2024-04-11T00:33:35.558Z,1712795615.558 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-11T00:33:35.559Z,1712795615.559 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409304E0 2024-04-11T00:33:35.559Z,1712795615.559 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9929 2024-04-11T00:33:35.580Z,1712795615.580 [Depth_Keller] Loaded 2024-04-11T00:33:35.580Z,1712795615.580 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-11T00:33:35.585Z,1712795615.585 [DropWeight] Loaded 2024-04-11T00:33:35.586Z,1712795615.586 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-11T00:33:35.648Z,1712795615.648 [NAL9602] Loaded 2024-04-11T00:33:35.648Z,1712795615.648 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-11T00:33:35.677Z,1712795615.677 [Onboard] Loaded 2024-04-11T00:33:35.677Z,1712795615.677 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-11T00:33:35.678Z,1712795615.678 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409604E0 2024-04-11T00:33:35.678Z,1712795615.678 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9930 2024-04-11T00:33:35.692Z,1712795615.692 [Power24vConverter] Loaded 2024-04-11T00:33:35.692Z,1712795615.692 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-11T00:33:35.705Z,1712795615.705 [Radio_Surface] Loaded 2024-04-11T00:33:35.705Z,1712795615.705 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-11T00:33:35.706Z,1712795615.706 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409904E0 2024-04-11T00:33:35.706Z,1712795615.706 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9931 2024-04-11T00:33:35.720Z,1712795615.720 [Sonardyne_Nano] Loaded 2024-04-11T00:33:35.720Z,1712795615.720 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-04-11T00:33:35.811Z,1712795615.811 [DAT] Loaded 2024-04-11T00:33:35.812Z,1712795615.812 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-11T00:33:35.813Z,1712795615.813 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0 2024-04-11T00:33:35.813Z,1712795615.813 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9932 2024-04-11T00:33:35.813Z,1712795615.813 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-11T00:33:35.814Z,1712795615.814 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-11T00:33:35.866Z,1712795615.866 [DepthRateCalculator] Loaded 2024-04-11T00:33:35.867Z,1712795615.867 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-11T00:33:35.872Z,1712795615.872 [PitchRateCalculator] Loaded 2024-04-11T00:33:35.872Z,1712795615.872 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-11T00:33:35.880Z,1712795615.880 [SpeedCalculator] Loaded 2024-04-11T00:33:35.881Z,1712795615.881 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-11T00:33:35.885Z,1712795615.885 [YawRateCalculator] Loaded 2024-04-11T00:33:35.885Z,1712795615.885 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-11T00:33:35.904Z,1712795615.904 [ElevatorOffsetCalculator] Loaded 2024-04-11T00:33:35.904Z,1712795615.904 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-11T00:33:35.905Z,1712795615.905 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-11T00:33:35.905Z,1712795615.905 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-11T00:33:36.041Z,1712795616.041 [CANONSampler] Loaded 2024-04-11T00:33:36.041Z,1712795616.041 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-04-11T00:33:36.101Z,1712795616.101 [CTD_Seabird] Loaded 2024-04-11T00:33:36.101Z,1712795616.101 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-11T00:33:36.102Z,1712795616.102 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0 2024-04-11T00:33:36.103Z,1712795616.103 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9933 2024-04-11T00:33:36.122Z,1712795616.122 [PAR_Licor] Loaded 2024-04-11T00:33:36.123Z,1712795616.123 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-11T00:33:36.166Z,1712795616.166 [WetLabsBB2FL] Loaded 2024-04-11T00:33:36.166Z,1712795616.166 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-11T00:33:36.167Z,1712795616.167 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAC4E0 2024-04-11T00:33:36.168Z,1712795616.168 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9934 2024-04-11T00:33:36.168Z,1712795616.168 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-11T00:33:36.169Z,1712795616.169 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-11T00:33:36.196Z,1712795616.196 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-11T00:33:36.197Z,1712795616.197 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-11T00:33:36.538Z,1712795616.538 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-11T00:33:36.539Z,1712795616.539 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-11T00:33:36.682Z,1712795616.682 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-11T00:33:36.691Z,1712795616.691 [SBIT] Loaded 2024-04-11T00:33:36.692Z,1712795616.692 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-11T00:33:36.695Z,1712795616.695 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-11T00:33:36.707Z,1712795616.707 [IBIT] Loaded 2024-04-11T00:33:36.707Z,1712795616.707 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-11T00:33:36.713Z,1712795616.713 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-11T00:33:36.817Z,1712795616.817 [CBIT] Loaded 2024-04-11T00:33:36.817Z,1712795616.817 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-11T00:33:36.818Z,1712795616.818 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-11T00:33:36.818Z,1712795616.818 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-11T00:33:36.934Z,1712795616.934 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-11T00:33:36.941Z,1712795616.941 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-11T00:33:36.944Z,1712795616.944 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-11T00:33:36.955Z,1712795616.955 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-11T00:33:36.956Z,1712795616.956 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2024-04-11T00:33:36.956Z,1712795616.956 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9935 2024-04-11T00:33:36.961Z,1712795616.961 [Supervisor](INFO): Main Thread ID is 9529 2024-04-11T00:33:36.961Z,1712795616.961 [Supervisor](DEBUG): Running supervisor. 2024-04-11T00:33:36.962Z,1712795616.962 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9936 2024-04-11T00:33:36.962Z,1712795616.962 [CommandExec](INFO): Initializing the command executive. 2024-04-11T00:33:36.964Z,1712795616.964 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9937 2024-04-11T00:33:36.966Z,1712795616.966 [controlThread ThreadHandler](INFO): Handler Thread ID is 9938 2024-04-11T00:33:36.967Z,1712795616.967 [controlThread](DEBUG): Initializing ControlThread 2024-04-11T00:33:36.968Z,1712795616.968 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-11T00:33:36.969Z,1712795616.969 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-11T00:33:36.970Z,1712795616.970 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-11T00:33:36.970Z,1712795616.970 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-11T00:33:36.972Z,1712795616.972 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-11T00:33:36.973Z,1712795616.973 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-11T00:33:36.977Z,1712795616.977 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-11T00:33:36.978Z,1712795616.978 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-11T00:33:36.978Z,1712795616.978 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-11T00:33:36.978Z,1712795616.978 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-11T00:33:36.979Z,1712795616.979 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-11T00:33:36.983Z,1712795616.983 [SBIT](INFO): Initialize SBIT Component. 2024-04-11T00:33:36.984Z,1712795616.984 [SBIT](IMPORTANT): git: 2024-03-26-44-g1514cc2c4 2024-04-11T00:33:36.984Z,1712795616.984 [SBIT](INFO): git hash: 1514cc2c4a99aff7d2a01b01e5397a9c6de228ef 2024-04-11T00:33:36.984Z,1712795616.984 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-11T00:33:36.985Z,1712795616.985 [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-04-11T00:33:36.986Z,1712795616.986 [SBIT](INFO): Beginning SBIT in 49.000000 seconds. 2024-04-11T00:33:36.987Z,1712795616.987 [IBIT](INFO): Initialize IBIT Component. 2024-04-11T00:33:36.988Z,1712795616.988 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-11T00:33:36.989Z,1712795616.989 [logger ThreadHandler](INFO): Handler Thread ID is 9939 2024-04-11T00:33:36.999Z,1712795616.999 [CBIT](DEBUG): Initialized mux pins. 2024-04-11T00:33:36.999Z,1712795616.999 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-11T00:33:37.008Z,1712795617.008 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9940 2024-04-11T00:33:37.009Z,1712795617.009 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-11T00:33:37.020Z,1712795617.020 [Onboard ThreadHandler](INFO): Handler Thread ID is 9941 2024-04-11T00:33:37.024Z,1712795617.024 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-11T00:33:37.024Z,1712795617.024 [CBIT](DEBUG): Initializing heartbeat. 2024-04-11T00:33:37.040Z,1712795617.040 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9942 2024-04-11T00:33:37.052Z,1712795617.052 [DAT ThreadHandler](INFO): Handler Thread ID is 9943 2024-04-11T00:33:37.052Z,1712795617.052 [DAT](INFO): Powering up 2024-04-11T00:33:37.053Z,1712795617.053 [DAT](DEBUG): Initializing DAT. 2024-04-11T00:33:37.057Z,1712795617.057 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9945 2024-04-11T00:33:37.057Z,1712795617.057 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-11T00:33:37.061Z,1712795617.061 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9947 2024-04-11T00:33:37.064Z,1712795617.064 [WetLabsBB2FL](INFO): Powering up 2024-04-11T00:33:37.065Z,1712795617.065 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9949 2024-04-11T00:33:37.069Z,1712795617.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-11T00:33:37.069Z,1712795617.069 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-11T00:33:37.070Z,1712795617.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-11T00:33:37.070Z,1712795617.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-11T00:33:37.070Z,1712795617.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-11T00:33:37.070Z,1712795617.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-11T00:33:37.070Z,1712795617.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-11T00:33:37.070Z,1712795617.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-11T00:33:37.095Z,1712795617.095 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-11T00:33:37.095Z,1712795617.095 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-11T00:33:37.135Z,1712795617.135 [CBIT](DEBUG): Backplane powered. 2024-04-11T00:33:37.136Z,1712795617.136 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-04-11T00:33:37.137Z,1712795617.137 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-11T00:33:37.137Z,1712795617.137 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-04-11T00:33:37.138Z,1712795617.138 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-04-11T00:33:37.180Z,1712795617.180 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-04-11T00:33:37.201Z,1712795617.201 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-11T00:33:37.221Z,1712795617.221 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-04-11T00:33:37.222Z,1712795617.222 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-04-11T00:33:37.222Z,1712795617.222 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-04-11T00:33:37.247Z,1712795617.247 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-04-11T00:33:37.443Z,1712795617.443 [Radio_Surface](INFO): Powering up 2024-04-11T00:33:37.677Z,1712795617.677 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-04-11T00:33:37.682Z,1712795617.682 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-11T00:33:37.700Z,1712795617.700 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-11T00:33:37.702Z,1712795617.702 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-11T00:33:37.801Z,1712795617.801 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-11T00:33:37.835Z,1712795617.835 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-11T00:33:37.841Z,1712795617.841 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-11T00:33:37.848Z,1712795617.848 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-04-11T00:33:37.853Z,1712795617.853 [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-04-11T00:33:37.876Z,1712795617.876 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-11T00:33:37.946Z,1712795617.946 [Power24vConverter](INFO): Powering up. 2024-04-11T00:33:37.946Z,1712795617.946 [Sonardyne_Nano](INFO): Initializing. 2024-04-11T00:33:37.963Z,1712795617.963 [CANONSampler](INFO): Powering down 2024-04-11T00:33:38.001Z,1712795618.001 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-11T00:33:38.007Z,1712795618.007 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-11T00:33:38.009Z,1712795618.009 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-11T00:33:38.016Z,1712795618.016 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-11T00:33:38.017Z,1712795618.017 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-11T00:33:38.023Z,1712795618.023 [MassServo](DEBUG): Initializing MassServo. 2024-04-11T00:33:38.024Z,1712795618.024 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-11T00:33:38.031Z,1712795618.031 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-11T00:33:38.032Z,1712795618.032 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-11T00:33:38.040Z,1712795618.040 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-11T00:33:39.172Z,1712795619.172 [WetLabsBB2FL](INFO): Powering down 2024-04-11T00:33:39.882Z,1712795619.882 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-04-11T00:33:41.528Z,1712795621.528 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2024-04-11T00:33:41.931Z,1712795621.931 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging 2024-04-11T00:33:43.756Z,1712795623.756 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-11T00:33:49.652Z,1712795629.652 [DAT](INFO): DAT read: 2024-04-11T00:33:49.653Z,1712795629.653 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-11T00:33:51.416Z,1712795631.416 [DAT](INFO): DAT read: MF Frequency Band 2024-04-11T00:33:51.417Z,1712795631.417 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-11T00:33:51.418Z,1712795631.418 [DAT](INFO): DAT read: Apr 11 2024 00:33:45 2024-04-11T00:33:52.676Z,1712795632.676 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-11T00:33:52.677Z,1712795632.677 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-04-11T00:33:52.678Z,1712795632.678 [DAT](INFO): commRate: 1200 2024-04-11T00:33:54.743Z,1712795634.743 [DAT](INFO): entering command mode 2024-04-11T00:33:54.944Z,1712795634.944 [DAT](INFO): DAT read: 2024-04-11T00:33:54.944Z,1712795634.944 [DAT](INFO): DAT read: user:1> 2024-04-11T00:33:54.945Z,1712795634.945 [DAT](INFO): setting verbose to 3 2024-04-11T00:33:55.196Z,1712795635.196 [DAT](INFO): DAT read: user:1> 2024-04-11T00:33:55.197Z,1712795635.197 [DAT](INFO): DAT read: Verbose | 3 2024-04-11T00:33:55.197Z,1712795635.197 [DAT](INFO): set verbose to 3 2024-04-11T00:33:55.198Z,1712795635.198 [DAT](INFO): setting DatVerbose to 27440 2024-04-11T00:33:55.448Z,1712795635.448 [DAT](INFO): DAT read: user:2> 2024-04-11T00:33:55.449Z,1712795635.449 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-11T00:33:55.449Z,1712795635.449 [DAT](INFO): set DatVerbose to 27440 2024-04-11T00:33:55.450Z,1712795635.450 [DAT](INFO): setting transmit power to 8 2024-04-11T00:33:55.702Z,1712795635.702 [DAT](INFO): DAT read: user:3> 2024-04-11T00:33:55.703Z,1712795635.703 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-11T00:33:55.703Z,1712795635.703 [DAT](INFO): set transmit power to 8 2024-04-11T00:33:55.703Z,1712795635.703 [DAT](INFO): setting local address to 8 2024-04-11T00:33:55.952Z,1712795635.952 [DAT](INFO): DAT read: user:4> 2024-04-11T00:33:55.953Z,1712795635.953 [DAT](INFO): DAT read: LocalAddr | 8 2024-04-11T00:33:55.953Z,1712795635.953 [DAT](INFO): set local address to 8 2024-04-11T00:33:55.954Z,1712795635.954 [DAT](INFO): Setting time to: 0:33:55 And date to:4/11/2024 2024-04-11T00:33:56.204Z,1712795636.204 [DAT](INFO): DAT read: user:5> 2024-04-11T00:33:56.205Z,1712795636.205 [DAT](INFO): DAT read: Thu Apr 11, 2024 00:33:55 2024-04-11T00:33:56.205Z,1712795636.205 [DAT](INFO): Local DAT time set to Thu Apr 11, 2024 00:33:55 2024-04-11T00:34:05.741Z,1712795645.741 [NAL9602](INFO): Powering up NAL9602 2024-04-11T00:34:16.661Z,1712795656.661 [NAL9602](INFO): NAL9602 initialized 2024-04-11T00:34:26.373Z,1712795666.373 [SBIT](IMPORTANT): Beginning Startup BIT 2024-04-11T00:34:26.382Z,1712795666.382 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-11T00:34:37.678Z,1712795677.678 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-04-11T00:34:38.086Z,1712795678.086 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-11T00:34:44.946Z,1712795684.946 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:34:49.068Z,1712795689.068 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-04-11T00:34:49.476Z,1712795689.476 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-11T00:35:00.370Z,1712795700.370 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-04-11T00:35:00.370Z,1712795700.370 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-04-11T00:35:02.299Z,1712795702.299 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:35:19.669Z,1712795719.669 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:35:20.513Z,1712795720.513 [SBIT](IMPORTANT): SBIT PASSED 2024-04-11T00:35:20.514Z,1712795720.514 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-04-11T00:35:20.514Z,1712795720.514 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-04-11T00:35:20.514Z,1712795720.514 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool; 2024-04-11T00:35:20.515Z,1712795720.515 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=186 cubic_centimeter; 2024-04-11T00:35:20.515Z,1712795720.515 [SBIT](IMPORTANT): VerticalControl.massDefault=7.5 millimeter; 2024-04-11T00:35:20.898Z,1712795720.898 [MissionManager](IMPORTANT): Started mission Startup 2024-04-11T00:35:20.898Z,1712795720.898 [Startup] Running Loop=1 2024-04-11T00:35:20.898Z,1712795720.898 [Startup](DEBUG): Aggregate::initialize Startup 2024-04-11T00:35:20.898Z,1712795720.898 [Startup:A.GoToSurface] Running Loop=1 2024-04-11T00:35:20.898Z,1712795720.898 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-11T00:35:20.899Z,1712795720.899 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-11T00:35:20.900Z,1712795720.900 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-11T00:35:20.900Z,1712795720.900 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-11T00:35:20.901Z,1712795720.901 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-11T00:35:20.901Z,1712795720.901 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-11T00:35:20.903Z,1712795720.903 [Startup:StartupSatComms] Running Loop=1 2024-04-11T00:35:20.903Z,1712795720.903 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-04-11T00:35:20.903Z,1712795720.903 [Startup:StartupSatComms:A] Running Loop=1 2024-04-11T00:35:21.301Z,1712795721.301 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-04-11T00:35:37.041Z,1712795737.041 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:35:54.428Z,1712795754.428 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:36:11.381Z,1712795771.381 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:36:19.909Z,1712795779.909 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004718 2024-04-11T00:36:21.097Z,1712795781.097 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-11T00:35:20.9Z 2024-04-11T00:36:21.097Z,1712795781.097 [Startup:StartupSatComms:A] Stopped 2024-04-11T00:36:21.097Z,1712795781.097 [Startup:StartupSatComms:B] Running Loop=1 2024-04-11T00:36:21.533Z,1712795781.533 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-11T00:36:28.263Z,1712795788.263 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20240411T001307/Courier0007.lzma 2024-04-11T00:36:28.349Z,1712795788.349 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:36:29.265Z,1712795789.265 [DataOverHttps](INFO): Moved sent file to Logs/20240411T001307/Courier0007.lzma.bak 2024-04-11T00:36:29.265Z,1712795789.265 [DataOverHttps](INFO): SBD MOMSN=19398177 2024-04-11T00:36:37.256Z,1712795797.256 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-04-11T00:36:37.256Z,1712795797.256 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-11T00:36:37.267Z,1712795797.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-11T00:36:37.673Z,1712795797.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-11T00:36:37.674Z,1712795797.674 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-04-11T00:36:44.895Z,1712795804.895 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240411T003326/Courier0000.lzma 2024-04-11T00:36:44.957Z,1712795804.957 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-04-11T00:36:45.897Z,1712795805.897 [DataOverHttps](INFO): Moved sent file to Logs/20240411T003326/Courier0000.lzma.bak 2024-04-11T00:36:45.897Z,1712795805.897 [DataOverHttps](INFO): SBD MOMSN=19398182 2024-04-11T00:37:01.427Z,1712795821.427 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20240411T001307/Express0008.lzma 2024-04-11T00:37:02.429Z,1712795822.429 [DataOverHttps](INFO): Moved sent file to Logs/20240411T001307/Express0008.lzma.bak 2024-04-11T00:37:02.429Z,1712795822.429 [DataOverHttps](INFO): SBD MOMSN=19398185 2024-04-11T00:37:15.741Z,1712795835.741 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-11T00:37:18.484Z,1712795838.484 [DataOverHttps](INFO): Sending 725 bytes from file Logs/20240411T003326/Express0001.lzma 2024-04-11T00:37:19.485Z,1712795839.485 [DataOverHttps](INFO): Moved sent file to Logs/20240411T003326/Express0001.lzma.bak 2024-04-11T00:37:19.485Z,1712795839.485 [DataOverHttps](INFO): SBD MOMSN=19398191 2024-04-11T00:37:20.888Z,1712795840.888 [Startup:StartupSatComms:B] Stopped 2024-04-11T00:37:20.888Z,1712795840.888 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-04-11T00:37:20.889Z,1712795840.889 [Startup:StartupSatComms] Stopped 2024-04-11T00:37:20.889Z,1712795840.889 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-04-11T00:37:20.890Z,1712795840.890 [Startup](INFO): Completed Startup 2024-04-11T00:37:20.890Z,1712795840.890 [MissionManager](INFO): Startup is completed. 2024-04-11T00:37:20.890Z,1712795840.890 [MissionManager](INFO): Uninitializing Mission Startup 2024-04-11T00:37:20.890Z,1712795840.890 [Startup] Stopped 2024-04-11T00:37:20.890Z,1712795840.890 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-04-11T00:37:20.890Z,1712795840.890 [Startup:A.GoToSurface] Stopped 2024-04-11T00:37:20.890Z,1712795840.890 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-11T00:37:21.292Z,1712795841.292 [MissionManager](IMPORTANT): Started mission Default 2024-04-11T00:37:21.292Z,1712795841.292 [Default] Running Loop=1 2024-04-11T00:37:21.293Z,1712795841.293 [Default](DEBUG): Aggregate::initialize Default 2024-04-11T00:37:21.293Z,1712795841.293 [Default:B.GoToSurface] Running Loop=1 2024-04-11T00:37:21.293Z,1712795841.293 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-11T00:37:21.293Z,1712795841.293 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-11T00:37:21.293Z,1712795841.293 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-11T00:37:21.294Z,1712795841.294 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-11T00:37:21.294Z,1712795841.294 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-11T00:37:21.294Z,1712795841.294 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-11T00:37:21.294Z,1712795841.294 [Default:A.Wait] Running Loop=1 2024-04-11T00:37:21.295Z,1712795841.295 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-11T00:37:34.621Z,1712795854.621 [Default:A.Wait](INFO): Done Waiting. 2024-04-11T00:37:34.621Z,1712795854.621 [Default:A.Wait] Stopped 2024-04-11T00:37:34.622Z,1712795854.622 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-11T00:37:35.035Z,1712795855.035 [Default:CheckIn] Running Loop=1 2024-04-11T00:37:35.035Z,1712795855.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-11T00:37:35.035Z,1712795855.035 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-11T00:37:35.430Z,1712795855.430 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-04-11T00:39:03.953Z,1712795943.953 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-04-11T00:39:20.057Z,1712795960.057 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-11T00:39:34.189Z,1712795974.189 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T00:39:34.196Z,1712795974.196 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-04-11T00:39:34.199Z,1712795974.199 [BPC1](INFO): Received data from all battery sticks. 2024-04-11T00:39:38.244Z,1712795978.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-04-11T00:39:38.244Z,1712795978.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-11T00:39:38.255Z,1712795978.255 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-11T00:39:38.667Z,1712795978.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-11T00:39:38.667Z,1712795978.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-04-11T00:42:35.218Z,1712796155.218 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-11T00:37:35.0Z 2024-04-11T00:42:35.218Z,1712796155.218 [Default:CheckIn:Read_GPS] Stopped 2024-04-11T00:42:35.218Z,1712796155.218 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-11T00:42:35.610Z,1712796155.610 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-11T00:42:39.329Z,1712796159.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-04-11T00:42:39.329Z,1712796159.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-11T00:42:39.381Z,1712796159.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-11T00:42:39.681Z,1712796159.681 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-11T00:42:39.681Z,1712796159.681 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-04-11T00:42:42.851Z,1712796162.851 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240411T003326/Courier0004.lzma 2024-04-11T00:42:43.853Z,1712796163.853 [DataOverHttps](INFO): Moved sent file to Logs/20240411T003326/Courier0004.lzma.bak 2024-04-11T00:42:43.853Z,1712796163.853 [DataOverHttps](INFO): SBD MOMSN=19398222 2024-04-11T00:42:59.364Z,1712796179.364 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240411T003326/Express0005.lzma 2024-04-11T00:43:00.365Z,1712796180.365 [DataOverHttps](INFO): Moved sent file to Logs/20240411T003326/Express0005.lzma.bak 2024-04-11T00:43:00.365Z,1712796180.365 [DataOverHttps](INFO): SBD MOMSN=19398224 2024-04-11T00:43:01.889Z,1712796181.889 [Default:CheckIn:Read_Iridium] Stopped 2024-04-11T00:43:01.889Z,1712796181.889 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-11T00:43:01.889Z,1712796181.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-11T00:44:17.416Z,1712796257.416 [CommandExec](IMPORTANT): got command quit 2024-04-11T00:44:18.419Z,1712796258.419 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-04-11T00:44:18.420Z,1712796258.420 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:18.420Z,1712796258.420 [CommandExec](INFO): Uninitializing the command executive. 2024-04-11T00:44:18.420Z,1712796258.420 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-11T00:44:18.420Z,1712796258.420 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:18.441Z,1712796258.441 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-11T00:44:18.441Z,1712796258.441 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-11T00:44:18.442Z,1712796258.442 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:18.442Z,1712796258.442 [NavChartDb](INFO): Join timeout helper Thread ID is 9972 2024-04-11T00:44:18.620Z,1712796258.620 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-11T00:44:18.620Z,1712796258.620 [NAL9602] Data Fault, FailCount= 1 2024-04-11T00:44:18.620Z,1712796258.620 [NAL9602](ERROR): Data Fault 2024-04-11T00:44:18.640Z,1712796258.640 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-11T00:44:18.800Z,1712796258.800 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:18.800Z,1712796258.800 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:18.811Z,1712796258.811 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-11T00:44:18.811Z,1712796258.811 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:18.812Z,1712796258.812 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9973 2024-04-11T00:44:18.859Z,1712796258.859 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:18.860Z,1712796258.860 [WetLabsBB2FL](INFO): Powering down 2024-04-11T00:44:18.860Z,1712796258.860 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:18.875Z,1712796258.875 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-11T00:44:18.875Z,1712796258.875 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:18.876Z,1712796258.876 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9974 2024-04-11T00:44:19.017Z,1712796259.017 [NAL9602](INFO): Powering down 2024-04-11T00:44:19.731Z,1712796259.731 [CTD_Seabird](INFO): Powering down 2024-04-11T00:44:19.743Z,1712796259.743 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:19.743Z,1712796259.743 [CTD_Seabird](INFO): Powering down 2024-04-11T00:44:19.756Z,1712796259.756 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:19.767Z,1712796259.767 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-11T00:44:19.768Z,1712796259.768 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:19.768Z,1712796259.768 [DAT](INFO): Join timeout helper Thread ID is 9975 2024-04-11T00:44:19.768Z,1712796259.768 [DAT](INFO): Powering down 2024-04-11T00:44:19.864Z,1712796259.864 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:19.864Z,1712796259.864 [DAT](INFO): Powering down 2024-04-11T00:44:19.865Z,1712796259.865 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:19.872Z,1712796259.872 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-11T00:44:19.875Z,1712796259.875 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-11T00:44:19.875Z,1712796259.875 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:19.876Z,1712796259.876 [Radio_Surface](INFO): Join timeout helper Thread ID is 9976 2024-04-11T00:44:19.876Z,1712796259.876 [NAL9602] No Fault, FailCount= 1 2024-04-11T00:44:19.939Z,1712796259.939 [Radio_Surface](INFO): Powering down 2024-04-11T00:44:19.940Z,1712796259.940 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:19.941Z,1712796259.941 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:19.944Z,1712796259.944 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-11T00:44:19.944Z,1712796259.944 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:19.945Z,1712796259.945 [Onboard](INFO): Join timeout helper Thread ID is 9977 2024-04-11T00:44:20.447Z,1712796260.447 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-11T00:44:22.595Z,1712796262.595 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:22.596Z,1712796262.596 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:22.612Z,1712796262.612 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-11T00:44:22.612Z,1712796262.612 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:22.612Z,1712796262.612 [DataOverHttps](INFO): Join timeout helper Thread ID is 9978 2024-04-11T00:44:23.459Z,1712796263.459 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:23.460Z,1712796263.460 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.476Z,1712796263.476 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-11T00:44:23.476Z,1712796263.476 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.476Z,1712796263.476 [logger](INFO): Join timeout helper Thread ID is 9979 2024-04-11T00:44:23.514Z,1712796263.514 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:23.514Z,1712796263.514 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.516Z,1712796263.516 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-11T00:44:23.516Z,1712796263.516 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.516Z,1712796263.516 [CommandLine](INFO): Join timeout helper Thread ID is 9980 2024-04-11T00:44:23.567Z,1712796263.567 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:23.568Z,1712796263.568 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.588Z,1712796263.588 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-11T00:44:23.588Z,1712796263.588 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.588Z,1712796263.588 [CommandExec](INFO): Join timeout helper Thread ID is 9981 2024-04-11T00:44:23.589Z,1712796263.589 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-11T00:44:23.589Z,1712796263.589 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:23.590Z,1712796263.590 [controlThread](INFO): Join timeout helper Thread ID is 9982 2024-04-11T00:44:23.851Z,1712796263.851 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T00:44:23.852Z,1712796263.852 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-11T00:44:23.852Z,1712796263.852 [AHRS_M2](INFO): Powering down 2024-04-11T00:44:23.924Z,1712796263.924 [NAL9602](INFO): Powering down 2024-04-11T00:44:23.925Z,1712796263.925 [Sonardyne_Nano](INFO): Powering down 2024-04-11T00:44:24.045Z,1712796264.045 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-11T00:44:24.045Z,1712796264.045 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-11T00:44:24.046Z,1712796264.046 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-11T00:44:24.046Z,1712796264.046 [MissionManager](INFO): Uninitializing Mission Default 2024-04-11T00:44:24.046Z,1712796264.046 [Default] Stopped 2024-04-11T00:44:24.047Z,1712796264.047 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-11T00:44:24.047Z,1712796264.047 [Default:B.GoToSurface] Stopped 2024-04-11T00:44:24.047Z,1712796264.047 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-11T00:44:24.047Z,1712796264.047 [Default:CheckIn] Stopped 2024-04-11T00:44:24.047Z,1712796264.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-11T00:44:24.047Z,1712796264.047 [Default:CheckIn:C.Wait] Stopped 2024-04-11T00:44:24.047Z,1712796264.047 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-11T00:44:24.050Z,1712796264.050 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-11T00:44:24.050Z,1712796264.050 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-11T00:44:24.051Z,1712796264.051 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-11T00:44:24.051Z,1712796264.051 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-11T00:44:24.051Z,1712796264.051 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-11T00:44:24.052Z,1712796264.052 [BuoyancyServo](INFO): Powering down 2024-04-11T00:44:24.063Z,1712796264.063 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-11T00:44:24.063Z,1712796264.063 [ElevatorServo](INFO): Powering down 2024-04-11T00:44:24.064Z,1712796264.064 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-11T00:44:24.064Z,1712796264.064 [MassServo](INFO): Powering down 2024-04-11T00:44:24.065Z,1712796264.065 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-11T00:44:24.065Z,1712796264.065 [RudderServo](INFO): Powering down 2024-04-11T00:44:24.066Z,1712796264.066 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-11T00:44:24.066Z,1712796264.066 [ThrusterHE](INFO): Powering down 2024-04-11T00:44:24.067Z,1712796264.067 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-11T00:44:24.067Z,1712796264.067 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-11T00:44:24.068Z,1712796264.068 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-11T00:44:24.068Z,1712796264.068 [CBIT](DEBUG): Powering off loads. 2024-04-11T00:44:24.079Z,1712796264.079 [CBIT](DEBUG): Disabling WDT. 2024-04-11T00:44:24.091Z,1712796264.091 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-11T00:44:24.092Z,1712796264.092 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.145Z,1712796264.145 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.151Z,1712796264.151 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.152Z,1712796264.152 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.158Z,1712796264.158 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.230Z,1712796264.230 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.234Z,1712796264.234 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.275Z,1712796264.275 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-11T00:44:24.339Z,1712796264.339 [logger ThreadHandler](INFO): Thread cancelled.