2024-04-11T00:13:07.720Z,1712794387.720 [Supervisor](DEBUG): Initializing supervisor.
2024-04-11T00:13:07.724Z,1712794387.724 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-11T00:13:07.725Z,1712794387.725 [SyncHandler](INFO): Protected caller Thread ID is 9671
2024-04-11T00:13:07.725Z,1712794387.725 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-11T00:13:07.726Z,1712794387.726 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-11T00:13:07.726Z,1712794387.726 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9672
2024-04-11T00:13:07.730Z,1712794387.730 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-11T00:13:07.748Z,1712794387.748 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-11T00:13:07.749Z,1712794387.749 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-11T00:13:07.750Z,1712794387.750 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9673
2024-04-11T00:13:07.754Z,1712794387.754 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-11T00:13:07.755Z,1712794387.755 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-11T00:13:07.755Z,1712794387.755 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9674
2024-04-11T00:13:07.757Z,1712794387.757 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-11T00:13:07.758Z,1712794387.758 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-11T00:13:07.759Z,1712794387.759 [logger ThreadHandler](INFO): Protected caller Thread ID is 9675
2024-04-11T00:13:07.762Z,1712794387.762 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-11T00:13:07.763Z,1712794387.763 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-11T00:13:07.764Z,1712794387.764 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-11T00:13:08.036Z,1712794388.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-11T00:13:08.036Z,1712794388.036 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-11T00:13:08.119Z,1712794388.119 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-11T00:13:08.625Z,1712794388.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-11T00:13:08.625Z,1712794388.625 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-11T00:13:08.973Z,1712794388.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-11T00:13:08.974Z,1712794388.974 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-11T00:13:09.073Z,1712794389.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-11T00:13:09.073Z,1712794389.073 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-11T00:13:09.489Z,1712794389.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-11T00:13:09.489Z,1712794389.489 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-11T00:13:09.710Z,1712794389.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-11T00:13:09.710Z,1712794389.710 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-11T00:13:10.249Z,1712794390.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-11T00:13:10.249Z,1712794390.249 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-11T00:13:10.390Z,1712794390.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-11T00:13:10.391Z,1712794390.391 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-11T00:13:10.472Z,1712794390.472 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-11T00:13:11.303Z,1712794391.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-11T00:13:11.303Z,1712794391.303 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-11T00:13:11.691Z,1712794391.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-11T00:13:11.692Z,1712794391.692 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-11T00:13:11.892Z,1712794391.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-11T00:13:11.892Z,1712794391.892 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-11T00:13:12.007Z,1712794392.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-11T00:13:12.008Z,1712794392.008 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-11T00:13:12.247Z,1712794392.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-11T00:13:12.248Z,1712794392.248 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-11T00:13:12.518Z,1712794392.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-11T00:13:12.520Z,1712794392.520 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-04-11T00:13:12.521Z,1712794392.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-04-11T00:13:12.801Z,1712794392.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-04-11T00:13:13.034Z,1712794393.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-04-11T00:13:13.262Z,1712794393.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-04-11T00:13:13.348Z,1712794393.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-04-11T00:13:13.456Z,1712794393.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-04-11T00:13:13.553Z,1712794393.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-04-11T00:13:13.671Z,1712794393.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-04-11T00:13:13.825Z,1712794393.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-04-11T00:13:13.968Z,1712794393.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-04-11T00:13:14.081Z,1712794394.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-04-11T00:13:14.346Z,1712794394.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-11T00:13:14.347Z,1712794394.347 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-04-11T00:13:14.353Z,1712794394.353 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-11T00:13:14.405Z,1712794394.405 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-11T00:13:14.466Z,1712794394.466 [VerticalControl] Loaded
2024-04-11T00:13:14.467Z,1712794394.467 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-11T00:13:14.469Z,1712794394.469 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-11T00:13:14.511Z,1712794394.511 [HorizontalControl] Loaded
2024-04-11T00:13:14.512Z,1712794394.512 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-11T00:13:14.514Z,1712794394.514 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-11T00:13:14.517Z,1712794394.517 [SpeedControl] Loaded
2024-04-11T00:13:14.517Z,1712794394.517 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-11T00:13:14.520Z,1712794394.520 [LoopControl](DEBUG): Construct LoopControl.
2024-04-11T00:13:14.521Z,1712794394.521 [LoopControl] Loaded
2024-04-11T00:13:14.521Z,1712794394.521 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-11T00:13:14.521Z,1712794394.521 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-11T00:13:14.522Z,1712794394.522 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-11T00:13:14.532Z,1712794394.532 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-11T00:13:14.532Z,1712794394.532 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-11T00:13:14.680Z,1712794394.680 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-11T00:13:14.681Z,1712794394.681 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-11T00:13:14.835Z,1712794394.835 [BuoyancyServo] Loaded
2024-04-11T00:13:14.835Z,1712794394.835 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-11T00:13:14.855Z,1712794394.855 [ElevatorServo] Loaded
2024-04-11T00:13:14.855Z,1712794394.855 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-11T00:13:14.874Z,1712794394.874 [MassServo] Loaded
2024-04-11T00:13:14.874Z,1712794394.874 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-11T00:13:14.892Z,1712794394.892 [RudderServo] Loaded
2024-04-11T00:13:14.892Z,1712794394.892 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-11T00:13:14.907Z,1712794394.907 [ThrusterHE] Loaded
2024-04-11T00:13:14.907Z,1712794394.907 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-11T00:13:14.908Z,1712794394.908 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-11T00:13:14.908Z,1712794394.908 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-11T00:13:15.019Z,1712794395.019 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-11T00:13:15.020Z,1712794395.020 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-11T00:13:15.033Z,1712794395.033 [NavChart] Loaded
2024-04-11T00:13:15.033Z,1712794395.033 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-11T00:13:15.039Z,1712794395.039 [UniversalFixResidualReporter] Loaded
2024-04-11T00:13:15.039Z,1712794395.039 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-11T00:13:15.040Z,1712794395.040 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-11T00:13:15.040Z,1712794395.040 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-11T00:13:15.096Z,1712794395.096 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-11T00:13:15.096Z,1712794395.096 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-11T00:13:15.519Z,1712794395.519 [AHRS_M2] Loaded
2024-04-11T00:13:15.520Z,1712794395.520 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-11T00:13:16.330Z,1712794396.330 [BPC1] Loaded
2024-04-11T00:13:16.330Z,1712794396.330 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-11T00:13:16.405Z,1712794396.405 [DataOverHttps] Loaded
2024-04-11T00:13:16.405Z,1712794396.405 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-11T00:13:16.406Z,1712794396.406 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091F4E0
2024-04-11T00:13:16.407Z,1712794396.407 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9757
2024-04-11T00:13:16.427Z,1712794396.427 [Depth_Keller] Loaded
2024-04-11T00:13:16.427Z,1712794396.427 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-11T00:13:16.432Z,1712794396.432 [DropWeight] Loaded
2024-04-11T00:13:16.433Z,1712794396.433 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-11T00:13:16.494Z,1712794396.494 [NAL9602] Loaded
2024-04-11T00:13:16.495Z,1712794396.495 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-11T00:13:16.524Z,1712794396.524 [Onboard] Loaded
2024-04-11T00:13:16.525Z,1712794396.525 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-11T00:13:16.526Z,1712794396.526 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094F4E0
2024-04-11T00:13:16.526Z,1712794396.526 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9758
2024-04-11T00:13:16.539Z,1712794396.539 [Power24vConverter] Loaded
2024-04-11T00:13:16.540Z,1712794396.540 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-11T00:13:16.553Z,1712794396.553 [Radio_Surface] Loaded
2024-04-11T00:13:16.553Z,1712794396.553 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-11T00:13:16.554Z,1712794396.554 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097F4E0
2024-04-11T00:13:16.554Z,1712794396.554 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9759
2024-04-11T00:13:16.575Z,1712794396.575 [AMEcho] Loaded
2024-04-11T00:13:16.576Z,1712794396.576 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2024-04-11T00:13:16.667Z,1712794396.667 [DAT] Loaded
2024-04-11T00:13:16.668Z,1712794396.668 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-11T00:13:16.669Z,1712794396.669 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0
2024-04-11T00:13:16.669Z,1712794396.669 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9760
2024-04-11T00:13:16.670Z,1712794396.670 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-11T00:13:16.670Z,1712794396.670 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-11T00:13:16.720Z,1712794396.720 [DepthRateCalculator] Loaded
2024-04-11T00:13:16.720Z,1712794396.720 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-11T00:13:16.725Z,1712794396.725 [PitchRateCalculator] Loaded
2024-04-11T00:13:16.726Z,1712794396.726 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-11T00:13:16.735Z,1712794396.735 [SpeedCalculator] Loaded
2024-04-11T00:13:16.735Z,1712794396.735 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-11T00:13:16.739Z,1712794396.739 [YawRateCalculator] Loaded
2024-04-11T00:13:16.740Z,1712794396.740 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-11T00:13:16.758Z,1712794396.758 [ElevatorOffsetCalculator] Loaded
2024-04-11T00:13:16.758Z,1712794396.758 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-11T00:13:16.759Z,1712794396.759 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-11T00:13:16.759Z,1712794396.759 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-11T00:13:16.891Z,1712794396.891 [CANONSampler] Loaded
2024-04-11T00:13:16.891Z,1712794396.891 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-04-11T00:13:16.951Z,1712794396.951 [CTD_Seabird] Loaded
2024-04-11T00:13:16.951Z,1712794396.951 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-11T00:13:16.952Z,1712794396.952 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6B4E0
2024-04-11T00:13:16.953Z,1712794396.953 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9761
2024-04-11T00:13:16.973Z,1712794396.973 [PAR_Licor] Loaded
2024-04-11T00:13:16.973Z,1712794396.973 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-11T00:13:17.015Z,1712794397.015 [WetLabsBB2FL] Loaded
2024-04-11T00:13:17.015Z,1712794397.015 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-11T00:13:17.016Z,1712794397.016 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2024-04-11T00:13:17.016Z,1712794397.016 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9762
2024-04-11T00:13:17.017Z,1712794397.017 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-11T00:13:17.018Z,1712794397.018 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-11T00:13:17.045Z,1712794397.045 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-11T00:13:17.045Z,1712794397.045 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-11T00:13:17.389Z,1712794397.389 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-11T00:13:17.390Z,1712794397.390 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-11T00:13:17.533Z,1712794397.533 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-11T00:13:17.542Z,1712794397.542 [SBIT] Loaded
2024-04-11T00:13:17.542Z,1712794397.542 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-11T00:13:17.545Z,1712794397.545 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-11T00:13:17.558Z,1712794397.558 [IBIT] Loaded
2024-04-11T00:13:17.558Z,1712794397.558 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-11T00:13:17.564Z,1712794397.564 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-11T00:13:17.670Z,1712794397.670 [CBIT] Loaded
2024-04-11T00:13:17.671Z,1712794397.671 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-11T00:13:17.671Z,1712794397.671 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-11T00:13:17.672Z,1712794397.672 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-11T00:13:18.088Z,1712794398.088 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-11T00:13:18.273Z,1712794398.273 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-11T00:13:18.277Z,1712794398.277 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-11T00:13:18.289Z,1712794398.289 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-11T00:13:18.290Z,1712794398.290 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF34E0
2024-04-11T00:13:18.290Z,1712794398.290 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9763
2024-04-11T00:13:18.294Z,1712794398.294 [Supervisor](INFO): Main Thread ID is 9529
2024-04-11T00:13:18.295Z,1712794398.295 [Supervisor](DEBUG): Running supervisor.
2024-04-11T00:13:18.295Z,1712794398.295 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9764
2024-04-11T00:13:18.296Z,1712794398.296 [CommandExec](INFO): Initializing the command executive.
2024-04-11T00:13:18.298Z,1712794398.298 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9765
2024-04-11T00:13:18.300Z,1712794398.300 [controlThread ThreadHandler](INFO): Handler Thread ID is 9766
2024-04-11T00:13:18.300Z,1712794398.300 [controlThread](DEBUG): Initializing ControlThread
2024-04-11T00:13:18.301Z,1712794398.301 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-11T00:13:18.303Z,1712794398.303 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-11T00:13:18.303Z,1712794398.303 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-11T00:13:18.304Z,1712794398.304 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-11T00:13:18.306Z,1712794398.306 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-11T00:13:18.306Z,1712794398.306 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-11T00:13:18.313Z,1712794398.313 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-11T00:13:18.313Z,1712794398.313 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-11T00:13:18.314Z,1712794398.314 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-11T00:13:18.314Z,1712794398.314 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-11T00:13:18.314Z,1712794398.314 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-11T00:13:18.320Z,1712794398.320 [SBIT](INFO): Initialize SBIT Component.
2024-04-11T00:13:18.320Z,1712794398.320 [SBIT](IMPORTANT): git: 2024-03-26
2024-04-11T00:13:18.320Z,1712794398.320 [SBIT](INFO): git hash: ad310a373d447f5044d653e05ec2c3eb1a52d830
2024-04-11T00:13:18.320Z,1712794398.320 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-11T00:13:18.321Z,1712794398.321 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-04-11T00:13:18.323Z,1712794398.323 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2024-04-11T00:13:18.323Z,1712794398.323 [IBIT](INFO): Initialize IBIT Component.
2024-04-11T00:13:18.324Z,1712794398.324 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-11T00:13:18.325Z,1712794398.325 [logger ThreadHandler](INFO): Handler Thread ID is 9767
2024-04-11T00:13:18.335Z,1712794398.335 [CBIT](DEBUG): Initialized mux pins.
2024-04-11T00:13:18.335Z,1712794398.335 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-11T00:13:18.344Z,1712794398.344 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9768
2024-04-11T00:13:18.345Z,1712794398.345 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-11T00:13:18.356Z,1712794398.356 [Onboard ThreadHandler](INFO): Handler Thread ID is 9769
2024-04-11T00:13:18.360Z,1712794398.360 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-11T00:13:18.360Z,1712794398.360 [CBIT](DEBUG): Initializing heartbeat.
2024-04-11T00:13:18.376Z,1712794398.376 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9770
2024-04-11T00:13:18.392Z,1712794398.392 [DAT ThreadHandler](INFO): Handler Thread ID is 9771
2024-04-11T00:13:18.392Z,1712794398.392 [DAT](INFO): Powering up
2024-04-11T00:13:18.393Z,1712794398.393 [DAT](DEBUG): Initializing DAT.
2024-04-11T00:13:18.397Z,1712794398.397 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9773
2024-04-11T00:13:18.397Z,1712794398.397 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-11T00:13:18.401Z,1712794398.401 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9775
2024-04-11T00:13:18.404Z,1712794398.404 [WetLabsBB2FL](INFO): Powering up
2024-04-11T00:13:18.405Z,1712794398.405 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9777
2024-04-11T00:13:18.408Z,1712794398.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-04-11T00:13:18.408Z,1712794398.408 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-04-11T00:13:18.409Z,1712794398.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-04-11T00:13:18.409Z,1712794398.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-04-11T00:13:18.409Z,1712794398.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-04-11T00:13:18.409Z,1712794398.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-04-11T00:13:18.409Z,1712794398.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-04-11T00:13:18.409Z,1712794398.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-04-11T00:13:18.431Z,1712794398.431 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-11T00:13:18.431Z,1712794398.431 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-11T00:13:18.471Z,1712794398.471 [CBIT](DEBUG): Backplane powered.
2024-04-11T00:13:18.472Z,1712794398.472 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-11T00:13:18.473Z,1712794398.473 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-11T00:13:18.473Z,1712794398.473 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-11T00:13:18.474Z,1712794398.474 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-11T00:13:18.515Z,1712794398.515 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-11T00:13:18.539Z,1712794398.539 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-11T00:13:18.566Z,1712794398.566 [MissionManager](DEBUG):
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-04-11T00:13:18.567Z,1712794398.567 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-04-11T00:13:18.567Z,1712794398.567 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-04-11T00:13:18.592Z,1712794398.592 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-04-11T00:13:18.779Z,1712794398.779 [Radio_Surface](INFO): Powering up
2024-04-11T00:13:18.876Z,1712794398.876 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-04-11T00:13:18.899Z,1712794398.899 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-11T00:13:18.900Z,1712794398.900 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-11T00:13:18.902Z,1712794398.902 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-11T00:13:18.951Z,1712794398.951 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-11T00:13:18.961Z,1712794398.961 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-11T00:13:18.983Z,1712794398.983 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-11T00:13:18.986Z,1712794398.986 [MissionManager](DEBUG):
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-04-11T00:13:18.991Z,1712794398.991 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,AMEcho,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-04-11T00:13:19.003Z,1712794399.003 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-11T00:13:19.070Z,1712794399.070 [Power24vConverter](INFO): Powering up.
2024-04-11T00:13:19.070Z,1712794399.070 [AMEcho](INFO): Powering up.
2024-04-11T00:13:19.087Z,1712794399.087 [CANONSampler](INFO): Powering down
2024-04-11T00:13:19.145Z,1712794399.145 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-11T00:13:19.151Z,1712794399.151 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-11T00:13:19.153Z,1712794399.153 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-11T00:13:19.159Z,1712794399.159 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-11T00:13:19.160Z,1712794399.160 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-11T00:13:19.167Z,1712794399.167 [MassServo](DEBUG): Initializing MassServo.
2024-04-11T00:13:19.168Z,1712794399.168 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-11T00:13:19.175Z,1712794399.175 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-11T00:13:19.176Z,1712794399.176 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-11T00:13:19.184Z,1712794399.184 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-11T00:13:19.611Z,1712794399.611 [AMEcho](INFO): Powering down.
2024-04-11T00:13:20.214Z,1712794400.214 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2024-04-11T00:13:20.511Z,1712794400.511 [WetLabsBB2FL](INFO): Powering down
2024-04-11T00:13:24.920Z,1712794404.920 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-11T00:13:25.428Z,1712794405.428 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-04-11T00:13:30.996Z,1712794410.996 [DAT](INFO): DAT read:
2024-04-11T00:13:30.997Z,1712794410.997 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-11T00:13:32.760Z,1712794412.760 [DAT](INFO): DAT read: MF Frequency Band
2024-04-11T00:13:32.761Z,1712794412.761 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-11T00:13:32.762Z,1712794412.762 [DAT](INFO): DAT read: Apr 11 2024 00:13:26
2024-04-11T00:13:34.020Z,1712794414.020 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-11T00:13:34.021Z,1712794414.021 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-04-11T00:13:34.022Z,1712794414.022 [DAT](INFO): commRate: 1200
2024-04-11T00:13:36.087Z,1712794416.087 [DAT](INFO): entering command mode
2024-04-11T00:13:36.288Z,1712794416.288 [DAT](INFO): DAT read:
2024-04-11T00:13:36.288Z,1712794416.288 [DAT](INFO): DAT read: user:1>
2024-04-11T00:13:36.289Z,1712794416.289 [DAT](INFO): setting verbose to 3
2024-04-11T00:13:36.540Z,1712794416.540 [DAT](INFO): DAT read: user:1>
2024-04-11T00:13:36.541Z,1712794416.541 [DAT](INFO): DAT read: Verbose | 3
2024-04-11T00:13:36.541Z,1712794416.541 [DAT](INFO): set verbose to 3
2024-04-11T00:13:36.542Z,1712794416.542 [DAT](INFO): setting DatVerbose to 27440
2024-04-11T00:13:36.792Z,1712794416.792 [DAT](INFO): DAT read: user:2>
2024-04-11T00:13:36.793Z,1712794416.793 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-11T00:13:36.793Z,1712794416.793 [DAT](INFO): set DatVerbose to 27440
2024-04-11T00:13:36.793Z,1712794416.793 [DAT](INFO): setting transmit power to 8
2024-04-11T00:13:37.044Z,1712794417.044 [DAT](INFO): DAT read: user:3>
2024-04-11T00:13:37.045Z,1712794417.045 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-11T00:13:37.045Z,1712794417.045 [DAT](INFO): set transmit power to 8
2024-04-11T00:13:37.045Z,1712794417.045 [DAT](INFO): setting local address to 8
2024-04-11T00:13:37.296Z,1712794417.296 [DAT](INFO): DAT read: user:4>
2024-04-11T00:13:37.297Z,1712794417.297 [DAT](INFO): DAT read: LocalAddr | 8
2024-04-11T00:13:37.297Z,1712794417.297 [DAT](INFO): set local address to 8
2024-04-11T00:13:37.298Z,1712794417.298 [DAT](INFO): Setting time to: 0:13:37 And date to:4/11/2024
2024-04-11T00:13:37.548Z,1712794417.548 [DAT](INFO): DAT read: user:5>
2024-04-11T00:13:37.549Z,1712794417.549 [DAT](INFO): DAT read: Thu Apr 11, 2024 00:13:37
2024-04-11T00:13:37.549Z,1712794417.549 [DAT](INFO): Local DAT time set to Thu Apr 11, 2024 00:13:37
2024-04-11T00:13:46.881Z,1712794426.881 [NAL9602](INFO): Powering up NAL9602
2024-04-11T00:13:57.789Z,1712794437.789 [NAL9602](INFO): NAL9602 initialized
2024-04-11T00:14:07.914Z,1712794447.914 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-11T00:14:07.918Z,1712794447.918 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-11T00:14:18.842Z,1712794458.842 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-04-11T00:14:19.234Z,1712794459.234 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-11T00:14:30.236Z,1712794470.236 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-04-11T00:14:30.684Z,1712794470.684 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-11T00:14:38.217Z,1712794478.217 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-11T00:14:41.520Z,1712794481.520 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-04-11T00:14:41.520Z,1712794481.520 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-04-11T00:15:02.051Z,1712794502.051 [SBIT](IMPORTANT): SBIT PASSED
2024-04-11T00:15:02.051Z,1712794502.051 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-11T00:15:02.052Z,1712794502.052 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-04-11T00:15:02.052Z,1712794502.052 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-11T00:15:02.052Z,1712794502.052 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=186 cubic_centimeter;
2024-04-11T00:15:02.052Z,1712794502.052 [SBIT](IMPORTANT): VerticalControl.massDefault=7.5 millimeter;
2024-04-11T00:15:02.458Z,1712794502.458 [MissionManager](IMPORTANT): Started mission Startup
2024-04-11T00:15:02.458Z,1712794502.458 [Startup] Running Loop=1
2024-04-11T00:15:02.459Z,1712794502.459 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-11T00:15:02.459Z,1712794502.459 [Startup:A.GoToSurface] Running Loop=1
2024-04-11T00:15:02.459Z,1712794502.459 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-11T00:15:02.464Z,1712794502.464 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-11T00:15:02.464Z,1712794502.464 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-11T00:15:02.464Z,1712794502.464 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-11T00:15:02.465Z,1712794502.465 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-11T00:15:02.465Z,1712794502.465 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-11T00:15:02.466Z,1712794502.466 [Startup:StartupSatComms] Running Loop=1
2024-04-11T00:15:02.467Z,1712794502.467 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-11T00:15:02.467Z,1712794502.467 [Startup:StartupSatComms:A] Running Loop=1
2024-04-11T00:15:02.857Z,1712794502.857 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-11T00:15:03.254Z,1712794503.254 [AMEcho](INFO): Powering up.
2024-04-11T00:15:15.392Z,1712794515.392 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:15:15.392Z,1712794515.392 [AMEcho] Communications Fault, FailCount= 1
2024-04-11T00:15:15.392Z,1712794515.392 [AMEcho](ERROR): Communications Fault
2024-04-11T00:15:15.440Z,1712794515.440 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:15:15.959Z,1712794515.959 [AMEcho](INFO): Powering down.
2024-04-11T00:15:16.604Z,1712794516.604 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:15:16.604Z,1712794516.604 [AMEcho] No Fault, FailCount= 1
2024-04-11T00:15:16.997Z,1712794516.997 [AMEcho](INFO): Powering up.
2024-04-11T00:15:29.110Z,1712794529.110 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:15:29.110Z,1712794529.110 [AMEcho] Communications Fault, FailCount= 2
2024-04-11T00:15:29.110Z,1712794529.110 [AMEcho](ERROR): Communications Fault
2024-04-11T00:15:29.151Z,1712794529.151 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:15:29.680Z,1712794529.680 [AMEcho](INFO): Powering down.
2024-04-11T00:15:30.348Z,1712794530.348 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:15:30.348Z,1712794530.348 [AMEcho] No Fault, FailCount= 2
2024-04-11T00:15:30.747Z,1712794530.747 [AMEcho](INFO): Powering up.
2024-04-11T00:15:42.857Z,1712794542.857 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:15:42.858Z,1712794542.858 [AMEcho] Communications Fault, FailCount= 3
2024-04-11T00:15:42.858Z,1712794542.858 [AMEcho](ERROR): Communications Fault
2024-04-11T00:15:42.894Z,1712794542.894 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:15:42.895Z,1712794542.895 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2024-04-11T00:15:43.407Z,1712794543.407 [AMEcho](INFO): Powering down.
2024-04-11T00:15:43.429Z,1712794543.429 [CommandExec](FAULT): Scheduling is paused
2024-04-11T00:15:43.430Z,1712794543.430 [CBIT](INFO): Critical error at 20240411T001542
2024-04-11T00:15:43.430Z,1712794543.430 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-04-11T00:16:01.246Z,1712794561.246 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005032
2024-04-11T00:16:02.670Z,1712794562.670 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-11T00:15:02.5Z
2024-04-11T00:16:02.670Z,1712794562.670 [Startup:StartupSatComms:A] Stopped
2024-04-11T00:16:02.670Z,1712794562.670 [Startup:StartupSatComms:B] Running Loop=1
2024-04-11T00:16:03.053Z,1712794563.053 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-11T00:16:09.471Z,1712794569.471 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240411T000349/Courier0007.lzma
2024-04-11T00:16:10.473Z,1712794570.473 [DataOverHttps](INFO): Moved sent file to Logs/20240411T000349/Courier0007.lzma.bak
2024-04-11T00:16:10.473Z,1712794570.473 [DataOverHttps](INFO): SBD MOMSN=19397969
2024-04-11T00:16:18.414Z,1712794578.414 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-04-11T00:16:18.414Z,1712794578.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-11T00:16:18.425Z,1712794578.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-11T00:16:18.833Z,1712794578.833 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-11T00:16:18.834Z,1712794578.834 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-04-11T00:16:26.015Z,1712794586.015 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240411T001307/Courier0000.lzma
2024-04-11T00:16:27.017Z,1712794587.017 [DataOverHttps](INFO): Moved sent file to Logs/20240411T001307/Courier0000.lzma.bak
2024-04-11T00:16:27.017Z,1712794587.017 [DataOverHttps](INFO): SBD MOMSN=19397971
2024-04-11T00:16:42.568Z,1712794602.568 [DataOverHttps](INFO): Sending 292 bytes from file Logs/20240410T233302/Express0005.lzma
2024-04-11T00:16:43.569Z,1712794603.569 [DataOverHttps](INFO): Moved sent file to Logs/20240410T233302/Express0005.lzma.bak
2024-04-11T00:16:43.569Z,1712794603.569 [DataOverHttps](INFO): SBD MOMSN=19397975
2024-04-11T00:16:59.085Z,1712794619.085 [DataOverHttps](INFO): Sending 1530 bytes from file Logs/20240411T000349/Express0001.lzma
2024-04-11T00:17:00.097Z,1712794620.097 [DataOverHttps](INFO): Moved sent file to Logs/20240411T000349/Express0001.lzma.bak
2024-04-11T00:17:00.097Z,1712794620.097 [DataOverHttps](INFO): SBD MOMSN=19397988
2024-04-11T00:17:02.857Z,1712794622.857 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-11T00:16:02.7Z
2024-04-11T00:17:02.858Z,1712794622.858 [Startup:StartupSatComms:B] Stopped
2024-04-11T00:17:02.858Z,1712794622.858 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-11T00:17:02.858Z,1712794622.858 [Startup:StartupSatComms] Stopped
2024-04-11T00:17:02.858Z,1712794622.858 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-11T00:17:02.859Z,1712794622.859 [Startup](INFO): Completed Startup
2024-04-11T00:17:02.859Z,1712794622.859 [MissionManager](INFO): Startup is completed.
2024-04-11T00:17:02.859Z,1712794622.859 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-11T00:17:02.859Z,1712794622.859 [Startup] Stopped
2024-04-11T00:17:02.859Z,1712794622.859 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-11T00:17:02.860Z,1712794622.860 [Startup:A.GoToSurface] Stopped
2024-04-11T00:17:02.860Z,1712794622.860 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-11T00:17:03.275Z,1712794623.275 [MissionManager](IMPORTANT): Started mission Default
2024-04-11T00:17:03.275Z,1712794623.275 [Default] Running Loop=1
2024-04-11T00:17:03.276Z,1712794623.276 [Default](DEBUG): Aggregate::initialize Default
2024-04-11T00:17:03.276Z,1712794623.276 [Default:B.GoToSurface] Running Loop=1
2024-04-11T00:17:03.276Z,1712794623.276 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-11T00:17:03.276Z,1712794623.276 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-11T00:17:03.276Z,1712794623.276 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-11T00:17:03.277Z,1712794623.277 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-11T00:17:03.277Z,1712794623.277 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-11T00:17:03.277Z,1712794623.277 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-11T00:17:03.277Z,1712794623.277 [Default:A.Wait] Running Loop=1
2024-04-11T00:17:03.278Z,1712794623.278 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-11T00:17:16.344Z,1712794636.344 [DataOverHttps](INFO): Sending 960 bytes from file Logs/20240411T000349/Express0005.lzma
2024-04-11T00:17:16.581Z,1712794636.581 [Default:A.Wait](INFO): Done Waiting.
2024-04-11T00:17:16.581Z,1712794636.581 [Default:A.Wait] Stopped
2024-04-11T00:17:16.581Z,1712794636.581 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-11T00:17:16.981Z,1712794636.981 [Default:CheckIn] Running Loop=1
2024-04-11T00:17:16.981Z,1712794636.981 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-11T00:17:16.982Z,1712794636.982 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-11T00:17:17.345Z,1712794637.345 [DataOverHttps](INFO): Moved sent file to Logs/20240411T000349/Express0005.lzma.bak
2024-04-11T00:17:17.345Z,1712794637.345 [DataOverHttps](INFO): SBD MOMSN=19398060
2024-04-11T00:17:17.398Z,1712794637.398 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-11T00:18:43.065Z,1712794723.065 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:18:43.065Z,1712794723.065 [AMEcho] No Fault, FailCount= 3
2024-04-11T00:18:43.445Z,1712794723.445 [AMEcho](INFO): Powering up.
2024-04-11T00:18:55.624Z,1712794735.624 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:18:55.625Z,1712794735.625 [AMEcho] Communications Fault, FailCount= 1
2024-04-11T00:18:55.625Z,1712794735.625 [AMEcho](ERROR): Communications Fault
2024-04-11T00:18:55.641Z,1712794735.641 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:18:56.159Z,1712794736.159 [AMEcho](INFO): Powering down.
2024-04-11T00:18:56.817Z,1712794736.817 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:18:56.817Z,1712794736.817 [AMEcho] No Fault, FailCount= 1
2024-04-11T00:18:57.184Z,1712794737.184 [AMEcho](INFO): Powering up.
2024-04-11T00:19:01.209Z,1712794741.209 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-11T00:19:09.350Z,1712794749.350 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:19:09.350Z,1712794749.350 [AMEcho] Communications Fault, FailCount= 2
2024-04-11T00:19:09.350Z,1712794749.350 [AMEcho](ERROR): Communications Fault
2024-04-11T00:19:09.384Z,1712794749.384 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:19:09.863Z,1712794749.863 [AMEcho](INFO): Powering down.
2024-04-11T00:19:10.554Z,1712794750.554 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:19:10.554Z,1712794750.554 [AMEcho] No Fault, FailCount= 2
2024-04-11T00:19:10.926Z,1712794750.926 [AMEcho](INFO): Powering up.
2024-04-11T00:19:19.397Z,1712794759.397 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-04-11T00:19:19.397Z,1712794759.397 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-11T00:19:19.407Z,1712794759.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-11T00:19:19.813Z,1712794759.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-11T00:19:19.813Z,1712794759.813 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-04-11T00:19:23.088Z,1712794763.088 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:19:23.088Z,1712794763.088 [AMEcho] Communications Fault, FailCount= 3
2024-04-11T00:19:23.088Z,1712794763.088 [AMEcho](ERROR): Communications Fault
2024-04-11T00:19:23.104Z,1712794763.104 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:19:23.105Z,1712794763.105 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2024-04-11T00:19:23.611Z,1712794763.611 [AMEcho](INFO): Powering down.
2024-04-11T00:19:23.632Z,1712794763.632 [CBIT](INFO): Critical error at 20240411T001923
2024-04-11T00:21:39.630Z,1712794899.630 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-11T00:21:51.339Z,1712794911.339 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-11T00:22:17.181Z,1712794937.181 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-11T00:17:16.0Z
2024-04-11T00:22:17.181Z,1712794937.181 [Default:CheckIn:Read_GPS] Stopped
2024-04-11T00:22:17.181Z,1712794937.181 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-11T00:22:17.581Z,1712794937.581 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-11T00:22:20.440Z,1712794940.440 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20240411T001307/Courier0004.lzma
2024-04-11T00:22:20.442Z,1712794940.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-04-11T00:22:20.442Z,1712794940.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-11T00:22:20.453Z,1712794940.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-11T00:22:20.830Z,1712794940.830 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-11T00:22:20.830Z,1712794940.830 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-04-11T00:22:21.269Z,1712794941.269 [DataOverHttps](INFO): Moved sent file to Logs/20240411T001307/Courier0004.lzma.bak
2024-04-11T00:22:21.269Z,1712794941.269 [DataOverHttps](INFO): SBD MOMSN=19398117
2024-04-11T00:22:23.274Z,1712794943.274 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:22:23.275Z,1712794943.275 [AMEcho] No Fault, FailCount= 3
2024-04-11T00:22:23.637Z,1712794943.637 [AMEcho](INFO): Powering up.
2024-04-11T00:22:35.757Z,1712794955.757 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:22:35.757Z,1712794955.757 [AMEcho] Communications Fault, FailCount= 1
2024-04-11T00:22:35.757Z,1712794955.757 [AMEcho](ERROR): Communications Fault
2024-04-11T00:22:35.813Z,1712794955.813 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:22:36.327Z,1712794956.327 [AMEcho](INFO): Powering down.
2024-04-11T00:22:36.843Z,1712794956.843 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20240411T000349/Express0008.lzma
2024-04-11T00:22:36.989Z,1712794956.989 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:22:36.989Z,1712794956.989 [AMEcho] No Fault, FailCount= 1
2024-04-11T00:22:37.389Z,1712794957.389 [AMEcho](INFO): Powering up.
2024-04-11T00:22:37.845Z,1712794957.845 [DataOverHttps](INFO): Moved sent file to Logs/20240411T000349/Express0008.lzma.bak
2024-04-11T00:22:37.845Z,1712794957.845 [DataOverHttps](INFO): SBD MOMSN=19398120
2024-04-11T00:22:49.504Z,1712794969.504 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:22:49.505Z,1712794969.505 [AMEcho] Communications Fault, FailCount= 2
2024-04-11T00:22:49.505Z,1712794969.505 [AMEcho](ERROR): Communications Fault
2024-04-11T00:22:49.567Z,1712794969.567 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:22:50.079Z,1712794970.079 [AMEcho](INFO): Powering down.
2024-04-11T00:22:50.757Z,1712794970.757 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:22:50.758Z,1712794970.758 [AMEcho] No Fault, FailCount= 2
2024-04-11T00:22:51.109Z,1712794971.109 [AMEcho](INFO): Powering up.
2024-04-11T00:22:53.459Z,1712794973.459 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240411T000349/Express0011.lzma
2024-04-11T00:22:54.461Z,1712794974.461 [DataOverHttps](INFO): Moved sent file to Logs/20240411T000349/Express0011.lzma.bak
2024-04-11T00:22:54.461Z,1712794974.461 [DataOverHttps](INFO): SBD MOMSN=19398124
2024-04-11T00:23:03.234Z,1712794983.234 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:23:03.234Z,1712794983.234 [AMEcho] Communications Fault, FailCount= 3
2024-04-11T00:23:03.234Z,1712794983.234 [AMEcho](ERROR): Communications Fault
2024-04-11T00:23:03.250Z,1712794983.250 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:23:03.250Z,1712794983.250 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2024-04-11T00:23:03.796Z,1712794983.796 [AMEcho](INFO): Powering down.
2024-04-11T00:23:03.817Z,1712794983.817 [CBIT](INFO): Critical error at 20240411T002303
2024-04-11T00:23:10.052Z,1712794990.052 [DataOverHttps](INFO): Sending 745 bytes from file Logs/20240411T001307/Express0001.lzma
2024-04-11T00:23:11.053Z,1712794991.053 [DataOverHttps](INFO): Moved sent file to Logs/20240411T001307/Express0001.lzma.bak
2024-04-11T00:23:11.053Z,1712794991.053 [DataOverHttps](INFO): SBD MOMSN=19398126
2024-04-11T00:23:26.523Z,1712795006.523 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240411T001307/Express0005.lzma
2024-04-11T00:23:27.525Z,1712795007.525 [DataOverHttps](INFO): Moved sent file to Logs/20240411T001307/Express0005.lzma.bak
2024-04-11T00:23:27.525Z,1712795007.525 [DataOverHttps](INFO): SBD MOMSN=19398146
2024-04-11T00:23:29.105Z,1712795009.105 [Default:CheckIn:Read_Iridium] Stopped
2024-04-11T00:23:29.105Z,1712795009.105 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-11T00:23:29.105Z,1712795009.105 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-11T00:24:00.193Z,1712795040.193 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-11T00:24:00.193Z,1712795040.193 [NAL9602] Data Fault, FailCount= 1
2024-04-11T00:24:00.193Z,1712795040.193 [NAL9602](ERROR): Data Fault
2024-04-11T00:24:00.235Z,1712795040.235 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-11T00:24:00.601Z,1712795040.601 [NAL9602](INFO): Powering down
2024-04-11T00:24:01.435Z,1712795041.435 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-11T00:24:01.435Z,1712795041.435 [NAL9602] No Fault, FailCount= 1
2024-04-11T00:24:30.901Z,1712795070.901 [NAL9602](INFO): Powering up NAL9602
2024-04-11T00:24:41.825Z,1712795081.825 [NAL9602](INFO): NAL9602 initialized
2024-04-11T00:25:11.784Z,1712795111.784 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-11T00:25:21.453Z,1712795121.453 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-04-11T00:25:21.453Z,1712795121.453 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-11T00:25:21.463Z,1712795121.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-11T00:25:21.840Z,1712795121.840 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-11T00:25:21.840Z,1712795121.840 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-04-11T00:26:03.499Z,1712795163.499 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:26:03.499Z,1712795163.499 [AMEcho] No Fault, FailCount= 3
2024-04-11T00:26:03.854Z,1712795163.854 [AMEcho](INFO): Powering up.
2024-04-11T00:26:15.962Z,1712795175.962 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:26:15.962Z,1712795175.962 [AMEcho] Communications Fault, FailCount= 1
2024-04-11T00:26:15.962Z,1712795175.962 [AMEcho](ERROR): Communications Fault
2024-04-11T00:26:16.003Z,1712795176.003 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:26:16.532Z,1712795176.532 [AMEcho](INFO): Powering down.
2024-04-11T00:26:17.210Z,1712795177.210 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:26:17.210Z,1712795177.210 [AMEcho] No Fault, FailCount= 1
2024-04-11T00:26:17.588Z,1712795177.588 [AMEcho](INFO): Powering up.
2024-04-11T00:26:29.699Z,1712795189.699 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:26:29.699Z,1712795189.699 [AMEcho] Communications Fault, FailCount= 2
2024-04-11T00:26:29.699Z,1712795189.699 [AMEcho](ERROR): Communications Fault
2024-04-11T00:26:29.715Z,1712795189.715 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:26:30.271Z,1712795190.271 [AMEcho](INFO): Powering down.
2024-04-11T00:26:30.943Z,1712795190.943 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:26:30.943Z,1712795190.943 [AMEcho] No Fault, FailCount= 2
2024-04-11T00:26:31.309Z,1712795191.309 [AMEcho](INFO): Powering up.
2024-04-11T00:26:43.452Z,1712795203.452 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:26:43.453Z,1712795203.453 [AMEcho] Communications Fault, FailCount= 3
2024-04-11T00:26:43.453Z,1712795203.453 [AMEcho](ERROR): Communications Fault
2024-04-11T00:26:43.475Z,1712795203.475 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:26:43.476Z,1712795203.476 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2024-04-11T00:26:44.023Z,1712795204.023 [AMEcho](INFO): Powering down.
2024-04-11T00:26:44.069Z,1712795204.069 [CBIT](INFO): Critical error at 20240411T002643
2024-04-11T00:27:55.001Z,1712795275.001 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-11T00:28:22.442Z,1712795302.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-04-11T00:28:22.442Z,1712795302.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-11T00:28:22.452Z,1712795302.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-11T00:28:22.866Z,1712795302.866 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-11T00:28:22.866Z,1712795302.866 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-04-11T00:28:29.693Z,1712795309.693 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-11T00:28:29.693Z,1712795309.693 [Default:CheckIn:C.Wait] Stopped
2024-04-11T00:28:29.693Z,1712795309.693 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-11T00:28:29.693Z,1712795309.693 [Default:CheckIn:D] Running Loop=1
2024-04-11T00:28:30.107Z,1712795310.107 [Default:CheckIn:D] Stopped
2024-04-11T00:28:30.107Z,1712795310.107 [Default:CheckIn:E] Running Loop=1
2024-04-11T00:28:30.525Z,1712795310.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.447187 min
2024-04-11T00:28:30.525Z,1712795310.525 [Default:CheckIn:E] Stopped
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn] Stopped
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn](INFO): Running loop #2
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn] Running Loop=2
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-11T00:28:30.526Z,1712795310.526 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-11T00:29:43.690Z,1712795383.690 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:29:43.690Z,1712795383.690 [AMEcho] No Fault, FailCount= 3
2024-04-11T00:29:44.038Z,1712795384.038 [AMEcho](INFO): Powering up.
2024-04-11T00:29:56.165Z,1712795396.165 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:29:56.165Z,1712795396.165 [AMEcho] Communications Fault, FailCount= 1
2024-04-11T00:29:56.165Z,1712795396.165 [AMEcho](ERROR): Communications Fault
2024-04-11T00:29:56.218Z,1712795396.218 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:29:56.978Z,1712795396.978 [AMEcho](INFO): Powering down.
2024-04-11T00:29:57.579Z,1712795397.579 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:29:57.579Z,1712795397.579 [AMEcho] No Fault, FailCount= 1
2024-04-11T00:29:57.920Z,1712795397.920 [AMEcho](INFO): Powering up.
2024-04-11T00:30:10.230Z,1712795410.230 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:30:10.230Z,1712795410.230 [AMEcho] Communications Fault, FailCount= 2
2024-04-11T00:30:10.230Z,1712795410.230 [AMEcho](ERROR): Communications Fault
2024-04-11T00:30:10.302Z,1712795410.302 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:30:10.611Z,1712795410.611 [AMEcho](INFO): Powering down.
2024-04-11T00:30:11.310Z,1712795411.310 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:30:11.310Z,1712795411.310 [AMEcho] No Fault, FailCount= 2
2024-04-11T00:30:11.626Z,1712795411.626 [AMEcho](INFO): Powering up.
2024-04-11T00:30:23.812Z,1712795423.812 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2024-04-11T00:30:23.812Z,1712795423.812 [AMEcho] Communications Fault, FailCount= 3
2024-04-11T00:30:23.812Z,1712795423.812 [AMEcho](ERROR): Communications Fault
2024-04-11T00:30:23.828Z,1712795423.828 [CBIT](ERROR): Communications Fault in component: AMEcho
2024-04-11T00:30:23.829Z,1712795423.829 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2024-04-11T00:30:24.331Z,1712795424.331 [AMEcho](INFO): Powering down.
2024-04-11T00:30:24.356Z,1712795424.356 [CBIT](INFO): Critical error at 20240411T003023
2024-04-11T00:31:05.012Z,1712795465.012 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-04-11T00:31:23.572Z,1712795483.572 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-04-11T00:31:23.572Z,1712795483.572 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-11T00:31:23.582Z,1712795483.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-11T00:31:24.037Z,1712795484.037 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-11T00:31:24.037Z,1712795484.037 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-04-11T00:33:13.380Z,1712795593.380 [DataOverHttps](IMPORTANT): SBD MTMSN=20240411T003312
2024-04-11T00:33:20.631Z,1712795600.631 [DataOverHttps](INFO): Received command: restart app
2024-04-11T00:33:20.681Z,1712795600.681 [CommandExec](IMPORTANT): got command restart application
2024-04-11T00:33:21.683Z,1712795601.683 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:21.683Z,1712795601.683 [CommandExec](INFO): Uninitializing the command executive.
2024-04-11T00:33:21.684Z,1712795601.684 [CommandExec](INFO): Uninitializing the command scheduler.
2024-04-11T00:33:21.684Z,1712795601.684 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:21.867Z,1712795601.867 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-04-11T00:33:21.867Z,1712795601.867 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-04-11T00:33:21.867Z,1712795601.867 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:21.868Z,1712795601.868 [NavChartDb](INFO): Join timeout helper Thread ID is 9831
2024-04-11T00:33:22.075Z,1712795602.075 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:22.076Z,1712795602.076 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:22.091Z,1712795602.091 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-04-11T00:33:22.091Z,1712795602.091 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:22.092Z,1712795602.092 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9832
2024-04-11T00:33:22.511Z,1712795602.511 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:22.512Z,1712795602.512 [WetLabsBB2FL](INFO): Powering down
2024-04-11T00:33:22.512Z,1712795602.512 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:22.515Z,1712795602.515 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-04-11T00:33:22.515Z,1712795602.515 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:22.516Z,1712795602.516 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9833
2024-04-11T00:33:23.511Z,1712795603.511 [CTD_Seabird](INFO): Powering down
2024-04-11T00:33:23.530Z,1712795603.530 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:23.530Z,1712795603.530 [CTD_Seabird](INFO): Powering down
2024-04-11T00:33:23.543Z,1712795603.543 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:23.559Z,1712795603.559 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-04-11T00:33:23.559Z,1712795603.559 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:23.560Z,1712795603.560 [DAT](INFO): Join timeout helper Thread ID is 9834
2024-04-11T00:33:23.636Z,1712795603.636 [DAT](INFO): Powering down
2024-04-11T00:33:23.708Z,1712795603.708 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:23.708Z,1712795603.708 [DAT](INFO): Powering down
2024-04-11T00:33:23.709Z,1712795603.709 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:23.723Z,1712795603.723 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-04-11T00:33:23.723Z,1712795603.723 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:23.724Z,1712795603.724 [Radio_Surface](INFO): Join timeout helper Thread ID is 9835
2024-04-11T00:33:24.017Z,1712795604.017 [CBIT](INFO): Clearing failed state for component AMEcho
2024-04-11T00:33:24.017Z,1712795604.017 [AMEcho] No Fault, FailCount= 3
2024-04-11T00:33:24.091Z,1712795604.091 [Radio_Surface](INFO): Powering down
2024-04-11T00:33:24.092Z,1712795604.092 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:24.092Z,1712795604.092 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.108Z,1712795604.108 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-04-11T00:33:24.108Z,1712795604.108 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.109Z,1712795604.109 [Onboard](INFO): Join timeout helper Thread ID is 9836
2024-04-11T00:33:24.391Z,1712795604.391 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:24.391Z,1712795604.391 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.393Z,1712795604.393 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-04-11T00:33:24.393Z,1712795604.393 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.393Z,1712795604.393 [DataOverHttps](INFO): Join timeout helper Thread ID is 9837
2024-04-11T00:33:24.396Z,1712795604.396 [AMEcho](INFO): Powering up.
2024-04-11T00:33:24.643Z,1712795604.643 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:24.644Z,1712795604.644 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.648Z,1712795604.648 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-04-11T00:33:24.648Z,1712795604.648 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.648Z,1712795604.648 [logger](INFO): Join timeout helper Thread ID is 9839
2024-04-11T00:33:24.671Z,1712795604.671 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:24.672Z,1712795604.672 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.688Z,1712795604.688 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-04-11T00:33:24.688Z,1712795604.688 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.688Z,1712795604.688 [CommandLine](INFO): Join timeout helper Thread ID is 9840
2024-04-11T00:33:24.735Z,1712795604.735 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:24.736Z,1712795604.736 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.748Z,1712795604.748 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-04-11T00:33:24.748Z,1712795604.748 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.748Z,1712795604.748 [CommandExec](INFO): Join timeout helper Thread ID is 9841
2024-04-11T00:33:24.749Z,1712795604.749 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-04-11T00:33:24.750Z,1712795604.750 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:24.750Z,1712795604.750 [controlThread](INFO): Join timeout helper Thread ID is 9842
2024-04-11T00:33:24.779Z,1712795604.779 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-11T00:33:24.780Z,1712795604.780 [controlThread](DEBUG): Uninitializing ControlThread
2024-04-11T00:33:24.780Z,1712795604.780 [AHRS_M2](INFO): Powering down
2024-04-11T00:33:24.852Z,1712795604.852 [NAL9602](INFO): Powering down
2024-04-11T00:33:25.019Z,1712795605.019 [AMEcho](INFO): Powering down.
2024-04-11T00:33:25.021Z,1712795605.021 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-04-11T00:33:25.022Z,1712795605.022 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-04-11T00:33:25.023Z,1712795605.023 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-04-11T00:33:25.023Z,1712795605.023 [MissionManager](INFO): Uninitializing Mission Default
2024-04-11T00:33:25.023Z,1712795605.023 [Default] Stopped
2024-04-11T00:33:25.024Z,1712795605.024 [Default](DEBUG): Aggregate::uninitialize Default
2024-04-11T00:33:25.024Z,1712795605.024 [Default:B.GoToSurface] Stopped
2024-04-11T00:33:25.024Z,1712795605.024 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-11T00:33:25.024Z,1712795605.024 [Default:CheckIn] Stopped
2024-04-11T00:33:25.024Z,1712795605.024 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-11T00:33:25.024Z,1712795605.024 [Default:CheckIn:Read_GPS] Stopped
2024-04-11T00:33:25.027Z,1712795605.027 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-04-11T00:33:25.027Z,1712795605.027 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-04-11T00:33:25.027Z,1712795605.027 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-04-11T00:33:25.028Z,1712795605.028 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-04-11T00:33:25.028Z,1712795605.028 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-04-11T00:33:25.028Z,1712795605.028 [BuoyancyServo](INFO): Powering down
2024-04-11T00:33:25.043Z,1712795605.043 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-04-11T00:33:25.043Z,1712795605.043 [ElevatorServo](INFO): Powering down
2024-04-11T00:33:25.044Z,1712795605.044 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-04-11T00:33:25.044Z,1712795605.044 [MassServo](INFO): Powering down
2024-04-11T00:33:25.045Z,1712795605.045 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-04-11T00:33:25.045Z,1712795605.045 [RudderServo](INFO): Powering down
2024-04-11T00:33:25.046Z,1712795605.046 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-04-11T00:33:25.046Z,1712795605.046 [ThrusterHE](INFO): Powering down
2024-04-11T00:33:25.047Z,1712795605.047 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-04-11T00:33:25.048Z,1712795605.048 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-04-11T00:33:25.048Z,1712795605.048 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-04-11T00:33:25.048Z,1712795605.048 [CBIT](DEBUG): Powering off loads.
2024-04-11T00:33:25.059Z,1712795605.059 [CBIT](DEBUG): Disabling WDT.
2024-04-11T00:33:25.071Z,1712795605.071 [CBIT](DEBUG): Opening all GF detection circuits.
2024-04-11T00:33:25.072Z,1712795605.072 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.131Z,1712795605.131 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.137Z,1712795605.137 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.139Z,1712795605.139 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.145Z,1712795605.145 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.222Z,1712795605.222 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.226Z,1712795605.226 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.275Z,1712795605.275 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-11T00:33:25.338Z,1712795605.338 [logger ThreadHandler](INFO): Thread cancelled.