2024-08-20T23:18:39.012Z,1724195919.012 [Supervisor](DEBUG): Initializing supervisor. 2024-08-20T23:18:39.017Z,1724195919.017 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-08-20T23:18:39.017Z,1724195919.017 [SyncHandler](INFO): Protected caller Thread ID is 6759 2024-08-20T23:18:39.018Z,1724195919.018 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-08-20T23:18:39.019Z,1724195919.019 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-08-20T23:18:39.019Z,1724195919.019 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6760 2024-08-20T23:18:39.023Z,1724195919.023 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-08-20T23:18:39.040Z,1724195919.040 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-08-20T23:18:39.041Z,1724195919.041 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-08-20T23:18:39.041Z,1724195919.041 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6761 2024-08-20T23:18:39.045Z,1724195919.045 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-08-20T23:18:39.046Z,1724195919.046 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-08-20T23:18:39.046Z,1724195919.046 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6762 2024-08-20T23:18:39.048Z,1724195919.048 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-08-20T23:18:39.049Z,1724195919.049 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-08-20T23:18:39.050Z,1724195919.050 [logger ThreadHandler](INFO): Protected caller Thread ID is 6763 2024-08-20T23:18:39.053Z,1724195919.053 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-08-20T23:18:39.054Z,1724195919.053 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-08-20T23:18:39.055Z,1724195919.055 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-08-20T23:18:39.338Z,1724195919.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-08-20T23:18:39.339Z,1724195919.339 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-08-20T23:18:39.432Z,1724195919.432 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-08-20T23:18:39.995Z,1724195919.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-08-20T23:18:39.996Z,1724195919.996 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-08-20T23:18:40.367Z,1724195920.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-08-20T23:18:40.367Z,1724195920.367 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-08-20T23:18:40.476Z,1724195920.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-08-20T23:18:40.477Z,1724195920.477 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-08-20T23:18:40.908Z,1724195920.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-08-20T23:18:40.908Z,1724195920.908 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-08-20T23:18:41.142Z,1724195921.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-08-20T23:18:41.142Z,1724195921.142 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-08-20T23:18:41.721Z,1724195921.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-08-20T23:18:41.721Z,1724195921.721 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-08-20T23:18:41.920Z,1724195921.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-08-20T23:18:41.921Z,1724195921.921 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-08-20T23:18:42.012Z,1724195922.012 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-08-20T23:18:42.882Z,1724195922.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-08-20T23:18:42.882Z,1724195922.882 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-08-20T23:18:43.290Z,1724195923.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-08-20T23:18:43.291Z,1724195923.291 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-08-20T23:18:43.502Z,1724195923.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-08-20T23:18:43.502Z,1724195923.502 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-08-20T23:18:43.628Z,1724195923.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-08-20T23:18:43.629Z,1724195923.629 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-08-20T23:18:43.946Z,1724195923.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-08-20T23:18:43.946Z,1724195923.946 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-08-20T23:18:44.560Z,1724195924.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-08-20T23:18:44.562Z,1724195924.562 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-08-20T23:18:44.563Z,1724195924.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-08-20T23:18:44.667Z,1724195924.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-08-20T23:18:44.765Z,1724195924.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-08-20T23:18:44.881Z,1724195924.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-08-20T23:18:44.977Z,1724195924.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-08-20T23:18:45.093Z,1724195925.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-08-20T23:18:45.199Z,1724195925.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-08-20T23:18:45.330Z,1724195925.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-08-20T23:18:45.499Z,1724195925.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-08-20T23:18:45.664Z,1724195925.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-08-20T23:18:45.788Z,1724195925.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-08-20T23:18:46.070Z,1724195926.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-08-20T23:18:46.071Z,1724195926.071 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-08-20T23:18:46.071Z,1724195926.071 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-08-20T23:18:46.076Z,1724195926.076 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-08-20T23:18:46.131Z,1724195926.131 [VerticalControl](DEBUG): Construct VerticalControl. 2024-08-20T23:18:46.189Z,1724195926.189 [VerticalControl] Loaded 2024-08-20T23:18:46.189Z,1724195926.189 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-08-20T23:18:46.192Z,1724195926.192 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-08-20T23:18:46.237Z,1724195926.237 [HorizontalControl] Loaded 2024-08-20T23:18:46.238Z,1724195926.238 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-08-20T23:18:46.240Z,1724195926.240 [SpeedControl](DEBUG): Construct SpeedControl. 2024-08-20T23:18:46.243Z,1724195926.243 [SpeedControl] Loaded 2024-08-20T23:18:46.243Z,1724195926.243 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-08-20T23:18:46.246Z,1724195926.246 [LoopControl](DEBUG): Construct LoopControl. 2024-08-20T23:18:46.246Z,1724195926.246 [LoopControl] Loaded 2024-08-20T23:18:46.246Z,1724195926.246 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-08-20T23:18:46.247Z,1724195926.247 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-08-20T23:18:46.247Z,1724195926.247 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-08-20T23:18:46.258Z,1724195926.258 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-08-20T23:18:46.258Z,1724195926.258 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-08-20T23:18:46.438Z,1724195926.438 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-08-20T23:18:46.439Z,1724195926.439 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-08-20T23:18:46.574Z,1724195926.574 [BuoyancyServo] Loaded 2024-08-20T23:18:46.575Z,1724195926.575 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-08-20T23:18:46.596Z,1724195926.596 [ElevatorServo] Loaded 2024-08-20T23:18:46.597Z,1724195926.597 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-08-20T23:18:46.617Z,1724195926.617 [MassServo] Loaded 2024-08-20T23:18:46.618Z,1724195926.618 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-08-20T23:18:46.637Z,1724195926.637 [RudderServo] Loaded 2024-08-20T23:18:46.638Z,1724195926.638 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-08-20T23:18:46.654Z,1724195926.654 [ThrusterHE] Loaded 2024-08-20T23:18:46.654Z,1724195926.654 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-08-20T23:18:46.654Z,1724195926.654 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-08-20T23:18:46.655Z,1724195926.655 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-08-20T23:18:46.768Z,1724195926.768 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-08-20T23:18:46.768Z,1724195926.768 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-08-20T23:18:46.781Z,1724195926.781 [NavChart] Loaded 2024-08-20T23:18:46.781Z,1724195926.781 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-08-20T23:18:46.787Z,1724195926.787 [UniversalFixResidualReporter] Loaded 2024-08-20T23:18:46.787Z,1724195926.787 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-08-20T23:18:46.787Z,1724195926.787 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-08-20T23:18:46.788Z,1724195926.788 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-08-20T23:18:46.846Z,1724195926.846 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-08-20T23:18:46.846Z,1724195926.846 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-08-20T23:18:47.301Z,1724195927.301 [AHRS_M2] Loaded 2024-08-20T23:18:47.301Z,1724195927.301 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-08-20T23:18:48.132Z,1724195928.132 [BPC1] Loaded 2024-08-20T23:18:48.133Z,1724195928.133 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-08-20T23:18:48.205Z,1724195928.205 [DataOverHttps] Loaded 2024-08-20T23:18:48.205Z,1724195928.205 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-08-20T23:18:48.206Z,1724195928.206 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2024-08-20T23:18:48.207Z,1724195928.207 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6846 2024-08-20T23:18:48.230Z,1724195928.230 [Depth_Keller] Loaded 2024-08-20T23:18:48.230Z,1724195928.230 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-08-20T23:18:48.235Z,1724195928.235 [DropWeight] Loaded 2024-08-20T23:18:48.235Z,1724195928.235 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-08-20T23:18:48.299Z,1724195928.299 [NAL9602] Loaded 2024-08-20T23:18:48.299Z,1724195928.299 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-08-20T23:18:48.329Z,1724195928.329 [Onboard] Loaded 2024-08-20T23:18:48.329Z,1724195928.329 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-08-20T23:18:48.330Z,1724195928.330 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0 2024-08-20T23:18:48.331Z,1724195928.331 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6847 2024-08-20T23:18:48.346Z,1724195928.346 [Power24vConverter] Loaded 2024-08-20T23:18:48.346Z,1724195928.346 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-08-20T23:18:48.361Z,1724195928.361 [Radio_Surface] Loaded 2024-08-20T23:18:48.361Z,1724195928.361 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-08-20T23:18:48.362Z,1724195928.362 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0 2024-08-20T23:18:48.363Z,1724195928.363 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6848 2024-08-20T23:18:48.458Z,1724195928.458 [DAT] Loaded 2024-08-20T23:18:48.459Z,1724195928.459 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-08-20T23:18:48.460Z,1724195928.460 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0 2024-08-20T23:18:48.460Z,1724195928.460 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6849 2024-08-20T23:18:48.461Z,1724195928.461 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-08-20T23:18:48.461Z,1724195928.461 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-08-20T23:18:48.512Z,1724195928.512 [DepthRateCalculator] Loaded 2024-08-20T23:18:48.513Z,1724195928.513 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-08-20T23:18:48.517Z,1724195928.517 [PitchRateCalculator] Loaded 2024-08-20T23:18:48.518Z,1724195928.518 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-08-20T23:18:48.526Z,1724195928.526 [SpeedCalculator] Loaded 2024-08-20T23:18:48.526Z,1724195928.526 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-08-20T23:18:48.531Z,1724195928.531 [YawRateCalculator] Loaded 2024-08-20T23:18:48.531Z,1724195928.531 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-08-20T23:18:48.549Z,1724195928.549 [ElevatorOffsetCalculator] Loaded 2024-08-20T23:18:48.550Z,1724195928.550 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-08-20T23:18:48.550Z,1724195928.550 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-08-20T23:18:48.551Z,1724195928.551 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-08-20T23:18:48.689Z,1724195928.689 [CANONSampler] Loaded 2024-08-20T23:18:48.689Z,1724195928.689 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-08-20T23:18:48.753Z,1724195928.753 [CTD_Seabird] Loaded 2024-08-20T23:18:48.753Z,1724195928.753 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-08-20T23:18:48.754Z,1724195928.754 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0 2024-08-20T23:18:48.754Z,1724195928.754 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6850 2024-08-20T23:18:48.777Z,1724195928.777 [PAR_Licor] Loaded 2024-08-20T23:18:48.777Z,1724195928.777 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-08-20T23:18:48.819Z,1724195928.819 [WetLabsBB2FL] Loaded 2024-08-20T23:18:48.819Z,1724195928.819 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-08-20T23:18:48.820Z,1724195928.820 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0 2024-08-20T23:18:48.821Z,1724195928.821 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6851 2024-08-20T23:18:48.821Z,1724195928.821 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-08-20T23:18:48.822Z,1724195928.822 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-08-20T23:18:48.849Z,1724195928.849 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-08-20T23:18:48.849Z,1724195928.849 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-08-20T23:18:49.301Z,1724195929.301 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-08-20T23:18:49.302Z,1724195929.302 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-08-20T23:18:49.513Z,1724195929.513 [SBIT](DEBUG): Construct Startup Built In Test. 2024-08-20T23:18:49.529Z,1724195929.529 [SBIT] Loaded 2024-08-20T23:18:49.530Z,1724195929.530 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-08-20T23:18:49.541Z,1724195929.541 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-08-20T23:18:49.561Z,1724195929.561 [IBIT] Loaded 2024-08-20T23:18:49.562Z,1724195929.562 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-08-20T23:18:49.575Z,1724195929.575 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-08-20T23:18:49.742Z,1724195929.742 [CBIT] Loaded 2024-08-20T23:18:49.742Z,1724195929.742 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-08-20T23:18:49.743Z,1724195929.743 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-08-20T23:18:49.743Z,1724195929.743 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-08-20T23:18:49.860Z,1724195929.860 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-08-20T23:18:49.866Z,1724195929.866 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-08-20T23:18:49.869Z,1724195929.869 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-08-20T23:18:49.880Z,1724195929.880 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-08-20T23:18:49.881Z,1724195929.881 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-08-20T23:18:49.882Z,1724195929.882 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6852 2024-08-20T23:18:49.886Z,1724195929.886 [Supervisor](INFO): Main Thread ID is 6758 2024-08-20T23:18:49.886Z,1724195929.886 [Supervisor](DEBUG): Running supervisor. 2024-08-20T23:18:49.887Z,1724195929.887 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6853 2024-08-20T23:18:49.887Z,1724195929.887 [CommandExec](INFO): Initializing the command executive. 2024-08-20T23:18:49.889Z,1724195929.889 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6854 2024-08-20T23:18:49.891Z,1724195929.891 [controlThread ThreadHandler](INFO): Handler Thread ID is 6855 2024-08-20T23:18:49.891Z,1724195929.891 [controlThread](DEBUG): Initializing ControlThread 2024-08-20T23:18:49.892Z,1724195929.892 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-08-20T23:18:49.894Z,1724195929.894 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-08-20T23:18:49.895Z,1724195929.895 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-08-20T23:18:49.895Z,1724195929.895 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-08-20T23:18:49.897Z,1724195929.897 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-08-20T23:18:49.897Z,1724195929.897 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-08-20T23:18:49.901Z,1724195929.901 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-08-20T23:18:49.901Z,1724195929.901 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-08-20T23:18:49.901Z,1724195929.901 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-08-20T23:18:49.902Z,1724195929.902 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-08-20T23:18:49.902Z,1724195929.902 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-08-20T23:18:49.907Z,1724195929.907 [SBIT](INFO): Initialize SBIT Component. 2024-08-20T23:18:49.908Z,1724195929.908 [SBIT](IMPORTANT): git: 2024-08-12 2024-08-20T23:18:49.908Z,1724195929.908 [SBIT](INFO): git hash: 514a2155e4c471e50d5c939abb69db7fb725fa43 2024-08-20T23:18:49.908Z,1724195929.908 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-08-20T23:18:49.909Z,1724195929.909 [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-08-20T23:18:49.910Z,1724195929.910 [SBIT](INFO): Beginning SBIT in 69.000000 seconds. 2024-08-20T23:18:49.911Z,1724195929.911 [IBIT](INFO): Initialize IBIT Component. 2024-08-20T23:18:49.912Z,1724195929.912 [CBIT](DEBUG): Initialize CBIT Component. 2024-08-20T23:18:49.913Z,1724195929.913 [logger ThreadHandler](INFO): Handler Thread ID is 6856 2024-08-20T23:18:49.923Z,1724195929.923 [CBIT](DEBUG): Initialized mux pins. 2024-08-20T23:18:49.923Z,1724195929.923 [CBIT](DEBUG): Initializing the watchdog timer. 2024-08-20T23:18:49.931Z,1724195929.931 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6857 2024-08-20T23:18:49.933Z,1724195929.933 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-08-20T23:18:49.943Z,1724195929.943 [Onboard ThreadHandler](INFO): Handler Thread ID is 6858 2024-08-20T23:18:49.948Z,1724195929.948 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-08-20T23:18:49.948Z,1724195929.948 [CBIT](DEBUG): Initializing heartbeat. 2024-08-20T23:18:49.964Z,1724195929.964 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6859 2024-08-20T23:18:49.979Z,1724195929.979 [DAT ThreadHandler](INFO): Handler Thread ID is 6860 2024-08-20T23:18:49.980Z,1724195929.980 [DAT](INFO): Powering up 2024-08-20T23:18:49.980Z,1724195929.980 [DAT](DEBUG): Initializing DAT. 2024-08-20T23:18:49.984Z,1724195929.984 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6862 2024-08-20T23:18:49.985Z,1724195929.985 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-08-20T23:18:49.988Z,1724195929.988 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6864 2024-08-20T23:18:49.992Z,1724195929.992 [WetLabsBB2FL](INFO): Powering up 2024-08-20T23:18:49.993Z,1724195929.993 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6866 2024-08-20T23:18:49.997Z,1724195929.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-08-20T23:18:49.997Z,1724195929.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-08-20T23:18:49.997Z,1724195929.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-08-20T23:18:49.997Z,1724195929.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-08-20T23:18:49.998Z,1724195929.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-08-20T23:18:49.998Z,1724195929.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-08-20T23:18:49.998Z,1724195929.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-08-20T23:18:49.998Z,1724195929.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-08-20T23:18:50.019Z,1724195930.019 [CBIT](DEBUG): Deactivating GF circuits. 2024-08-20T23:18:50.019Z,1724195930.019 [CBIT](DEBUG): Deactivating emergency mode. 2024-08-20T23:18:50.059Z,1724195930.059 [CBIT](DEBUG): Backplane powered. 2024-08-20T23:18:50.060Z,1724195930.060 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-08-20T23:18:50.060Z,1724195930.060 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-08-20T23:18:50.061Z,1724195930.061 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-08-20T23:18:50.062Z,1724195930.062 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-08-20T23:18:50.106Z,1724195930.106 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-08-20T23:18:50.127Z,1724195930.127 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-20T23:18:50.155Z,1724195930.155 [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-08-20T23:18:50.155Z,1724195930.155 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-08-20T23:18:50.156Z,1724195930.156 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-08-20T23:18:50.157Z,1724195930.157 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-08-20T23:18:50.367Z,1724195930.367 [Radio_Surface](INFO): Powering up 2024-08-20T23:18:50.477Z,1724195930.477 [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-08-20T23:18:50.482Z,1724195930.482 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-08-20T23:18:50.492Z,1724195930.492 [Default:A.Wait](DEBUG): Construct Wait. 2024-08-20T23:18:50.494Z,1724195930.494 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-20T23:18:50.522Z,1724195930.522 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-08-20T23:18:50.541Z,1724195930.541 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-08-20T23:18:50.563Z,1724195930.563 [Default:E.Execute](DEBUG): Construct Execute. 2024-08-20T23:18:50.566Z,1724195930.566 [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-08-20T23:18:50.586Z,1724195930.586 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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-08-20T23:18:50.607Z,1724195930.607 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-08-20T23:18:50.677Z,1724195930.677 [Power24vConverter](INFO): Powering up. 2024-08-20T23:18:50.678Z,1724195930.678 [CANONSampler](INFO): Powering down 2024-08-20T23:18:50.716Z,1724195930.716 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-08-20T23:18:50.723Z,1724195930.723 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-08-20T23:18:50.724Z,1724195930.724 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-08-20T23:18:50.731Z,1724195930.731 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-08-20T23:18:50.732Z,1724195930.732 [MassServo](DEBUG): Initializing EZServoServo. 2024-08-20T23:18:50.739Z,1724195930.739 [MassServo](DEBUG): Initializing MassServo. 2024-08-20T23:18:50.740Z,1724195930.740 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-20T23:18:50.747Z,1724195930.747 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-20T23:18:50.748Z,1724195930.748 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-08-20T23:18:50.755Z,1724195930.755 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-08-20T23:18:52.603Z,1724195932.603 [WetLabsBB2FL](INFO): Powering down 2024-08-20T23:18:56.628Z,1724195936.628 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-08-20T23:19:01.604Z,1724195941.604 [DAT](INFO): DAT read: 2024-08-20T23:19:01.605Z,1724195941.605 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-08-20T23:19:03.116Z,1724195943.116 [DAT](INFO): DAT read: MF Frequency Band 2024-08-20T23:19:03.117Z,1724195943.117 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-08-20T23:19:03.118Z,1724195943.118 [DAT](INFO): DAT read: Aug 20 2024 23:09:15 2024-08-20T23:19:03.685Z,1724195943.685 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004829 2024-08-20T23:19:04.124Z,1724195944.124 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-08-20T23:19:04.125Z,1724195944.125 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-08-20T23:19:04.126Z,1724195944.126 [DAT](INFO): commRate: 1200 2024-08-20T23:19:06.191Z,1724195946.191 [DAT](INFO): entering command mode 2024-08-20T23:19:06.392Z,1724195946.392 [DAT](INFO): DAT read: 2024-08-20T23:19:06.392Z,1724195946.392 [DAT](INFO): DAT read: user:1> 2024-08-20T23:19:06.392Z,1724195946.392 [DAT](INFO): setting verbose to 3 2024-08-20T23:19:06.644Z,1724195946.644 [DAT](INFO): DAT read: user:1> 2024-08-20T23:19:06.645Z,1724195946.645 [DAT](INFO): DAT read: Verbose | 3 2024-08-20T23:19:06.645Z,1724195946.645 [DAT](INFO): set verbose to 3 2024-08-20T23:19:06.645Z,1724195946.645 [DAT](INFO): setting DatVerbose to 27440 2024-08-20T23:19:06.896Z,1724195946.896 [DAT](INFO): DAT read: user:2> 2024-08-20T23:19:06.897Z,1724195946.897 [DAT](INFO): DAT read: DatVerbose | 27440 2024-08-20T23:19:06.897Z,1724195946.897 [DAT](INFO): set DatVerbose to 27440 2024-08-20T23:19:06.897Z,1724195946.897 [DAT](INFO): setting transmit power to 8 2024-08-20T23:19:07.148Z,1724195947.148 [DAT](INFO): DAT read: user:3> 2024-08-20T23:19:07.149Z,1724195947.149 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-08-20T23:19:07.150Z,1724195947.150 [DAT](INFO): set transmit power to 8 2024-08-20T23:19:07.151Z,1724195947.151 [DAT](INFO): setting local address to 8 2024-08-20T23:19:07.400Z,1724195947.400 [DAT](INFO): DAT read: user:4> 2024-08-20T23:19:07.401Z,1724195947.401 [DAT](INFO): DAT read: LocalAddr | 8 2024-08-20T23:19:07.401Z,1724195947.401 [DAT](INFO): set local address to 8 2024-08-20T23:19:07.402Z,1724195947.402 [DAT](INFO): Setting time to: 23:19:7 And date to:8/20/2024 2024-08-20T23:19:07.652Z,1724195947.652 [DAT](INFO): DAT read: user:5> 2024-08-20T23:19:07.653Z,1724195947.653 [DAT](INFO): DAT read: Tue Aug 20, 2024 23:19:07 2024-08-20T23:19:07.653Z,1724195947.653 [DAT](INFO): Local DAT time set to Tue Aug 20, 2024 23:19:07 2024-08-20T23:19:18.521Z,1724195958.521 [NAL9602](INFO): Powering up NAL9602 2024-08-20T23:19:29.429Z,1724195969.429 [NAL9602](INFO): NAL9602 initialized 2024-08-20T23:19:59.358Z,1724195999.358 [SBIT](IMPORTANT): Beginning Startup BIT 2024-08-20T23:19:59.362Z,1724195999.362 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-20T23:20:10.306Z,1724196010.306 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-08-20T23:20:10.702Z,1724196010.702 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-20T23:20:21.598Z,1724196021.598 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-08-20T23:20:22.060Z,1724196022.060 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-20T23:20:32.877Z,1724196032.877 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-08-20T23:20:32.877Z,1724196032.877 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-08-20T23:20:53.477Z,1724196053.477 [SBIT](IMPORTANT): SBIT PASSED 2024-08-20T23:20:53.477Z,1724196053.477 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-08-20T23:20:53.478Z,1724196053.478 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-08-20T23:20:53.478Z,1724196053.478 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=202.807188 cubic_centimeter; 2024-08-20T23:20:53.478Z,1724196053.478 [SBIT](IMPORTANT): VerticalControl.massDefault=12.366144 millimeter; 2024-08-20T23:20:53.883Z,1724196053.883 [MissionManager](IMPORTANT): Started mission Startup 2024-08-20T23:20:53.884Z,1724196053.884 [Startup] Running Loop=1 2024-08-20T23:20:53.884Z,1724196053.884 [Startup](DEBUG): Aggregate::initialize Startup 2024-08-20T23:20:53.884Z,1724196053.884 [Startup:A.GoToSurface] Running Loop=1 2024-08-20T23:20:53.884Z,1724196053.884 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-20T23:20:53.884Z,1724196053.884 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-20T23:20:53.885Z,1724196053.885 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-20T23:20:53.885Z,1724196053.885 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-20T23:20:53.885Z,1724196053.885 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-20T23:20:53.886Z,1724196053.886 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-20T23:20:53.886Z,1724196053.886 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-20T23:20:53.888Z,1724196053.888 [Startup:StartupSatComms] Running Loop=1 2024-08-20T23:20:53.888Z,1724196053.888 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-08-20T23:20:53.888Z,1724196053.888 [Startup:StartupSatComms:A] Running Loop=1 2024-08-20T23:20:54.286Z,1724196054.286 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-08-20T23:21:37.560Z,1724196097.560 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-20T23:21:50.035Z,1724196110.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-08-20T23:21:50.035Z,1724196110.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-20T23:21:50.114Z,1724196110.114 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-20T23:21:50.511Z,1724196110.511 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-20T23:21:50.511Z,1724196110.511 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-08-20T23:21:54.156Z,1724196114.156 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-20T23:20:53.9Z 2024-08-20T23:21:54.157Z,1724196114.157 [Startup:StartupSatComms:A] Stopped 2024-08-20T23:21:54.158Z,1724196114.158 [Startup:StartupSatComms:B] Running Loop=1 2024-08-20T23:21:54.505Z,1724196114.505 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-08-20T23:21:59.817Z,1724196119.817 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240820T211826/Courier0025.lzma 2024-08-20T23:22:00.817Z,1724196120.817 [DataOverHttps](INFO): Moved sent file to Logs/20240820T211826/Courier0025.lzma.bak 2024-08-20T23:22:00.817Z,1724196120.817 [DataOverHttps](INFO): SBD MOMSN=23035948 2024-08-20T23:22:16.695Z,1724196136.695 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240820T231838/Courier0000.lzma 2024-08-20T23:22:17.697Z,1724196137.697 [DataOverHttps](INFO): Moved sent file to Logs/20240820T231838/Courier0000.lzma.bak 2024-08-20T23:22:17.697Z,1724196137.697 [DataOverHttps](INFO): SBD MOMSN=23035950 2024-08-20T23:22:33.648Z,1724196153.648 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20240820T211826/Express0026.lzma 2024-08-20T23:22:34.649Z,1724196154.649 [DataOverHttps](INFO): Moved sent file to Logs/20240820T211826/Express0026.lzma.bak 2024-08-20T23:22:34.649Z,1724196154.649 [DataOverHttps](INFO): SBD MOMSN=23035953 2024-08-20T23:22:50.468Z,1724196170.468 [DataOverHttps](INFO): Sending 666 bytes from file Logs/20240820T231838/Express0001.lzma 2024-08-20T23:22:51.469Z,1724196171.469 [DataOverHttps](INFO): Moved sent file to Logs/20240820T231838/Express0001.lzma.bak 2024-08-20T23:22:51.469Z,1724196171.469 [DataOverHttps](INFO): SBD MOMSN=23035960 2024-08-20T23:22:52.681Z,1724196172.681 [Startup:StartupSatComms:B] Stopped 2024-08-20T23:22:52.681Z,1724196172.681 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-08-20T23:22:52.681Z,1724196172.681 [Startup:StartupSatComms] Stopped 2024-08-20T23:22:52.681Z,1724196172.681 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-08-20T23:22:52.682Z,1724196172.682 [Startup](INFO): Completed Startup 2024-08-20T23:22:52.682Z,1724196172.682 [MissionManager](INFO): Startup is completed. 2024-08-20T23:22:52.683Z,1724196172.683 [MissionManager](INFO): Uninitializing Mission Startup 2024-08-20T23:22:52.683Z,1724196172.683 [Startup] Stopped 2024-08-20T23:22:52.683Z,1724196172.683 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-08-20T23:22:52.683Z,1724196172.683 [Startup:A.GoToSurface] Stopped 2024-08-20T23:22:52.683Z,1724196172.683 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-20T23:22:53.065Z,1724196173.065 [MissionManager](IMPORTANT): Started mission Default 2024-08-20T23:22:53.065Z,1724196173.065 [Default] Running Loop=1 2024-08-20T23:22:53.065Z,1724196173.065 [Default](DEBUG): Aggregate::initialize Default 2024-08-20T23:22:53.065Z,1724196173.065 [Default:B.GoToSurface] Running Loop=1 2024-08-20T23:22:53.065Z,1724196173.065 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-20T23:22:53.066Z,1724196173.066 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-20T23:22:53.066Z,1724196173.066 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-20T23:22:53.066Z,1724196173.066 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-20T23:22:53.067Z,1724196173.067 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-20T23:22:53.067Z,1724196173.067 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-20T23:22:53.067Z,1724196173.067 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-20T23:22:53.068Z,1724196173.068 [Default:A.Wait] Running Loop=1 2024-08-20T23:22:53.068Z,1724196173.068 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-08-20T23:23:06.404Z,1724196186.404 [Default:A.Wait](INFO): Done Waiting. 2024-08-20T23:23:06.404Z,1724196186.404 [Default:A.Wait] Stopped 2024-08-20T23:23:06.404Z,1724196186.404 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-20T23:23:06.802Z,1724196186.802 [Default:CheckIn] Running Loop=1 2024-08-20T23:23:06.802Z,1724196186.802 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-08-20T23:23:06.802Z,1724196186.802 [Default:CheckIn:Read_GPS] Running Loop=1 2024-08-20T23:23:07.205Z,1724196187.205 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-20T23:24:05.025Z,1724196245.025 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-08-20T23:24:20.379Z,1724196260.379 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-08-20T23:24:33.260Z,1724196273.260 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-08-20T23:24:46.180Z,1724196286.180 [CommandExec](IMPORTANT): got command failComponent 2024-08-20T23:24:46.180Z,1724196286.180 [CommandExec](IMPORTANT): Failed components: 2024-08-20T23:24:46.180Z,1724196286.180 [CommandExec](IMPORTANT): No failed Components. 2024-08-20T23:24:51.031Z,1724196291.031 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-08-20T23:24:51.031Z,1724196291.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-20T23:24:51.111Z,1724196291.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-20T23:24:51.523Z,1724196291.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-20T23:24:51.523Z,1724196291.523 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-08-20T23:24:51.592Z,1724196291.592 [CommandExec](IMPORTANT): got command quit 2024-08-20T23:24:52.595Z,1724196292.595 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-08-20T23:24:52.595Z,1724196292.595 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:52.595Z,1724196292.595 [CommandExec](INFO): Uninitializing the command executive. 2024-08-20T23:24:52.596Z,1724196292.596 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-20T23:24:52.596Z,1724196292.596 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:52.795Z,1724196292.795 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-20T23:24:52.795Z,1724196292.795 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-20T23:24:52.795Z,1724196292.795 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:52.796Z,1724196292.796 [NavChartDb](INFO): Join timeout helper Thread ID is 6882 2024-08-20T23:24:53.159Z,1724196293.159 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:53.159Z,1724196293.159 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:53.171Z,1724196293.171 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-08-20T23:24:53.171Z,1724196293.171 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:53.172Z,1724196293.172 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6883 2024-08-20T23:24:53.587Z,1724196293.587 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:53.587Z,1724196293.587 [WetLabsBB2FL](INFO): Powering down 2024-08-20T23:24:53.588Z,1724196293.588 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:53.591Z,1724196293.591 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-20T23:24:53.591Z,1724196293.591 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:53.591Z,1724196293.591 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6884 2024-08-20T23:24:54.567Z,1724196294.567 [CTD_Seabird](INFO): Powering down 2024-08-20T23:24:54.579Z,1724196294.579 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:54.579Z,1724196294.579 [CTD_Seabird](INFO): Powering down 2024-08-20T23:24:54.591Z,1724196294.591 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:54.591Z,1724196294.591 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-08-20T23:24:54.591Z,1724196294.591 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:54.592Z,1724196294.592 [DAT](INFO): Join timeout helper Thread ID is 6885 2024-08-20T23:24:54.771Z,1724196294.771 [DAT](INFO): Powering down 2024-08-20T23:24:54.844Z,1724196294.844 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:54.844Z,1724196294.844 [DAT](INFO): Powering down 2024-08-20T23:24:54.845Z,1724196294.845 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:54.859Z,1724196294.859 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-20T23:24:54.859Z,1724196294.859 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:54.859Z,1724196294.859 [Radio_Surface](INFO): Join timeout helper Thread ID is 6886 2024-08-20T23:24:55.195Z,1724196295.195 [Radio_Surface](INFO): Powering down 2024-08-20T23:24:55.196Z,1724196295.196 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:55.196Z,1724196295.196 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.208Z,1724196295.208 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-20T23:24:55.208Z,1724196295.208 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.208Z,1724196295.208 [Onboard](INFO): Join timeout helper Thread ID is 6887 2024-08-20T23:24:55.243Z,1724196295.243 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:55.243Z,1724196295.243 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.248Z,1724196295.248 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-08-20T23:24:55.248Z,1724196295.248 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.248Z,1724196295.248 [DataOverHttps](INFO): Join timeout helper Thread ID is 6888 2024-08-20T23:24:55.520Z,1724196295.520 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:55.522Z,1724196295.522 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.536Z,1724196295.536 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-08-20T23:24:55.536Z,1724196295.536 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.536Z,1724196295.536 [logger](INFO): Join timeout helper Thread ID is 6889 2024-08-20T23:24:55.543Z,1724196295.543 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:55.544Z,1724196295.544 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.556Z,1724196295.556 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-08-20T23:24:55.556Z,1724196295.556 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.556Z,1724196295.556 [CommandLine](INFO): Join timeout helper Thread ID is 6890 2024-08-20T23:24:55.557Z,1724196295.557 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:55.557Z,1724196295.557 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.558Z,1724196295.558 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-08-20T23:24:55.558Z,1724196295.558 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.559Z,1724196295.559 [CommandExec](INFO): Join timeout helper Thread ID is 6891 2024-08-20T23:24:55.560Z,1724196295.560 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-08-20T23:24:55.560Z,1724196295.560 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:55.561Z,1724196295.561 [controlThread](INFO): Join timeout helper Thread ID is 6892 2024-08-20T23:24:55.855Z,1724196295.855 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-20T23:24:55.855Z,1724196295.855 [controlThread](DEBUG): Uninitializing ControlThread 2024-08-20T23:24:55.856Z,1724196295.856 [AHRS_M2](INFO): Powering down 2024-08-20T23:24:55.928Z,1724196295.928 [NAL9602](INFO): Powering down 2024-08-20T23:24:55.931Z,1724196295.931 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-08-20T23:24:55.932Z,1724196295.932 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-08-20T23:24:55.932Z,1724196295.932 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-08-20T23:24:55.933Z,1724196295.933 [MissionManager](INFO): Uninitializing Mission Default 2024-08-20T23:24:55.933Z,1724196295.933 [Default] Stopped 2024-08-20T23:24:55.933Z,1724196295.933 [Default](DEBUG): Aggregate::uninitialize Default 2024-08-20T23:24:55.933Z,1724196295.933 [Default:B.GoToSurface] Stopped 2024-08-20T23:24:55.933Z,1724196295.933 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-20T23:24:55.933Z,1724196295.933 [Default:CheckIn] Stopped 2024-08-20T23:24:55.933Z,1724196295.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-08-20T23:24:55.933Z,1724196295.933 [Default:CheckIn:Read_GPS] Stopped 2024-08-20T23:24:55.936Z,1724196295.936 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-08-20T23:24:55.936Z,1724196295.936 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-08-20T23:24:55.937Z,1724196295.937 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-08-20T23:24:55.937Z,1724196295.937 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-08-20T23:24:55.937Z,1724196295.937 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-08-20T23:24:55.937Z,1724196295.937 [BuoyancyServo](INFO): Powering down 2024-08-20T23:24:55.951Z,1724196295.951 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-08-20T23:24:55.951Z,1724196295.951 [ElevatorServo](INFO): Powering down 2024-08-20T23:24:55.952Z,1724196295.952 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-08-20T23:24:55.952Z,1724196295.952 [MassServo](INFO): Powering down 2024-08-20T23:24:55.953Z,1724196295.953 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-20T23:24:55.953Z,1724196295.953 [RudderServo](INFO): Powering down 2024-08-20T23:24:55.954Z,1724196295.954 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-08-20T23:24:55.954Z,1724196295.954 [ThrusterHE](INFO): Powering down 2024-08-20T23:24:55.955Z,1724196295.955 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-08-20T23:24:55.955Z,1724196295.955 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-08-20T23:24:55.955Z,1724196295.955 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-08-20T23:24:55.956Z,1724196295.956 [CBIT](DEBUG): Powering off loads. 2024-08-20T23:24:55.967Z,1724196295.967 [CBIT](DEBUG): Disabling WDT. 2024-08-20T23:24:55.979Z,1724196295.979 [CBIT](DEBUG): Opening all GF detection circuits. 2024-08-20T23:24:55.980Z,1724196295.980 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.154Z,1724196296.154 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.159Z,1724196296.159 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.161Z,1724196296.161 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.167Z,1724196296.167 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.238Z,1724196296.238 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.242Z,1724196296.242 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.283Z,1724196296.283 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-20T23:24:56.346Z,1724196296.346 [logger ThreadHandler](INFO): Thread cancelled.