2024-10-25T20:17:27.226Z,1729887447.226 [Supervisor](DEBUG): Initializing supervisor.
2024-10-25T20:17:27.230Z,1729887447.230 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-10-25T20:17:27.230Z,1729887447.230 [SyncHandler](INFO): Protected caller Thread ID is 8518
2024-10-25T20:17:27.231Z,1729887447.231 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-10-25T20:17:27.232Z,1729887447.232 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-10-25T20:17:27.232Z,1729887447.232 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8519
2024-10-25T20:17:27.236Z,1729887447.236 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-10-25T20:17:27.253Z,1729887447.253 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-10-25T20:17:27.254Z,1729887447.254 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-10-25T20:17:27.254Z,1729887447.254 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8520
2024-10-25T20:17:27.258Z,1729887447.258 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-10-25T20:17:27.259Z,1729887447.259 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-10-25T20:17:27.260Z,1729887447.260 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8521
2024-10-25T20:17:27.262Z,1729887447.262 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-10-25T20:17:27.263Z,1729887447.263 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-10-25T20:17:27.263Z,1729887447.263 [logger ThreadHandler](INFO): Protected caller Thread ID is 8522
2024-10-25T20:17:27.266Z,1729887447.266 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-10-25T20:17:27.267Z,1729887447.267 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-10-25T20:17:27.269Z,1729887447.269 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-10-25T20:17:27.625Z,1729887447.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-10-25T20:17:27.626Z,1729887447.626 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-10-25T20:17:27.721Z,1729887447.721 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-10-25T20:17:28.270Z,1729887448.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-10-25T20:17:28.271Z,1729887448.271 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-10-25T20:17:28.630Z,1729887448.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-10-25T20:17:28.631Z,1729887448.631 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-10-25T20:17:28.740Z,1729887448.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-10-25T20:17:28.740Z,1729887448.740 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-10-25T20:17:29.164Z,1729887449.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-10-25T20:17:29.165Z,1729887449.165 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-10-25T20:17:29.394Z,1729887449.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-10-25T20:17:29.394Z,1729887449.394 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-10-25T20:17:29.945Z,1729887449.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-10-25T20:17:29.945Z,1729887449.945 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-10-25T20:17:30.094Z,1729887450.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-10-25T20:17:30.094Z,1729887450.094 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-10-25T20:17:30.186Z,1729887450.186 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-10-25T20:17:31.205Z,1729887451.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-10-25T20:17:31.206Z,1729887451.206 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-10-25T20:17:31.606Z,1729887451.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-10-25T20:17:31.607Z,1729887451.607 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-10-25T20:17:31.807Z,1729887451.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-10-25T20:17:31.807Z,1729887451.807 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-10-25T20:17:31.931Z,1729887451.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-10-25T20:17:31.932Z,1729887451.932 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-10-25T20:17:32.173Z,1729887452.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-10-25T20:17:32.173Z,1729887452.173 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-10-25T20:17:32.437Z,1729887452.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-10-25T20:17:32.439Z,1729887452.439 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-10-25T20:17:32.440Z,1729887452.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-10-25T20:17:32.544Z,1729887452.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-10-25T20:17:32.678Z,1729887452.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-10-25T20:17:32.835Z,1729887452.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-10-25T20:17:33.043Z,1729887453.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-10-25T20:17:33.331Z,1729887453.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-10-25T20:17:33.595Z,1729887453.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-10-25T20:17:33.983Z,1729887453.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-10-25T20:17:34.150Z,1729887454.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-10-25T20:17:34.312Z,1729887454.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-10-25T20:17:34.434Z,1729887454.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-10-25T20:17:34.711Z,1729887454.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-10-25T20:17:34.712Z,1729887454.712 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-10-25T20:17:34.713Z,1729887454.713 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-10-25T20:17:34.725Z,1729887454.725 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-10-25T20:17:34.776Z,1729887454.776 [VerticalControl](DEBUG): Construct VerticalControl.
2024-10-25T20:17:34.834Z,1729887454.834 [VerticalControl] Loaded
2024-10-25T20:17:34.834Z,1729887454.834 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-10-25T20:17:34.837Z,1729887454.837 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-10-25T20:17:34.883Z,1729887454.883 [HorizontalControl] Loaded
2024-10-25T20:17:34.883Z,1729887454.883 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-10-25T20:17:34.885Z,1729887454.885 [SpeedControl](DEBUG): Construct SpeedControl.
2024-10-25T20:17:34.888Z,1729887454.888 [SpeedControl] Loaded
2024-10-25T20:17:34.889Z,1729887454.889 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-10-25T20:17:34.891Z,1729887454.891 [LoopControl](DEBUG): Construct LoopControl.
2024-10-25T20:17:34.892Z,1729887454.892 [LoopControl] Loaded
2024-10-25T20:17:34.892Z,1729887454.892 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-10-25T20:17:34.892Z,1729887454.892 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-10-25T20:17:34.893Z,1729887454.893 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-10-25T20:17:34.903Z,1729887454.903 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-10-25T20:17:34.903Z,1729887454.903 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-10-25T20:17:35.068Z,1729887455.068 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-10-25T20:17:35.069Z,1729887455.069 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-10-25T20:17:35.224Z,1729887455.224 [BuoyancyServo] Loaded
2024-10-25T20:17:35.224Z,1729887455.224 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-10-25T20:17:35.246Z,1729887455.246 [ElevatorServo] Loaded
2024-10-25T20:17:35.246Z,1729887455.246 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-10-25T20:17:35.266Z,1729887455.266 [MassServo] Loaded
2024-10-25T20:17:35.267Z,1729887455.267 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-10-25T20:17:35.287Z,1729887455.287 [RudderServo] Loaded
2024-10-25T20:17:35.287Z,1729887455.287 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-10-25T20:17:35.304Z,1729887455.304 [ThrusterHE] Loaded
2024-10-25T20:17:35.304Z,1729887455.304 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-10-25T20:17:35.304Z,1729887455.304 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-10-25T20:17:35.305Z,1729887455.305 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-10-25T20:17:35.414Z,1729887455.414 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-10-25T20:17:35.414Z,1729887455.414 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-10-25T20:17:35.427Z,1729887455.427 [NavChart] Loaded
2024-10-25T20:17:35.427Z,1729887455.427 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-10-25T20:17:35.433Z,1729887455.433 [UniversalFixResidualReporter] Loaded
2024-10-25T20:17:35.433Z,1729887455.433 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-10-25T20:17:35.433Z,1729887455.433 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-10-25T20:17:35.434Z,1729887455.434 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-10-25T20:17:35.488Z,1729887455.488 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-10-25T20:17:35.488Z,1729887455.488 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-10-25T20:17:35.911Z,1729887455.911 [AHRS_M2] Loaded
2024-10-25T20:17:35.911Z,1729887455.911 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-10-25T20:17:36.732Z,1729887456.732 [BPC1] Loaded
2024-10-25T20:17:36.732Z,1729887456.732 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-10-25T20:17:36.804Z,1729887456.804 [DataOverHttps] Loaded
2024-10-25T20:17:36.805Z,1729887456.805 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-10-25T20:17:36.806Z,1729887456.806 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-10-25T20:17:36.806Z,1729887456.806 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8604
2024-10-25T20:17:36.829Z,1729887456.829 [Depth_Keller] Loaded
2024-10-25T20:17:36.829Z,1729887456.829 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-10-25T20:17:36.834Z,1729887456.834 [DropWeight] Loaded
2024-10-25T20:17:36.834Z,1729887456.834 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-10-25T20:17:36.897Z,1729887456.897 [NAL9602] Loaded
2024-10-25T20:17:36.898Z,1729887456.898 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-10-25T20:17:36.927Z,1729887456.927 [Onboard] Loaded
2024-10-25T20:17:36.927Z,1729887456.927 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-10-25T20:17:36.928Z,1729887456.928 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-10-25T20:17:36.929Z,1729887456.929 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8605
2024-10-25T20:17:36.944Z,1729887456.944 [Power24vConverter] Loaded
2024-10-25T20:17:36.944Z,1729887456.944 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-10-25T20:17:36.959Z,1729887456.959 [Radio_Surface] Loaded
2024-10-25T20:17:36.959Z,1729887456.959 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-10-25T20:17:36.960Z,1729887456.960 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-10-25T20:17:36.961Z,1729887456.961 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8606
2024-10-25T20:17:36.976Z,1729887456.976 [Sonardyne_Nano] Loaded
2024-10-25T20:17:36.976Z,1729887456.976 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-10-25T20:17:36.976Z,1729887456.976 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-10-25T20:17:36.977Z,1729887456.977 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-10-25T20:17:37.025Z,1729887457.025 [DepthRateCalculator] Loaded
2024-10-25T20:17:37.025Z,1729887457.025 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-10-25T20:17:37.030Z,1729887457.030 [PitchRateCalculator] Loaded
2024-10-25T20:17:37.030Z,1729887457.030 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-10-25T20:17:37.039Z,1729887457.039 [SpeedCalculator] Loaded
2024-10-25T20:17:37.039Z,1729887457.039 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-10-25T20:17:37.043Z,1729887457.043 [YawRateCalculator] Loaded
2024-10-25T20:17:37.044Z,1729887457.044 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-10-25T20:17:37.062Z,1729887457.062 [ElevatorOffsetCalculator] Loaded
2024-10-25T20:17:37.062Z,1729887457.062 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-10-25T20:17:37.063Z,1729887457.063 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-10-25T20:17:37.063Z,1729887457.063 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-10-25T20:17:37.234Z,1729887457.234 [CTD_Seabird] Loaded
2024-10-25T20:17:37.234Z,1729887457.234 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-10-25T20:17:37.235Z,1729887457.235 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2024-10-25T20:17:37.235Z,1729887457.235 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8607
2024-10-25T20:17:37.257Z,1729887457.257 [PAR_Licor] Loaded
2024-10-25T20:17:37.257Z,1729887457.257 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-10-25T20:17:37.302Z,1729887457.302 [WetLabsBB2FL] Loaded
2024-10-25T20:17:37.302Z,1729887457.302 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-10-25T20:17:37.303Z,1729887457.303 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-10-25T20:17:37.303Z,1729887457.303 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8608
2024-10-25T20:17:37.304Z,1729887457.304 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-10-25T20:17:37.304Z,1729887457.304 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-10-25T20:17:37.330Z,1729887457.330 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-10-25T20:17:37.331Z,1729887457.331 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-10-25T20:17:37.655Z,1729887457.655 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-10-25T20:17:37.655Z,1729887457.655 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-10-25T20:17:37.791Z,1729887457.791 [SBIT](DEBUG): Construct Startup Built In Test.
2024-10-25T20:17:37.801Z,1729887457.801 [SBIT] Loaded
2024-10-25T20:17:37.802Z,1729887457.802 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-10-25T20:17:37.804Z,1729887457.804 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-10-25T20:17:37.817Z,1729887457.817 [IBIT] Loaded
2024-10-25T20:17:37.817Z,1729887457.817 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-10-25T20:17:37.823Z,1729887457.823 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-10-25T20:17:37.925Z,1729887457.925 [CBIT] Loaded
2024-10-25T20:17:37.926Z,1729887457.926 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-10-25T20:17:37.926Z,1729887457.926 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-10-25T20:17:37.927Z,1729887457.927 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-10-25T20:17:38.180Z,1729887458.180 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-10-25T20:17:38.315Z,1729887458.315 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-10-25T20:17:38.319Z,1729887458.319 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-10-25T20:17:38.330Z,1729887458.330 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-10-25T20:17:38.331Z,1729887458.331 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-10-25T20:17:38.332Z,1729887458.332 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8609
2024-10-25T20:17:38.336Z,1729887458.336 [Supervisor](INFO): Main Thread ID is 8272
2024-10-25T20:17:38.336Z,1729887458.336 [Supervisor](DEBUG): Running supervisor.
2024-10-25T20:17:38.337Z,1729887458.337 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8610
2024-10-25T20:17:38.337Z,1729887458.337 [CommandExec](INFO): Initializing the command executive.
2024-10-25T20:17:38.339Z,1729887458.339 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8611
2024-10-25T20:17:38.342Z,1729887458.342 [controlThread ThreadHandler](INFO): Handler Thread ID is 8612
2024-10-25T20:17:38.343Z,1729887458.343 [controlThread](DEBUG): Initializing ControlThread
2024-10-25T20:17:38.343Z,1729887458.343 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-10-25T20:17:38.345Z,1729887458.345 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-10-25T20:17:38.346Z,1729887458.346 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-10-25T20:17:38.346Z,1729887458.346 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-10-25T20:17:38.348Z,1729887458.348 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-10-25T20:17:38.349Z,1729887458.349 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-10-25T20:17:38.354Z,1729887458.354 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-10-25T20:17:38.355Z,1729887458.355 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-10-25T20:17:38.355Z,1729887458.355 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-10-25T20:17:38.355Z,1729887458.355 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-10-25T20:17:38.356Z,1729887458.356 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-10-25T20:17:38.361Z,1729887458.361 [SBIT](INFO): Initialize SBIT Component.
2024-10-25T20:17:38.361Z,1729887458.361 [SBIT](IMPORTANT): git: 2024-10-25
2024-10-25T20:17:38.361Z,1729887458.361 [SBIT](INFO): git hash: fb21fbef08ef638e26610dd09246b1b72c2e8aa9
2024-10-25T20:17:38.362Z,1729887458.362 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-10-25T20:17:38.363Z,1729887458.363 [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-10-25T20:17:38.364Z,1729887458.364 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2024-10-25T20:17:38.365Z,1729887458.365 [IBIT](INFO): Initialize IBIT Component.
2024-10-25T20:17:38.365Z,1729887458.365 [CBIT](DEBUG): Initialize CBIT Component.
2024-10-25T20:17:38.366Z,1729887458.366 [logger ThreadHandler](INFO): Handler Thread ID is 8613
2024-10-25T20:17:38.376Z,1729887458.376 [CBIT](DEBUG): Initialized mux pins.
2024-10-25T20:17:38.377Z,1729887458.377 [CBIT](DEBUG): Initializing the watchdog timer.
2024-10-25T20:17:38.385Z,1729887458.385 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8614
2024-10-25T20:17:38.387Z,1729887458.387 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-10-25T20:17:38.397Z,1729887458.397 [Onboard ThreadHandler](INFO): Handler Thread ID is 8615
2024-10-25T20:17:38.401Z,1729887458.401 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-10-25T20:17:38.402Z,1729887458.402 [CBIT](DEBUG): Initializing heartbeat.
2024-10-25T20:17:38.418Z,1729887458.418 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8616
2024-10-25T20:17:38.437Z,1729887458.437 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8617
2024-10-25T20:17:38.438Z,1729887458.438 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-10-25T20:17:38.443Z,1729887458.443 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8619
2024-10-25T20:17:38.449Z,1729887458.449 [WetLabsBB2FL](INFO): Powering up
2024-10-25T20:17:38.450Z,1729887458.450 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8621
2024-10-25T20:17:38.453Z,1729887458.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-10-25T20:17:38.454Z,1729887458.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-10-25T20:17:38.473Z,1729887458.473 [CBIT](DEBUG): Deactivating GF circuits.
2024-10-25T20:17:38.473Z,1729887458.473 [CBIT](DEBUG): Deactivating emergency mode.
2024-10-25T20:17:38.512Z,1729887458.512 [CBIT](DEBUG): Backplane powered.
2024-10-25T20:17:38.513Z,1729887458.513 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-10-25T20:17:38.514Z,1729887458.514 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-10-25T20:17:38.514Z,1729887458.514 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-10-25T20:17:38.515Z,1729887458.515 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-10-25T20:17:38.562Z,1729887458.562 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-10-25T20:17:38.566Z,1729887458.566 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-25T20:17:38.610Z,1729887458.610 [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-10-25T20:17:38.611Z,1729887458.611 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-10-25T20:17:38.611Z,1729887458.611 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-10-25T20:17:38.612Z,1729887458.612 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-10-25T20:17:38.821Z,1729887458.821 [Radio_Surface](INFO): Powering up
2024-10-25T20:17:38.899Z,1729887458.899 [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-10-25T20:17:38.929Z,1729887458.929 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-10-25T20:17:38.930Z,1729887458.930 [Default:A.Wait](DEBUG): Construct Wait.
2024-10-25T20:17:38.932Z,1729887458.932 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-25T20:17:38.997Z,1729887458.997 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-10-25T20:17:38.999Z,1729887458.999 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-10-25T20:17:39.025Z,1729887459.025 [Default:E.Execute](DEBUG): Construct Execute.
2024-10-25T20:17:39.028Z,1729887459.028 [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-10-25T20:17:39.040Z,1729887459.040 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-10-25T20:17:39.053Z,1729887459.053 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-10-25T20:17:39.119Z,1729887459.119 [Power24vConverter](INFO): Powering up.
2024-10-25T20:17:39.120Z,1729887459.120 [Sonardyne_Nano](INFO): Initializing.
2024-10-25T20:17:39.180Z,1729887459.180 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-10-25T20:17:39.185Z,1729887459.185 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-10-25T20:17:39.186Z,1729887459.186 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-10-25T20:17:39.193Z,1729887459.193 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-10-25T20:17:39.194Z,1729887459.194 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-25T20:17:39.201Z,1729887459.201 [MassServo](DEBUG): Initializing MassServo.
2024-10-25T20:17:39.202Z,1729887459.202 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-25T20:17:39.209Z,1729887459.209 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-25T20:17:39.210Z,1729887459.210 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-10-25T20:17:39.217Z,1729887459.217 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-10-25T20:17:39.494Z,1729887459.494 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-10-25T20:17:39.494Z,1729887459.494 [DropWeight] Hardware Fault, FailCount= 1
2024-10-25T20:17:39.494Z,1729887459.494 [DropWeight](ERROR): Hardware Fault
2024-10-25T20:17:39.527Z,1729887459.527 [CommandExec](FAULT): Scheduling is paused
2024-10-25T20:17:39.533Z,1729887459.533 [CBIT](INFO): Critical error at 20241025T201739
2024-10-25T20:17:39.533Z,1729887459.533 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-10-25T20:17:39.536Z,1729887459.536 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-10-25T20:17:39.536Z,1729887459.536 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-10-25T20:17:39.877Z,1729887459.877 [CBIT](INFO): Critical error at 20241025T201739
2024-10-25T20:17:41.057Z,1729887461.057 [WetLabsBB2FL](INFO): Powering down
2024-10-25T20:17:41.071Z,1729887461.071 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-10-25T20:17:42.357Z,1729887462.357 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2024-10-25T20:17:44.933Z,1729887464.933 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-10-25T20:17:45.469Z,1729887465.469 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-10-25T20:18:06.930Z,1729887486.930 [NAL9602](INFO): Powering up NAL9602
2024-10-25T20:18:17.838Z,1729887497.838 [NAL9602](INFO): NAL9602 initialized
2024-10-25T20:18:25.136Z,1729887505.136 [SBIT](IMPORTANT): Beginning Startup BIT
2024-10-25T20:18:25.145Z,1729887505.145 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-25T20:18:36.056Z,1729887516.056 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002822
CHAN A1 (24V): 0.000783
CHAN A2 (12V): -0.002029
CHAN A3 (5V): -0.001291
CHAN B0 (3.3V): 0.000353
CHAN B1 (3.15aV): 0.000202
CHAN B2 (3.15bV): 0.000311
CHAN B3 (GND): 0.000029
OPEN: -0.000523
Full Scale: +/- 1 mA
2024-10-25T20:19:19.287Z,1729887559.287 [SBIT](IMPORTANT): SBIT PASSED
2024-10-25T20:19:19.287Z,1729887559.287 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-10-25T20:19:19.288Z,1729887559.288 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool;
2024-10-25T20:19:19.289Z,1729887559.289 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool;
2024-10-25T20:19:19.289Z,1729887559.289 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-10-25T20:19:19.291Z,1729887559.291 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-10-25T20:19:19.291Z,1729887559.291 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-10-25T20:19:19.291Z,1729887559.291 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-10-25T20:19:19.291Z,1729887559.291 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-10-25T20:19:19.291Z,1729887559.291 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2024-10-25T20:19:19.291Z,1729887559.291 [SBIT](IMPORTANT): HorizontalControl.rudLimit=9 degree;
2024-10-25T20:19:19.292Z,1729887559.292 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=1 bool;
2024-10-25T20:19:19.292Z,1729887559.292 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=315.16 cubic_centimeter;
2024-10-25T20:19:19.292Z,1729887559.292 [SBIT](IMPORTANT): VerticalControl.elevLimit=9 degree;
2024-10-25T20:19:19.292Z,1729887559.292 [SBIT](IMPORTANT): VerticalControl.massDefault=6.624070 millimeter;
2024-10-25T20:19:19.697Z,1729887559.697 [MissionManager](IMPORTANT): Started mission Startup
2024-10-25T20:19:19.697Z,1729887559.697 [Startup] Running Loop=1
2024-10-25T20:19:19.697Z,1729887559.697 [Startup](DEBUG): Aggregate::initialize Startup
2024-10-25T20:19:19.697Z,1729887559.697 [Startup:A.GoToSurface] Running Loop=1
2024-10-25T20:19:19.697Z,1729887559.697 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-25T20:19:19.698Z,1729887559.698 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-25T20:19:19.698Z,1729887559.698 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-25T20:19:19.699Z,1729887559.699 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-25T20:19:19.699Z,1729887559.699 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-25T20:19:19.699Z,1729887559.699 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-25T20:19:19.700Z,1729887559.700 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-25T20:19:19.701Z,1729887559.701 [Startup:StartupSatComms] Running Loop=1
2024-10-25T20:19:19.702Z,1729887559.702 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-10-25T20:19:19.702Z,1729887559.702 [Startup:StartupSatComms:A] Running Loop=1
2024-10-25T20:19:20.087Z,1729887560.087 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-10-25T20:20:19.916Z,1729887619.916 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-25T20:19:19.7Z
2024-10-25T20:20:19.916Z,1729887619.916 [Startup:StartupSatComms:A] Stopped
2024-10-25T20:20:19.917Z,1729887619.917 [Startup:StartupSatComms:B] Running Loop=1
2024-10-25T20:20:20.271Z,1729887620.271 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-25T20:20:38.470Z,1729887638.470 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-25T20:20:38.470Z,1729887638.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-25T20:20:38.480Z,1729887638.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-25T20:20:38.880Z,1729887638.880 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-25T20:20:38.880Z,1729887638.880 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-25T20:21:13.660Z,1729887673.660 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004728
2024-10-25T20:21:20.126Z,1729887680.126 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-25T20:20:19.9Z
2024-10-25T20:21:20.126Z,1729887680.126 [Startup:StartupSatComms:B] Stopped
2024-10-25T20:21:20.127Z,1729887680.127 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-10-25T20:21:20.127Z,1729887680.127 [Startup:StartupSatComms] Stopped
2024-10-25T20:21:20.127Z,1729887680.127 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-10-25T20:21:20.149Z,1729887680.149 [Startup](INFO): Completed Startup
2024-10-25T20:21:20.149Z,1729887680.149 [MissionManager](INFO): Startup is completed.
2024-10-25T20:21:20.149Z,1729887680.149 [MissionManager](INFO): Uninitializing Mission Startup
2024-10-25T20:21:20.149Z,1729887680.149 [Startup] Stopped
2024-10-25T20:21:20.149Z,1729887680.149 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-10-25T20:21:20.149Z,1729887680.149 [Startup:A.GoToSurface] Stopped
2024-10-25T20:21:20.149Z,1729887680.149 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-25T20:21:20.499Z,1729887680.499 [MissionManager](IMPORTANT): Started mission Default
2024-10-25T20:21:20.499Z,1729887680.499 [Default] Running Loop=1
2024-10-25T20:21:20.499Z,1729887680.499 [Default](DEBUG): Aggregate::initialize Default
2024-10-25T20:21:20.499Z,1729887680.499 [Default:B.GoToSurface] Running Loop=1
2024-10-25T20:21:20.499Z,1729887680.499 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-25T20:21:20.499Z,1729887680.499 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-25T20:21:20.500Z,1729887680.500 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-25T20:21:20.500Z,1729887680.500 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-25T20:21:20.500Z,1729887680.500 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-25T20:21:20.513Z,1729887680.513 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-25T20:21:20.513Z,1729887680.513 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-25T20:21:20.514Z,1729887680.514 [Default:A.Wait] Running Loop=1
2024-10-25T20:21:20.514Z,1729887680.514 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-10-25T20:21:33.827Z,1729887693.827 [Default:A.Wait](INFO): Done Waiting.
2024-10-25T20:21:33.827Z,1729887693.827 [Default:A.Wait] Stopped
2024-10-25T20:21:33.827Z,1729887693.827 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-10-25T20:21:34.219Z,1729887694.219 [Default:CheckIn] Running Loop=1
2024-10-25T20:21:34.219Z,1729887694.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-25T20:21:34.220Z,1729887694.220 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-25T20:21:34.619Z,1729887694.619 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-10-25T20:22:28.652Z,1729887748.652 [CommandExec](IMPORTANT): got command strobe off
2024-10-25T20:22:28.652Z,1729887748.652 [CommandExec](IMPORTANT): Deactivating strobe
2024-10-25T20:22:30.209Z,1729887750.209 [CommandExec](IMPORTANT): got command failComponent
2024-10-25T20:22:30.209Z,1729887750.209 [CommandExec](IMPORTANT): Failed components:
2024-10-25T20:22:30.210Z,1729887750.210 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-10-25T20:22:34.486Z,1729887754.486 [CommandLine](INFO): End of History
2024-10-25T20:22:34.533Z,1729887754.533 [CommandLine](INFO): End of History
2024-10-25T20:22:34.534Z,1729887754.534 [CommandLine](INFO): End of History
2024-10-25T20:22:34.535Z,1729887754.535 [CommandLine](INFO): End of History
2024-10-25T20:22:34.966Z,1729887754.966 [CommandLine](INFO): End of History
2024-10-25T20:22:34.970Z,1729887754.970 [CommandLine](INFO): End of History
2024-10-25T20:22:34.975Z,1729887754.975 [CommandLine](INFO): End of History
2024-10-25T20:22:34.978Z,1729887754.978 [CommandLine](INFO): End of History
2024-10-25T20:22:34.980Z,1729887754.980 [CommandLine](INFO): End of History
2024-10-25T20:22:34.981Z,1729887754.981 [CommandLine](INFO): End of History
2024-10-25T20:22:34.991Z,1729887754.991 [CommandLine](INFO): End of History
2024-10-25T20:22:34.996Z,1729887754.996 [CommandLine](INFO): End of History
2024-10-25T20:22:35.002Z,1729887755.002 [CommandLine](INFO): End of History
2024-10-25T20:22:35.079Z,1729887755.079 [CommandLine](INFO): End of History
2024-10-25T20:22:35.080Z,1729887755.080 [CommandLine](INFO): End of History
2024-10-25T20:22:35.082Z,1729887755.082 [CommandLine](INFO): End of History
2024-10-25T20:22:35.086Z,1729887755.086 [CommandLine](INFO): End of History
2024-10-25T20:22:35.088Z,1729887755.088 [CommandLine](INFO): End of History
2024-10-25T20:22:35.089Z,1729887755.089 [CommandLine](INFO): End of History
2024-10-25T20:22:35.444Z,1729887755.444 [CommandLine](INFO): End of History
2024-10-25T20:22:35.445Z,1729887755.445 [CommandLine](INFO): End of History
2024-10-25T20:22:35.446Z,1729887755.446 [CommandLine](INFO): End of History
2024-10-25T20:22:35.455Z,1729887755.455 [CommandLine](INFO): End of History
2024-10-25T20:22:35.456Z,1729887755.456 [CommandLine](INFO): End of History
2024-10-25T20:22:35.458Z,1729887755.458 [CommandLine](INFO): End of History
2024-10-25T20:22:35.467Z,1729887755.467 [CommandLine](INFO): End of History
2024-10-25T20:22:35.468Z,1729887755.468 [CommandLine](INFO): End of History
2024-10-25T20:22:35.473Z,1729887755.473 [CommandLine](INFO): End of History
2024-10-25T20:22:35.480Z,1729887755.480 [CommandLine](INFO): End of History
2024-10-25T20:22:35.482Z,1729887755.482 [CommandLine](INFO): End of History
2024-10-25T20:22:35.483Z,1729887755.483 [CommandLine](INFO): End of History
2024-10-25T20:22:35.530Z,1729887755.530 [CommandLine](INFO): End of History
2024-10-25T20:22:35.531Z,1729887755.531 [CommandLine](INFO): End of History
2024-10-25T20:22:35.533Z,1729887755.533 [CommandLine](INFO): End of History
2024-10-25T20:22:35.541Z,1729887755.541 [CommandLine](INFO): End of History
2024-10-25T20:22:35.542Z,1729887755.542 [CommandLine](INFO): End of History
2024-10-25T20:22:35.544Z,1729887755.544 [CommandLine](INFO): End of History
2024-10-25T20:22:36.174Z,1729887756.174 [CommandLine](INFO): End of History
2024-10-25T20:22:36.179Z,1729887756.179 [CommandLine](INFO): End of History
2024-10-25T20:22:36.185Z,1729887756.185 [CommandLine](INFO): End of History
2024-10-25T20:22:36.346Z,1729887756.346 [CommandLine](INFO): End of History
2024-10-25T20:22:36.347Z,1729887756.347 [CommandLine](INFO): End of History
2024-10-25T20:22:36.348Z,1729887756.348 [CommandLine](INFO): End of History
2024-10-25T20:22:36.437Z,1729887756.437 [CommandLine](INFO): End of History
2024-10-25T20:22:36.438Z,1729887756.438 [CommandLine](INFO): End of History
2024-10-25T20:22:36.439Z,1729887756.439 [CommandLine](INFO): End of History
2024-10-25T20:22:36.559Z,1729887756.559 [CommandLine](INFO): End of History
2024-10-25T20:22:36.564Z,1729887756.564 [CommandLine](INFO): End of History
2024-10-25T20:22:36.573Z,1729887756.573 [CommandLine](INFO): End of History
2024-10-25T20:22:39.249Z,1729887759.249 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:22:45.814Z,1729887765.814 [CommandExec](IMPORTANT): got command strobe off
2024-10-25T20:22:45.814Z,1729887765.814 [CommandExec](IMPORTANT): Deactivating strobe
2024-10-25T20:22:48.315Z,1729887768.315 [CommandExec](IMPORTANT): got command failComponent
2024-10-25T20:22:48.315Z,1729887768.315 [CommandExec](IMPORTANT): Failed components:
2024-10-25T20:22:48.316Z,1729887768.316 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-10-25T20:22:52.581Z,1729887772.581 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:23:02.024Z,1729887782.024 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241025T170952/Courier0007.lzma
2024-10-25T20:23:03.026Z,1729887783.026 [DataOverHttps](INFO): Moved sent file to Logs/20241025T170952/Courier0007.lzma.bak
2024-10-25T20:23:03.026Z,1729887783.026 [DataOverHttps](INFO): SBD MOMSN=23340220
2024-10-25T20:23:05.914Z,1729887785.914 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:23:19.246Z,1729887799.246 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:23:21.674Z,1729887801.674 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-25T20:23:32.578Z,1729887812.578 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:23:39.489Z,1729887819.489 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-25T20:23:39.489Z,1729887819.489 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-25T20:23:39.499Z,1729887819.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-25T20:23:39.874Z,1729887819.874 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-25T20:23:39.874Z,1729887819.874 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-25T20:23:45.909Z,1729887825.909 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:23:59.242Z,1729887839.242 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:24:12.574Z,1729887852.574 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:24:25.101Z,1729887865.101 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:24:38.026Z,1729887878.026 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:24:51.762Z,1729887891.762 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-25T20:26:34.422Z,1729887994.422 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-25T20:21:34.2Z
2024-10-25T20:26:34.422Z,1729887994.422 [Default:CheckIn:Read_GPS] Stopped
2024-10-25T20:26:34.422Z,1729887994.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-25T20:26:34.847Z,1729887994.847 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-25T20:26:40.485Z,1729888000.485 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-10-25T20:26:40.485Z,1729888000.485 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-25T20:26:40.520Z,1729888000.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-25T20:26:40.887Z,1729888000.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-25T20:26:40.888Z,1729888000.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-10-25T20:26:42.633Z,1729888002.633 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20241025T185830/Courier0000.lzma
2024-10-25T20:26:43.589Z,1729888003.589 [CommandExec](IMPORTANT): got command failComponent
2024-10-25T20:26:43.589Z,1729888003.589 [CommandExec](IMPORTANT): Failed components:
2024-10-25T20:26:43.589Z,1729888003.589 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-10-25T20:26:43.634Z,1729888003.634 [DataOverHttps](INFO): Moved sent file to Logs/20241025T185830/Courier0000.lzma.bak
2024-10-25T20:26:43.634Z,1729888003.634 [DataOverHttps](INFO): SBD MOMSN=23340322
2024-10-25T20:26:46.714Z,1729888006.714 [CommandExec](IMPORTANT): got command restart application
2024-10-25T20:26:47.717Z,1729888007.717 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:47.717Z,1729888007.717 [CommandExec](INFO): Uninitializing the command executive.
2024-10-25T20:26:47.717Z,1729888007.717 [CommandExec](INFO): Uninitializing the command scheduler.
2024-10-25T20:26:47.717Z,1729888007.717 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:47.772Z,1729888007.772 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-10-25T20:26:47.773Z,1729888007.773 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-10-25T20:26:47.773Z,1729888007.773 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:47.773Z,1729888007.773 [NavChartDb](INFO): Join timeout helper Thread ID is 8677
2024-10-25T20:26:47.881Z,1729888007.881 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:47.881Z,1729888007.881 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:47.889Z,1729888007.889 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-10-25T20:26:47.889Z,1729888007.889 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:47.889Z,1729888007.889 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8678
2024-10-25T20:26:48.113Z,1729888008.113 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:48.113Z,1729888008.113 [WetLabsBB2FL](INFO): Powering down
2024-10-25T20:26:48.114Z,1729888008.114 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:48.117Z,1729888008.117 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-10-25T20:26:48.117Z,1729888008.117 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:48.117Z,1729888008.117 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8679
2024-10-25T20:26:48.725Z,1729888008.725 [CTD_Seabird](INFO): Powering down
2024-10-25T20:26:48.737Z,1729888008.737 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:48.737Z,1729888008.737 [CTD_Seabird](INFO): Powering down
2024-10-25T20:26:48.749Z,1729888008.749 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:48.757Z,1729888008.757 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-10-25T20:26:48.757Z,1729888008.757 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:48.757Z,1729888008.757 [Radio_Surface](INFO): Join timeout helper Thread ID is 8680
2024-10-25T20:26:49.105Z,1729888009.105 [Radio_Surface](INFO): Powering down
2024-10-25T20:26:49.106Z,1729888009.106 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:49.106Z,1729888009.106 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:49.113Z,1729888009.113 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-10-25T20:26:49.113Z,1729888009.113 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:49.113Z,1729888009.113 [Onboard](INFO): Join timeout helper Thread ID is 8681
2024-10-25T20:26:53.861Z,1729888013.861 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:53.861Z,1729888013.861 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:53.881Z,1729888013.881 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-10-25T20:26:53.881Z,1729888013.881 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:53.882Z,1729888013.882 [DataOverHttps](INFO): Join timeout helper Thread ID is 8682
2024-10-25T20:26:54.357Z,1729888014.357 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:54.360Z,1729888014.360 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.365Z,1729888014.365 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-10-25T20:26:54.366Z,1729888014.366 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.366Z,1729888014.366 [logger](INFO): Join timeout helper Thread ID is 8683
2024-10-25T20:26:54.405Z,1729888014.405 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:54.405Z,1729888014.405 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.425Z,1729888014.425 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-10-25T20:26:54.425Z,1729888014.425 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.426Z,1729888014.426 [CommandLine](INFO): Join timeout helper Thread ID is 8684
2024-10-25T20:26:54.513Z,1729888014.513 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:54.513Z,1729888014.513 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.525Z,1729888014.525 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-10-25T20:26:54.525Z,1729888014.525 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.526Z,1729888014.526 [CommandExec](INFO): Join timeout helper Thread ID is 8685
2024-10-25T20:26:54.527Z,1729888014.527 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-10-25T20:26:54.527Z,1729888014.527 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.527Z,1729888014.527 [controlThread](INFO): Join timeout helper Thread ID is 8686
2024-10-25T20:26:54.569Z,1729888014.569 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-25T20:26:54.569Z,1729888014.569 [controlThread](DEBUG): Uninitializing ControlThread
2024-10-25T20:26:54.569Z,1729888014.569 [AHRS_M2](INFO): Powering down
2024-10-25T20:26:54.642Z,1729888014.642 [NAL9602](INFO): Powering down
2024-10-25T20:26:54.643Z,1729888014.643 [Sonardyne_Nano](INFO): Powering down
2024-10-25T20:26:54.761Z,1729888014.761 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-10-25T20:26:54.762Z,1729888014.762 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-10-25T20:26:54.762Z,1729888014.762 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-10-25T20:26:54.763Z,1729888014.763 [MissionManager](INFO): Uninitializing Mission Default
2024-10-25T20:26:54.763Z,1729888014.763 [Default] Stopped
2024-10-25T20:26:54.763Z,1729888014.763 [Default](DEBUG): Aggregate::uninitialize Default
2024-10-25T20:26:54.763Z,1729888014.763 [Default:B.GoToSurface] Stopped
2024-10-25T20:26:54.763Z,1729888014.763 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-25T20:26:54.763Z,1729888014.763 [Default:CheckIn] Stopped
2024-10-25T20:26:54.763Z,1729888014.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-25T20:26:54.764Z,1729888014.764 [Default:CheckIn:Read_Iridium] Stopped
2024-10-25T20:26:54.766Z,1729888014.766 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-10-25T20:26:54.767Z,1729888014.767 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-10-25T20:26:54.767Z,1729888014.767 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-10-25T20:26:54.767Z,1729888014.767 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-10-25T20:26:54.768Z,1729888014.768 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-10-25T20:26:54.768Z,1729888014.768 [BuoyancyServo](INFO): Powering down
2024-10-25T20:26:54.781Z,1729888014.781 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-10-25T20:26:54.781Z,1729888014.781 [ElevatorServo](INFO): Powering down
2024-10-25T20:26:54.782Z,1729888014.782 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-10-25T20:26:54.782Z,1729888014.782 [MassServo](INFO): Powering down
2024-10-25T20:26:54.782Z,1729888014.782 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-10-25T20:26:54.783Z,1729888014.783 [RudderServo](INFO): Powering down
2024-10-25T20:26:54.783Z,1729888014.783 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-10-25T20:26:54.783Z,1729888014.783 [ThrusterHE](INFO): Powering down
2024-10-25T20:26:54.784Z,1729888014.784 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-10-25T20:26:54.785Z,1729888014.785 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-10-25T20:26:54.785Z,1729888014.785 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-10-25T20:26:54.785Z,1729888014.785 [CBIT](DEBUG): Powering off loads.
2024-10-25T20:26:54.796Z,1729888014.796 [CBIT](DEBUG): Disabling WDT.
2024-10-25T20:26:54.808Z,1729888014.808 [CBIT](DEBUG): Opening all GF detection circuits.
2024-10-25T20:26:54.809Z,1729888014.809 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.864Z,1729888014.864 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.867Z,1729888014.867 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.872Z,1729888014.872 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.944Z,1729888014.944 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.948Z,1729888014.948 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:54.989Z,1729888014.989 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-25T20:26:55.051Z,1729888015.051 [logger ThreadHandler](INFO): Thread cancelled.