2024-10-28T15:16:31.293Z,1730128591.293 [Supervisor](DEBUG): Initializing supervisor.
2024-10-28T15:16:31.297Z,1730128591.297 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-10-28T15:16:31.298Z,1730128591.298 [SyncHandler](INFO): Protected caller Thread ID is 9982
2024-10-28T15:16:31.298Z,1730128591.298 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-10-28T15:16:31.299Z,1730128591.299 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-10-28T15:16:31.299Z,1730128591.299 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9983
2024-10-28T15:16:31.303Z,1730128591.303 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-10-28T15:16:31.320Z,1730128591.320 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-10-28T15:16:31.321Z,1730128591.321 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-10-28T15:16:31.321Z,1730128591.321 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9984
2024-10-28T15:16:31.325Z,1730128591.325 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-10-28T15:16:31.326Z,1730128591.326 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-10-28T15:16:31.327Z,1730128591.327 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9985
2024-10-28T15:16:31.329Z,1730128591.329 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-10-28T15:16:31.330Z,1730128591.330 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-10-28T15:16:31.330Z,1730128591.330 [logger ThreadHandler](INFO): Protected caller Thread ID is 9986
2024-10-28T15:16:31.333Z,1730128591.333 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-10-28T15:16:31.334Z,1730128591.334 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-10-28T15:16:31.335Z,1730128591.335 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-10-28T15:16:31.609Z,1730128591.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-10-28T15:16:31.610Z,1730128591.610 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-10-28T15:16:31.702Z,1730128591.702 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-10-28T15:16:32.245Z,1730128592.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-10-28T15:16:32.246Z,1730128592.246 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-10-28T15:16:32.602Z,1730128592.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-10-28T15:16:32.602Z,1730128592.602 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-10-28T15:16:32.710Z,1730128592.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-10-28T15:16:32.710Z,1730128592.710 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-10-28T15:16:33.131Z,1730128593.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-10-28T15:16:33.131Z,1730128593.131 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-10-28T15:16:33.357Z,1730128593.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-10-28T15:16:33.357Z,1730128593.357 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-10-28T15:16:33.903Z,1730128593.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-10-28T15:16:33.904Z,1730128593.904 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-10-28T15:16:34.051Z,1730128594.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-10-28T15:16:34.051Z,1730128594.051 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-10-28T15:16:34.142Z,1730128594.142 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-10-28T15:16:34.995Z,1730128594.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-10-28T15:16:34.996Z,1730128594.996 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-10-28T15:16:35.393Z,1730128595.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-10-28T15:16:35.393Z,1730128595.393 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-10-28T15:16:35.591Z,1730128595.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-10-28T15:16:35.592Z,1730128595.592 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-10-28T15:16:35.714Z,1730128595.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-10-28T15:16:35.714Z,1730128595.714 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-10-28T15:16:35.953Z,1730128595.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-10-28T15:16:35.953Z,1730128595.953 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-10-28T15:16:36.573Z,1730128596.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-10-28T15:16:36.575Z,1730128596.575 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-10-28T15:16:36.575Z,1730128596.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-10-28T15:16:36.808Z,1730128596.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-10-28T15:16:37.006Z,1730128597.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-10-28T15:16:37.119Z,1730128597.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-10-28T15:16:37.214Z,1730128597.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-10-28T15:16:37.327Z,1730128597.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-10-28T15:16:37.431Z,1730128597.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-10-28T15:16:37.559Z,1730128597.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-10-28T15:16:37.723Z,1730128597.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-10-28T15:16:37.885Z,1730128597.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-10-28T15:16:38.007Z,1730128598.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-10-28T15:16:38.279Z,1730128598.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-10-28T15:16:38.279Z,1730128598.279 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-10-28T15:16:38.287Z,1730128598.287 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-10-28T15:16:38.338Z,1730128598.338 [VerticalControl](DEBUG): Construct VerticalControl.
2024-10-28T15:16:38.396Z,1730128598.396 [VerticalControl] Loaded
2024-10-28T15:16:38.396Z,1730128598.396 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-10-28T15:16:38.399Z,1730128598.399 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-10-28T15:16:38.444Z,1730128598.444 [HorizontalControl] Loaded
2024-10-28T15:16:38.445Z,1730128598.445 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-10-28T15:16:38.447Z,1730128598.447 [SpeedControl](DEBUG): Construct SpeedControl.
2024-10-28T15:16:38.450Z,1730128598.450 [SpeedControl] Loaded
2024-10-28T15:16:38.450Z,1730128598.450 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-10-28T15:16:38.453Z,1730128598.453 [LoopControl](DEBUG): Construct LoopControl.
2024-10-28T15:16:38.453Z,1730128598.453 [LoopControl] Loaded
2024-10-28T15:16:38.453Z,1730128598.453 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-10-28T15:16:38.454Z,1730128598.454 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-10-28T15:16:38.454Z,1730128598.454 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-10-28T15:16:38.464Z,1730128598.464 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-10-28T15:16:38.465Z,1730128598.465 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-10-28T15:16:38.627Z,1730128598.627 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-10-28T15:16:38.628Z,1730128598.628 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-10-28T15:16:38.755Z,1730128598.755 [BuoyancyServo] Loaded
2024-10-28T15:16:38.755Z,1730128598.755 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-10-28T15:16:38.777Z,1730128598.777 [ElevatorServo] Loaded
2024-10-28T15:16:38.777Z,1730128598.777 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-10-28T15:16:38.797Z,1730128598.797 [MassServo] Loaded
2024-10-28T15:16:38.797Z,1730128598.797 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-10-28T15:16:38.817Z,1730128598.817 [RudderServo] Loaded
2024-10-28T15:16:38.818Z,1730128598.818 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-10-28T15:16:38.834Z,1730128598.834 [ThrusterHE] Loaded
2024-10-28T15:16:38.834Z,1730128598.834 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-10-28T15:16:38.834Z,1730128598.834 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-10-28T15:16:38.835Z,1730128598.835 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-10-28T15:16:38.942Z,1730128598.942 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-10-28T15:16:38.943Z,1730128598.943 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-10-28T15:16:38.956Z,1730128598.956 [NavChart] Loaded
2024-10-28T15:16:38.956Z,1730128598.956 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-10-28T15:16:38.961Z,1730128598.961 [UniversalFixResidualReporter] Loaded
2024-10-28T15:16:38.962Z,1730128598.962 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-10-28T15:16:38.962Z,1730128598.962 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-10-28T15:16:38.963Z,1730128598.963 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-10-28T15:16:39.017Z,1730128599.017 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-10-28T15:16:39.017Z,1730128599.017 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-10-28T15:16:39.435Z,1730128599.435 [AHRS_M2] Loaded
2024-10-28T15:16:39.435Z,1730128599.435 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-10-28T15:16:40.254Z,1730128600.254 [BPC1] Loaded
2024-10-28T15:16:40.254Z,1730128600.254 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-10-28T15:16:40.326Z,1730128600.326 [DataOverHttps] Loaded
2024-10-28T15:16:40.327Z,1730128600.327 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-10-28T15:16:40.328Z,1730128600.328 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-10-28T15:16:40.328Z,1730128600.328 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 368
2024-10-28T15:16:40.351Z,1730128600.351 [Depth_Keller] Loaded
2024-10-28T15:16:40.351Z,1730128600.351 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-10-28T15:16:40.356Z,1730128600.356 [DropWeight] Loaded
2024-10-28T15:16:40.356Z,1730128600.356 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-10-28T15:16:40.419Z,1730128600.419 [NAL9602] Loaded
2024-10-28T15:16:40.419Z,1730128600.419 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-10-28T15:16:40.448Z,1730128600.448 [Onboard] Loaded
2024-10-28T15:16:40.449Z,1730128600.449 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-10-28T15:16:40.450Z,1730128600.450 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-10-28T15:16:40.450Z,1730128600.450 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 369
2024-10-28T15:16:40.465Z,1730128600.465 [Power24vConverter] Loaded
2024-10-28T15:16:40.465Z,1730128600.465 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-10-28T15:16:40.480Z,1730128600.480 [Radio_Surface] Loaded
2024-10-28T15:16:40.480Z,1730128600.480 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-10-28T15:16:40.481Z,1730128600.481 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-10-28T15:16:40.481Z,1730128600.481 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 370
2024-10-28T15:16:40.496Z,1730128600.496 [Sonardyne_Nano] Loaded
2024-10-28T15:16:40.497Z,1730128600.497 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-10-28T15:16:40.497Z,1730128600.497 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-10-28T15:16:40.498Z,1730128600.498 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-10-28T15:16:40.545Z,1730128600.545 [DepthRateCalculator] Loaded
2024-10-28T15:16:40.545Z,1730128600.545 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-10-28T15:16:40.550Z,1730128600.550 [PitchRateCalculator] Loaded
2024-10-28T15:16:40.551Z,1730128600.551 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-10-28T15:16:40.559Z,1730128600.559 [SpeedCalculator] Loaded
2024-10-28T15:16:40.559Z,1730128600.559 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-10-28T15:16:40.563Z,1730128600.563 [YawRateCalculator] Loaded
2024-10-28T15:16:40.564Z,1730128600.564 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-10-28T15:16:40.582Z,1730128600.582 [ElevatorOffsetCalculator] Loaded
2024-10-28T15:16:40.582Z,1730128600.582 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-10-28T15:16:40.583Z,1730128600.583 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-10-28T15:16:40.583Z,1730128600.583 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-10-28T15:16:40.713Z,1730128600.713 [CANONSampler] Loaded
2024-10-28T15:16:40.714Z,1730128600.714 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-10-28T15:16:40.776Z,1730128600.776 [CTD_Seabird] Loaded
2024-10-28T15:16:40.776Z,1730128600.776 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-10-28T15:16:40.778Z,1730128600.778 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2024-10-28T15:16:40.778Z,1730128600.778 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 371
2024-10-28T15:16:40.800Z,1730128600.800 [PAR_Licor] Loaded
2024-10-28T15:16:40.800Z,1730128600.800 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-10-28T15:16:40.844Z,1730128600.844 [WetLabsBB2FL] Loaded
2024-10-28T15:16:40.845Z,1730128600.845 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-10-28T15:16:40.846Z,1730128600.846 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-10-28T15:16:40.846Z,1730128600.846 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 372
2024-10-28T15:16:40.847Z,1730128600.847 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-10-28T15:16:40.847Z,1730128600.847 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-10-28T15:16:40.872Z,1730128600.872 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-10-28T15:16:40.873Z,1730128600.873 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-10-28T15:16:41.194Z,1730128601.194 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-10-28T15:16:41.194Z,1730128601.194 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-10-28T15:16:41.343Z,1730128601.343 [SBIT](DEBUG): Construct Startup Built In Test.
2024-10-28T15:16:41.391Z,1730128601.391 [SBIT] Loaded
2024-10-28T15:16:41.391Z,1730128601.391 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-10-28T15:16:41.394Z,1730128601.394 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-10-28T15:16:41.427Z,1730128601.427 [IBIT] Loaded
2024-10-28T15:16:41.427Z,1730128601.427 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-10-28T15:16:41.439Z,1730128601.439 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-10-28T15:16:41.541Z,1730128601.541 [CBIT] Loaded
2024-10-28T15:16:41.541Z,1730128601.541 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-10-28T15:16:41.542Z,1730128601.542 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-10-28T15:16:41.542Z,1730128601.542 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-10-28T15:16:41.698Z,1730128601.698 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-10-28T15:16:41.704Z,1730128601.704 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-10-28T15:16:41.707Z,1730128601.707 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-10-28T15:16:41.719Z,1730128601.719 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-10-28T15:16:41.720Z,1730128601.720 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-10-28T15:16:41.720Z,1730128601.720 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 373
2024-10-28T15:16:41.724Z,1730128601.724 [Supervisor](INFO): Main Thread ID is 9981
2024-10-28T15:16:41.725Z,1730128601.725 [Supervisor](DEBUG): Running supervisor.
2024-10-28T15:16:41.725Z,1730128601.725 [CommandExec ThreadHandler](INFO): Handler Thread ID is 374
2024-10-28T15:16:41.726Z,1730128601.726 [CommandExec](INFO): Initializing the command executive.
2024-10-28T15:16:41.727Z,1730128601.727 [CommandLine ThreadHandler](INFO): Handler Thread ID is 375
2024-10-28T15:16:41.729Z,1730128601.729 [controlThread ThreadHandler](INFO): Handler Thread ID is 376
2024-10-28T15:16:41.730Z,1730128601.730 [controlThread](DEBUG): Initializing ControlThread
2024-10-28T15:16:41.731Z,1730128601.731 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-10-28T15:16:41.732Z,1730128601.732 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-10-28T15:16:41.733Z,1730128601.733 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-10-28T15:16:41.733Z,1730128601.733 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-10-28T15:16:41.735Z,1730128601.735 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-10-28T15:16:41.736Z,1730128601.736 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-10-28T15:16:41.739Z,1730128601.739 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-10-28T15:16:41.739Z,1730128601.739 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-10-28T15:16:41.740Z,1730128601.740 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-10-28T15:16:41.740Z,1730128601.740 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-10-28T15:16:41.740Z,1730128601.740 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-10-28T15:16:41.745Z,1730128601.745 [SBIT](INFO): Initialize SBIT Component.
2024-10-28T15:16:41.745Z,1730128601.745 [SBIT](IMPORTANT): git: 2024-10-25
2024-10-28T15:16:41.745Z,1730128601.745 [SBIT](INFO): git hash: fb21fbef08ef638e26610dd09246b1b72c2e8aa9
2024-10-28T15:16:41.746Z,1730128601.746 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-10-28T15:16:41.747Z,1730128601.747 [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-28T15:16:41.748Z,1730128601.748 [SBIT](INFO): Beginning SBIT in 21.000000 seconds.
2024-10-28T15:16:41.749Z,1730128601.749 [IBIT](INFO): Initialize IBIT Component.
2024-10-28T15:16:41.750Z,1730128601.750 [CBIT](DEBUG): Initialize CBIT Component.
2024-10-28T15:16:41.750Z,1730128601.750 [logger ThreadHandler](INFO): Handler Thread ID is 377
2024-10-28T15:16:41.761Z,1730128601.761 [CBIT](DEBUG): Initialized mux pins.
2024-10-28T15:16:41.761Z,1730128601.761 [CBIT](DEBUG): Initializing the watchdog timer.
2024-10-28T15:16:41.769Z,1730128601.769 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 378
2024-10-28T15:16:41.771Z,1730128601.771 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-10-28T15:16:41.781Z,1730128601.781 [Onboard ThreadHandler](INFO): Handler Thread ID is 379
2024-10-28T15:16:41.785Z,1730128601.785 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-10-28T15:16:41.786Z,1730128601.786 [CBIT](DEBUG): Initializing heartbeat.
2024-10-28T15:16:41.802Z,1730128601.802 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 380
2024-10-28T15:16:41.817Z,1730128601.817 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 381
2024-10-28T15:16:41.818Z,1730128601.818 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-10-28T15:16:41.822Z,1730128601.822 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 383
2024-10-28T15:16:41.825Z,1730128601.825 [WetLabsBB2FL](INFO): Powering up
2024-10-28T15:16:41.826Z,1730128601.826 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 385
2024-10-28T15:16:41.830Z,1730128601.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-10-28T15:16:41.830Z,1730128601.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-10-28T15:16:41.830Z,1730128601.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-10-28T15:16:41.830Z,1730128601.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-10-28T15:16:41.831Z,1730128601.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-10-28T15:16:41.831Z,1730128601.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-10-28T15:16:41.831Z,1730128601.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-10-28T15:16:41.831Z,1730128601.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-10-28T15:16:41.857Z,1730128601.857 [CBIT](DEBUG): Deactivating GF circuits.
2024-10-28T15:16:41.857Z,1730128601.857 [CBIT](DEBUG): Deactivating emergency mode.
2024-10-28T15:16:41.896Z,1730128601.896 [CBIT](DEBUG): Backplane powered.
2024-10-28T15:16:41.897Z,1730128601.897 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-10-28T15:16:41.898Z,1730128601.898 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-10-28T15:16:41.898Z,1730128601.898 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-10-28T15:16:41.899Z,1730128601.899 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-10-28T15:16:41.942Z,1730128601.942 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-10-28T15:16:41.946Z,1730128601.946 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-28T15:16:41.990Z,1730128601.990 [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-28T15:16:41.991Z,1730128601.991 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-10-28T15:16:41.991Z,1730128601.991 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-10-28T15:16:41.992Z,1730128601.992 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-10-28T15:16:42.205Z,1730128602.205 [Radio_Surface](INFO): Powering up
2024-10-28T15:16:42.267Z,1730128602.267 [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-28T15:16:42.313Z,1730128602.313 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-10-28T15:16:42.314Z,1730128602.314 [Default:A.Wait](DEBUG): Construct Wait.
2024-10-28T15:16:42.316Z,1730128602.316 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-28T15:16:42.357Z,1730128602.357 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-10-28T15:16:42.359Z,1730128602.359 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-10-28T15:16:42.381Z,1730128602.381 [Default:E.Execute](DEBUG): Construct Execute.
2024-10-28T15:16:42.384Z,1730128602.384 [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-28T15:16:42.396Z,1730128602.396 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-10-28T15:16:42.409Z,1730128602.409 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-10-28T15:16:42.475Z,1730128602.475 [Power24vConverter](INFO): Powering up.
2024-10-28T15:16:42.476Z,1730128602.476 [Sonardyne_Nano](INFO): Initializing.
2024-10-28T15:16:42.493Z,1730128602.493 [CANONSampler](INFO): Powering down
2024-10-28T15:16:42.554Z,1730128602.554 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-10-28T15:16:42.561Z,1730128602.561 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-10-28T15:16:42.562Z,1730128602.562 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-10-28T15:16:42.569Z,1730128602.569 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-10-28T15:16:42.570Z,1730128602.570 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-28T15:16:42.577Z,1730128602.577 [MassServo](DEBUG): Initializing MassServo.
2024-10-28T15:16:42.578Z,1730128602.578 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-28T15:16:42.585Z,1730128602.585 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-28T15:16:42.586Z,1730128602.586 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-10-28T15:16:42.593Z,1730128602.593 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-10-28T15:16:44.424Z,1730128604.424 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-10-28T15:16:44.437Z,1730128604.437 [WetLabsBB2FL](INFO): Powering down
2024-10-28T15:16:45.713Z,1730128605.713 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2024-10-28T15:16:46.068Z,1730128606.068 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-10-28T15:16:55.396Z,1730128615.396 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005328
2024-10-28T15:17:03.444Z,1730128623.444 [SBIT](IMPORTANT): Beginning Startup BIT
2024-10-28T15:17:03.452Z,1730128623.452 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-28T15:17:10.690Z,1730128630.690 [NAL9602](INFO): Powering up NAL9602
2024-10-28T15:17:14.846Z,1730128634.846 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-28T15:17:15.213Z,1730128635.213 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-28T15:17:21.598Z,1730128641.598 [NAL9602](INFO): NAL9602 initialized
2024-10-28T15:17:26.170Z,1730128646.170 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-28T15:17:26.566Z,1730128646.566 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-28T15:17:30.909Z,1730128650.909 [CommandExec](IMPORTANT): got command failComponent
2024-10-28T15:17:30.909Z,1730128650.909 [CommandExec](IMPORTANT): Failed components:
2024-10-28T15:17:30.910Z,1730128650.910 [CommandExec](IMPORTANT): No failed Components.
2024-10-28T15:17:37.384Z,1730128657.384 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-28T15:17:37.385Z,1730128657.385 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-10-28T15:17:57.592Z,1730128677.592 [SBIT](IMPORTANT): SBIT PASSED
2024-10-28T15:17:57.592Z,1730128677.592 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-10-28T15:17:57.593Z,1730128677.593 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-10-28T15:17:57.593Z,1730128677.593 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-10-28T15:17:57.593Z,1730128677.593 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-10-28T15:17:57.593Z,1730128677.593 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-10-28T15:17:57.594Z,1730128677.594 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-10-28T15:17:57.594Z,1730128677.594 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=284.301317 cubic_centimeter;
2024-10-28T15:17:57.594Z,1730128677.594 [SBIT](IMPORTANT): VerticalControl.massDefault=0.396365 millimeter;
2024-10-28T15:17:57.967Z,1730128677.967 [MissionManager](IMPORTANT): Started mission Startup
2024-10-28T15:17:57.967Z,1730128677.967 [Startup] Running Loop=1
2024-10-28T15:17:57.967Z,1730128677.967 [Startup](DEBUG): Aggregate::initialize Startup
2024-10-28T15:17:57.967Z,1730128677.967 [Startup:A.GoToSurface] Running Loop=1
2024-10-28T15:17:57.967Z,1730128677.967 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-28T15:17:57.968Z,1730128677.968 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-28T15:17:57.968Z,1730128677.968 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-28T15:17:57.969Z,1730128677.969 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-28T15:17:57.969Z,1730128677.969 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-28T15:17:57.969Z,1730128677.969 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-28T15:17:57.970Z,1730128677.970 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-28T15:17:57.971Z,1730128677.971 [Startup:StartupSatComms] Running Loop=1
2024-10-28T15:17:57.971Z,1730128677.971 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-10-28T15:17:57.972Z,1730128677.972 [Startup:StartupSatComms:A] Running Loop=1
2024-10-28T15:17:58.375Z,1730128678.375 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-10-28T15:18:08.813Z,1730128688.813 [CommandExec](IMPORTANT): got command failComponent
2024-10-28T15:18:08.813Z,1730128688.813 [CommandExec](IMPORTANT): Failed components:
2024-10-28T15:18:08.813Z,1730128688.813 [CommandExec](IMPORTANT): No failed Components.
2024-10-28T15:18:58.243Z,1730128738.243 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-28T15:17:57.0Z
2024-10-28T15:18:58.243Z,1730128738.243 [Startup:StartupSatComms:A] Stopped
2024-10-28T15:18:58.243Z,1730128738.243 [Startup:StartupSatComms:B] Running Loop=1
2024-10-28T15:18:58.653Z,1730128738.653 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-28T15:19:05.312Z,1730128745.312 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241025T232330/Courier0007.lzma
2024-10-28T15:19:06.317Z,1730128746.317 [DataOverHttps](INFO): Moved sent file to Logs/20241025T232330/Courier0007.lzma.bak
2024-10-28T15:19:06.317Z,1730128746.317 [DataOverHttps](INFO): SBD MOMSN=23345840
2024-10-28T15:19:22.249Z,1730128762.249 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241028T151631/Courier0000.lzma
2024-10-28T15:19:23.250Z,1730128763.250 [DataOverHttps](INFO): Moved sent file to Logs/20241028T151631/Courier0000.lzma.bak
2024-10-28T15:19:23.251Z,1730128763.251 [DataOverHttps](INFO): SBD MOMSN=23345842
2024-10-28T15:19:39.184Z,1730128779.184 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20241025T232330/Express0008.lzma
2024-10-28T15:19:40.186Z,1730128780.186 [DataOverHttps](INFO): Moved sent file to Logs/20241025T232330/Express0008.lzma.bak
2024-10-28T15:19:40.187Z,1730128780.187 [DataOverHttps](INFO): SBD MOMSN=23345845
2024-10-28T15:19:41.874Z,1730128781.874 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-28T15:19:41.875Z,1730128781.875 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-28T15:19:41.954Z,1730128781.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-28T15:19:42.313Z,1730128782.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-28T15:19:42.313Z,1730128782.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-28T15:19:52.345Z,1730128792.345 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-10-28T15:19:56.130Z,1730128796.130 [DataOverHttps](INFO): Sending 855 bytes from file Logs/20241028T151631/Express0001.lzma
2024-10-28T15:19:57.130Z,1730128797.130 [DataOverHttps](INFO): Moved sent file to Logs/20241028T151631/Express0001.lzma.bak
2024-10-28T15:19:57.131Z,1730128797.131 [DataOverHttps](INFO): SBD MOMSN=23345849
2024-10-28T15:19:58.443Z,1730128798.443 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-28T15:18:58.2Z
2024-10-28T15:19:58.443Z,1730128798.443 [Startup:StartupSatComms:B] Stopped
2024-10-28T15:19:58.443Z,1730128798.443 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-10-28T15:19:58.443Z,1730128798.443 [Startup:StartupSatComms] Stopped
2024-10-28T15:19:58.443Z,1730128798.443 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-10-28T15:19:58.444Z,1730128798.444 [Startup](INFO): Completed Startup
2024-10-28T15:19:58.444Z,1730128798.444 [MissionManager](INFO): Startup is completed.
2024-10-28T15:19:58.449Z,1730128798.449 [MissionManager](INFO): Uninitializing Mission Startup
2024-10-28T15:19:58.449Z,1730128798.449 [Startup] Stopped
2024-10-28T15:19:58.449Z,1730128798.449 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-10-28T15:19:58.449Z,1730128798.449 [Startup:A.GoToSurface] Stopped
2024-10-28T15:19:58.449Z,1730128798.449 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-28T15:19:58.826Z,1730128798.826 [MissionManager](IMPORTANT): Started mission Default
2024-10-28T15:19:58.827Z,1730128798.827 [Default] Running Loop=1
2024-10-28T15:19:58.827Z,1730128798.827 [Default](DEBUG): Aggregate::initialize Default
2024-10-28T15:19:58.827Z,1730128798.827 [Default:B.GoToSurface] Running Loop=1
2024-10-28T15:19:58.827Z,1730128798.827 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-28T15:19:58.827Z,1730128798.827 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-28T15:19:58.827Z,1730128798.827 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-28T15:19:58.828Z,1730128798.828 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-28T15:19:58.828Z,1730128798.828 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-28T15:19:58.828Z,1730128798.828 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-28T15:19:58.829Z,1730128798.829 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-28T15:19:58.829Z,1730128798.829 [Default:A.Wait] Running Loop=1
2024-10-28T15:19:58.829Z,1730128798.829 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-10-28T15:20:12.183Z,1730128812.183 [Default:A.Wait](INFO): Done Waiting.
2024-10-28T15:20:12.183Z,1730128812.183 [Default:A.Wait] Stopped
2024-10-28T15:20:12.183Z,1730128812.183 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-10-28T15:20:12.586Z,1730128812.586 [Default:CheckIn] Running Loop=1
2024-10-28T15:20:12.586Z,1730128812.586 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-28T15:20:12.586Z,1730128812.586 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-28T15:20:12.983Z,1730128812.983 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-10-28T15:21:44.253Z,1730128904.253 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:21:57.587Z,1730128917.587 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:22:11.321Z,1730128931.321 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:22:24.654Z,1730128944.654 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:22:25.482Z,1730128945.482 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-28T15:22:37.985Z,1730128957.985 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:22:42.850Z,1730128962.850 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-28T15:22:42.850Z,1730128962.850 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-28T15:22:42.917Z,1730128962.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-28T15:22:43.333Z,1730128963.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-28T15:22:43.333Z,1730128963.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-28T15:22:51.318Z,1730128971.318 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:23:05.057Z,1730128985.057 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:23:18.386Z,1730128998.386 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:23:31.718Z,1730129011.718 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:23:45.054Z,1730129025.054 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-28T15:24:33.637Z,1730129073.637 [CommandExec](IMPORTANT): got command quit
2024-10-28T15:24:34.641Z,1730129074.641 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-10-28T15:24:34.641Z,1730129074.641 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:34.641Z,1730129074.641 [CommandExec](INFO): Uninitializing the command executive.
2024-10-28T15:24:34.641Z,1730129074.641 [CommandExec](INFO): Uninitializing the command scheduler.
2024-10-28T15:24:34.641Z,1730129074.641 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:34.768Z,1730129074.768 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-10-28T15:24:34.769Z,1730129074.769 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-10-28T15:24:34.769Z,1730129074.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:34.769Z,1730129074.769 [NavChartDb](INFO): Join timeout helper Thread ID is 448
2024-10-28T15:24:34.933Z,1730129074.933 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:34.933Z,1730129074.933 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:34.937Z,1730129074.937 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-10-28T15:24:34.937Z,1730129074.937 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:34.937Z,1730129074.937 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 449
2024-10-28T15:24:35.325Z,1730129075.325 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:35.325Z,1730129075.325 [WetLabsBB2FL](INFO): Powering down
2024-10-28T15:24:35.326Z,1730129075.326 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:35.337Z,1730129075.337 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-10-28T15:24:35.337Z,1730129075.337 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:35.337Z,1730129075.337 [CTD_Seabird](INFO): Join timeout helper Thread ID is 450
2024-10-28T15:24:35.821Z,1730129075.821 [CTD_Seabird](INFO): Powering down
2024-10-28T15:24:35.833Z,1730129075.833 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:35.833Z,1730129075.833 [CTD_Seabird](INFO): Powering down
2024-10-28T15:24:35.844Z,1730129075.844 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:35.857Z,1730129075.857 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-10-28T15:24:35.857Z,1730129075.857 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:35.857Z,1730129075.857 [Radio_Surface](INFO): Join timeout helper Thread ID is 451
2024-10-28T15:24:36.157Z,1730129076.157 [Radio_Surface](INFO): Powering down
2024-10-28T15:24:36.158Z,1730129076.158 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:36.158Z,1730129076.158 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:36.165Z,1730129076.165 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-10-28T15:24:36.165Z,1730129076.165 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:36.165Z,1730129076.165 [Onboard](INFO): Join timeout helper Thread ID is 452
2024-10-28T15:24:37.169Z,1730129077.169 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:37.169Z,1730129077.169 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:37.174Z,1730129077.174 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-10-28T15:24:37.174Z,1730129077.174 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:37.174Z,1730129077.174 [DataOverHttps](INFO): Join timeout helper Thread ID is 453
2024-10-28T15:24:39.982Z,1730129079.982 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:40.034Z,1730129080.034 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.046Z,1730129080.046 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-10-28T15:24:40.046Z,1730129080.046 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.046Z,1730129080.046 [logger](INFO): Join timeout helper Thread ID is 454
2024-10-28T15:24:40.073Z,1730129080.073 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:40.074Z,1730129080.074 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.089Z,1730129080.089 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-10-28T15:24:40.089Z,1730129080.089 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.090Z,1730129080.090 [CommandLine](INFO): Join timeout helper Thread ID is 455
2024-10-28T15:24:40.169Z,1730129080.169 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:40.169Z,1730129080.169 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.173Z,1730129080.173 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-10-28T15:24:40.173Z,1730129080.173 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.174Z,1730129080.174 [CommandExec](INFO): Join timeout helper Thread ID is 456
2024-10-28T15:24:40.175Z,1730129080.175 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-10-28T15:24:40.175Z,1730129080.175 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.175Z,1730129080.175 [controlThread](INFO): Join timeout helper Thread ID is 457
2024-10-28T15:24:40.389Z,1730129080.389 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-28T15:24:40.389Z,1730129080.389 [controlThread](DEBUG): Uninitializing ControlThread
2024-10-28T15:24:40.389Z,1730129080.389 [AHRS_M2](INFO): Powering down
2024-10-28T15:24:40.462Z,1730129080.462 [NAL9602](INFO): Powering down
2024-10-28T15:24:40.463Z,1730129080.463 [Sonardyne_Nano](INFO): Powering down
2024-10-28T15:24:40.582Z,1730129080.582 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-10-28T15:24:40.583Z,1730129080.583 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-10-28T15:24:40.583Z,1730129080.583 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-10-28T15:24:40.584Z,1730129080.584 [MissionManager](INFO): Uninitializing Mission Default
2024-10-28T15:24:40.584Z,1730129080.584 [Default] Stopped
2024-10-28T15:24:40.584Z,1730129080.584 [Default](DEBUG): Aggregate::uninitialize Default
2024-10-28T15:24:40.584Z,1730129080.584 [Default:B.GoToSurface] Stopped
2024-10-28T15:24:40.584Z,1730129080.584 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-28T15:24:40.584Z,1730129080.584 [Default:CheckIn] Stopped
2024-10-28T15:24:40.584Z,1730129080.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-28T15:24:40.585Z,1730129080.585 [Default:CheckIn:Read_GPS] Stopped
2024-10-28T15:24:40.587Z,1730129080.587 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-10-28T15:24:40.588Z,1730129080.588 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-10-28T15:24:40.588Z,1730129080.588 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-10-28T15:24:40.588Z,1730129080.588 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-10-28T15:24:40.589Z,1730129080.589 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-10-28T15:24:40.589Z,1730129080.589 [BuoyancyServo](INFO): Powering down
2024-10-28T15:24:40.601Z,1730129080.601 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-10-28T15:24:40.601Z,1730129080.601 [ElevatorServo](INFO): Powering down
2024-10-28T15:24:40.602Z,1730129080.602 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-10-28T15:24:40.602Z,1730129080.602 [MassServo](INFO): Powering down
2024-10-28T15:24:40.603Z,1730129080.603 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-10-28T15:24:40.603Z,1730129080.603 [RudderServo](INFO): Powering down
2024-10-28T15:24:40.603Z,1730129080.603 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-10-28T15:24:40.604Z,1730129080.604 [ThrusterHE](INFO): Powering down
2024-10-28T15:24:40.604Z,1730129080.604 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-10-28T15:24:40.605Z,1730129080.605 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-10-28T15:24:40.605Z,1730129080.605 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-10-28T15:24:40.605Z,1730129080.605 [CBIT](DEBUG): Powering off loads.
2024-10-28T15:24:40.616Z,1730129080.616 [CBIT](DEBUG): Disabling WDT.
2024-10-28T15:24:40.628Z,1730129080.628 [CBIT](DEBUG): Opening all GF detection circuits.
2024-10-28T15:24:40.629Z,1730129080.629 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.793Z,1730129080.793 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.796Z,1730129080.796 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.801Z,1730129080.801 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.874Z,1730129080.874 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.878Z,1730129080.878 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.919Z,1730129080.919 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-28T15:24:40.982Z,1730129080.982 [logger ThreadHandler](INFO): Thread cancelled.