2022-03-16T18:38:21.546Z,1647455901.546 [Supervisor](DEBUG): Initializing supervisor.
2022-03-16T18:38:21.550Z,1647455901.550 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-03-16T18:38:21.551Z,1647455901.551 [SyncHandler](INFO): Protected caller Thread ID is 3819
2022-03-16T18:38:21.551Z,1647455901.551 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-03-16T18:38:21.552Z,1647455901.552 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-03-16T18:38:21.553Z,1647455901.553 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3820
2022-03-16T18:38:21.557Z,1647455901.557 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-03-16T18:38:21.575Z,1647455901.575 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-03-16T18:38:21.576Z,1647455901.576 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-03-16T18:38:21.576Z,1647455901.576 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3821
2022-03-16T18:38:21.580Z,1647455901.580 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-03-16T18:38:21.581Z,1647455901.581 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-03-16T18:38:21.582Z,1647455901.582 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3822
2022-03-16T18:38:21.584Z,1647455901.584 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-03-16T18:38:21.585Z,1647455901.585 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-03-16T18:38:21.585Z,1647455901.585 [logger ThreadHandler](INFO): Protected caller Thread ID is 3823
2022-03-16T18:38:21.589Z,1647455901.589 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-03-16T18:38:21.589Z,1647455901.589 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-03-16T18:38:21.591Z,1647455901.591 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-03-16T18:38:22.010Z,1647455902.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-03-16T18:38:22.010Z,1647455902.010 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-03-16T18:38:22.222Z,1647455902.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-03-16T18:38:22.223Z,1647455902.223 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-03-16T18:38:22.408Z,1647455902.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-03-16T18:38:22.409Z,1647455902.409 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-03-16T18:38:22.514Z,1647455902.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-03-16T18:38:22.515Z,1647455902.515 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-03-16T18:38:22.874Z,1647455902.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-03-16T18:38:22.875Z,1647455902.875 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-03-16T18:38:23.201Z,1647455903.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-03-16T18:38:23.201Z,1647455903.201 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-03-16T18:38:23.295Z,1647455903.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-03-16T18:38:23.296Z,1647455903.296 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-03-16T18:38:23.442Z,1647455903.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-03-16T18:38:23.443Z,1647455903.443 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-03-16T18:38:24.046Z,1647455904.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-03-16T18:38:24.047Z,1647455904.047 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-03-16T18:38:24.395Z,1647455904.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-03-16T18:38:24.396Z,1647455904.396 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-03-16T18:38:24.475Z,1647455904.475 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-03-16T18:38:24.682Z,1647455904.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-03-16T18:38:24.683Z,1647455904.683 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-03-16T18:38:24.807Z,1647455904.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-03-16T18:38:24.808Z,1647455904.808 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-03-16T18:38:25.502Z,1647455905.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-03-16T18:38:25.503Z,1647455905.503 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-03-16T18:38:25.580Z,1647455905.580 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-03-16T18:38:25.858Z,1647455905.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-03-16T18:38:25.860Z,1647455905.860 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2022-03-16T18:38:25.861Z,1647455905.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2022-03-16T18:38:26.034Z,1647455906.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2022-03-16T18:38:26.125Z,1647455906.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2022-03-16T18:38:26.235Z,1647455906.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2022-03-16T18:38:26.363Z,1647455906.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2022-03-16T18:38:26.554Z,1647455906.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2022-03-16T18:38:26.678Z,1647455906.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2022-03-16T18:38:26.950Z,1647455906.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2022-03-16T18:38:27.047Z,1647455907.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2022-03-16T18:38:27.375Z,1647455907.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2022-03-16T18:38:27.694Z,1647455907.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-03-16T18:38:27.695Z,1647455907.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2022-03-16T18:38:27.789Z,1647455907.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2022-03-16T18:38:27.789Z,1647455907.789 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-03-16T18:38:27.805Z,1647455907.805 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-03-16T18:38:28.143Z,1647455908.143 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-03-16T18:38:28.143Z,1647455908.143 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-03-16T18:38:28.259Z,1647455908.259 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-03-16T18:38:28.259Z,1647455908.259 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-03-16T18:38:28.296Z,1647455908.296 [DeadReckonUsingSpeedCalculator] Loaded
2022-03-16T18:38:28.296Z,1647455908.296 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2022-03-16T18:38:28.309Z,1647455908.309 [NavChart] Loaded
2022-03-16T18:38:28.309Z,1647455908.309 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-03-16T18:38:28.315Z,1647455908.315 [UniversalFixResidualReporter] Loaded
2022-03-16T18:38:28.315Z,1647455908.315 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-03-16T18:38:28.315Z,1647455908.315 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-03-16T18:38:28.316Z,1647455908.316 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-03-16T18:38:28.327Z,1647455908.327 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-03-16T18:38:28.327Z,1647455908.327 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-03-16T18:38:28.504Z,1647455908.504 [CTD_Seabird] Loaded
2022-03-16T18:38:28.504Z,1647455908.504 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-03-16T18:38:28.505Z,1647455908.505 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-03-16T18:38:28.505Z,1647455908.505 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3906
2022-03-16T18:38:28.524Z,1647455908.524 [PAR_Licor] Loaded
2022-03-16T18:38:28.525Z,1647455908.525 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-03-16T18:38:28.555Z,1647455908.555 [WetLabsBB2FL] Loaded
2022-03-16T18:38:28.556Z,1647455908.556 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-03-16T18:38:28.557Z,1647455908.557 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-03-16T18:38:28.557Z,1647455908.557 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3907
2022-03-16T18:38:28.558Z,1647455908.558 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-03-16T18:38:28.558Z,1647455908.558 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-03-16T18:38:28.613Z,1647455908.613 [VerticalControl](DEBUG): Construct VerticalControl.
2022-03-16T18:38:28.673Z,1647455908.673 [VerticalControl] Loaded
2022-03-16T18:38:28.673Z,1647455908.673 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-03-16T18:38:28.676Z,1647455908.676 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-03-16T18:38:28.714Z,1647455908.714 [HorizontalControl] Loaded
2022-03-16T18:38:28.714Z,1647455908.714 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-03-16T18:38:28.716Z,1647455908.716 [SpeedControl](DEBUG): Construct SpeedControl.
2022-03-16T18:38:28.719Z,1647455908.719 [SpeedControl] Loaded
2022-03-16T18:38:28.720Z,1647455908.720 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-03-16T18:38:28.722Z,1647455908.722 [LoopControl](DEBUG): Construct LoopControl.
2022-03-16T18:38:28.723Z,1647455908.723 [LoopControl] Loaded
2022-03-16T18:38:28.723Z,1647455908.723 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-03-16T18:38:28.723Z,1647455908.723 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-03-16T18:38:28.724Z,1647455908.724 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-03-16T18:38:28.768Z,1647455908.768 [DepthRateCalculator] Loaded
2022-03-16T18:38:28.768Z,1647455908.768 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-03-16T18:38:28.773Z,1647455908.773 [PitchRateCalculator] Loaded
2022-03-16T18:38:28.773Z,1647455908.773 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-03-16T18:38:28.782Z,1647455908.782 [SpeedCalculator] Loaded
2022-03-16T18:38:28.782Z,1647455908.782 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-03-16T18:38:28.795Z,1647455908.795 [TempGradientCalculator] Loaded
2022-03-16T18:38:28.795Z,1647455908.795 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-03-16T18:38:28.800Z,1647455908.800 [YawRateCalculator] Loaded
2022-03-16T18:38:28.800Z,1647455908.800 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-03-16T18:38:28.817Z,1647455908.817 [ElevatorOffsetCalculator] Loaded
2022-03-16T18:38:28.818Z,1647455908.818 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-03-16T18:38:28.818Z,1647455908.818 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-03-16T18:38:28.819Z,1647455908.819 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-03-16T18:38:28.919Z,1647455908.919 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-03-16T18:38:28.920Z,1647455908.920 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-03-16T18:38:28.981Z,1647455908.981 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-03-16T18:38:28.982Z,1647455908.982 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-03-16T18:38:29.112Z,1647455909.112 [BuoyancyServo] Loaded
2022-03-16T18:38:29.113Z,1647455909.113 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-03-16T18:38:29.132Z,1647455909.132 [ElevatorServo] Loaded
2022-03-16T18:38:29.132Z,1647455909.132 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-03-16T18:38:29.151Z,1647455909.151 [MassServo] Loaded
2022-03-16T18:38:29.151Z,1647455909.151 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-03-16T18:38:29.169Z,1647455909.169 [RudderServo] Loaded
2022-03-16T18:38:29.169Z,1647455909.169 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-03-16T18:38:29.184Z,1647455909.184 [ThrusterHE] Loaded
2022-03-16T18:38:29.184Z,1647455909.184 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-03-16T18:38:29.185Z,1647455909.185 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-03-16T18:38:29.185Z,1647455909.185 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-03-16T18:38:29.292Z,1647455909.292 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-03-16T18:38:29.292Z,1647455909.293 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-03-16T18:38:29.708Z,1647455909.708 [AHRS_M2] Loaded
2022-03-16T18:38:29.708Z,1647455909.708 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-03-16T18:38:29.744Z,1647455909.744 [BackseatComponent] Loaded
2022-03-16T18:38:29.744Z,1647455909.744 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-03-16T18:38:29.745Z,1647455909.745 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2022-03-16T18:38:29.745Z,1647455909.745 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3908
2022-03-16T18:38:29.748Z,1647455909.748 [LcmUniversalReporter] Loaded
2022-03-16T18:38:29.748Z,1647455909.748 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-03-16T18:38:30.579Z,1647455910.579 [BPC1] Loaded
2022-03-16T18:38:30.580Z,1647455910.580 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-03-16T18:38:30.650Z,1647455910.650 [DataOverHttps] Loaded
2022-03-16T18:38:30.650Z,1647455910.650 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-03-16T18:38:30.651Z,1647455910.651 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AA74E0
2022-03-16T18:38:30.652Z,1647455910.652 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3909
2022-03-16T18:38:30.671Z,1647455910.671 [Depth_Keller] Loaded
2022-03-16T18:38:30.671Z,1647455910.671 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-03-16T18:38:30.732Z,1647455910.732 [NAL9602] Loaded
2022-03-16T18:38:30.732Z,1647455910.732 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-03-16T18:38:30.761Z,1647455910.761 [Onboard] Loaded
2022-03-16T18:38:30.761Z,1647455910.761 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-03-16T18:38:30.762Z,1647455910.762 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AD74E0
2022-03-16T18:38:30.762Z,1647455910.762 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3910
2022-03-16T18:38:30.774Z,1647455910.774 [Power24vConverter] Loaded
2022-03-16T18:38:30.774Z,1647455910.774 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-03-16T18:38:30.788Z,1647455910.788 [Radio_Surface] Loaded
2022-03-16T18:38:30.788Z,1647455910.788 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-03-16T18:38:30.789Z,1647455910.789 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B074E0
2022-03-16T18:38:30.789Z,1647455910.789 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3911
2022-03-16T18:38:30.873Z,1647455910.873 [DAT] Loaded
2022-03-16T18:38:30.873Z,1647455910.873 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-03-16T18:38:30.874Z,1647455910.874 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B374E0
2022-03-16T18:38:30.875Z,1647455910.875 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3912
2022-03-16T18:38:30.875Z,1647455910.875 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-03-16T18:38:30.876Z,1647455910.876 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-03-16T18:38:31.029Z,1647455911.029 [SBIT](DEBUG): Construct Startup Built In Test.
2022-03-16T18:38:31.037Z,1647455911.037 [SBIT] Loaded
2022-03-16T18:38:31.038Z,1647455911.038 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-03-16T18:38:31.040Z,1647455911.040 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-03-16T18:38:31.053Z,1647455911.053 [IBIT] Loaded
2022-03-16T18:38:31.053Z,1647455911.053 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-03-16T18:38:31.059Z,1647455911.059 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-03-16T18:38:31.161Z,1647455911.161 [CBIT] Loaded
2022-03-16T18:38:31.162Z,1647455911.162 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-03-16T18:38:31.162Z,1647455911.162 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-03-16T18:38:31.162Z,1647455911.162 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-03-16T18:38:31.192Z,1647455911.192 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-03-16T18:38:31.198Z,1647455911.198 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-03-16T18:38:31.201Z,1647455911.201 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-03-16T18:38:31.212Z,1647455911.212 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-03-16T18:38:31.213Z,1647455911.213 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0
2022-03-16T18:38:31.213Z,1647455911.213 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3913
2022-03-16T18:38:31.217Z,1647455911.217 [Supervisor](INFO): Main Thread ID is 3818
2022-03-16T18:38:31.218Z,1647455911.218 [Supervisor](DEBUG): Running supervisor.
2022-03-16T18:38:31.218Z,1647455911.218 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3914
2022-03-16T18:38:31.219Z,1647455911.219 [CommandExec](INFO): Initializing the command executive.
2022-03-16T18:38:31.220Z,1647455911.220 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3915
2022-03-16T18:38:31.222Z,1647455911.222 [controlThread ThreadHandler](INFO): Handler Thread ID is 3916
2022-03-16T18:38:31.223Z,1647455911.223 [controlThread](DEBUG): Initializing ControlThread
2022-03-16T18:38:31.224Z,1647455911.224 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-03-16T18:38:31.225Z,1647455911.225 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-03-16T18:38:31.225Z,1647455911.225 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-03-16T18:38:31.229Z,1647455911.229 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-03-16T18:38:31.231Z,1647455911.231 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-03-16T18:38:31.232Z,1647455911.232 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-03-16T18:38:31.232Z,1647455911.232 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-03-16T18:38:31.233Z,1647455911.233 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-03-16T18:38:31.233Z,1647455911.233 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-03-16T18:38:31.233Z,1647455911.233 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-03-16T18:38:31.234Z,1647455911.234 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-03-16T18:38:31.234Z,1647455911.234 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-03-16T18:38:31.234Z,1647455911.234 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-03-16T18:38:31.238Z,1647455911.238 [SBIT](INFO): Initialize SBIT Component.
2022-03-16T18:38:31.239Z,1647455911.239 [SBIT](IMPORTANT): git: 2022-02-01-41-g055a528aa
2022-03-16T18:38:31.239Z,1647455911.239 [SBIT](INFO): git hash: 055a528aa498dab0a50a7c43a264b31c2ead9ecb
2022-03-16T18:38:31.239Z,1647455911.239 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-03-16T18:38:31.240Z,1647455911.240 [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
2022-03-16T18:38:31.242Z,1647455911.242 [SBIT](INFO): Beginning SBIT in 38.000000 seconds.
2022-03-16T18:38:31.242Z,1647455911.242 [IBIT](INFO): Initialize IBIT Component.
2022-03-16T18:38:31.243Z,1647455911.243 [CBIT](DEBUG): Initialize CBIT Component.
2022-03-16T18:38:31.244Z,1647455911.244 [logger ThreadHandler](INFO): Handler Thread ID is 3917
2022-03-16T18:38:31.257Z,1647455911.257 [CBIT](DEBUG): Initialized mux pins.
2022-03-16T18:38:31.257Z,1647455911.257 [CBIT](DEBUG): Initializing the watchdog timer.
2022-03-16T18:38:31.262Z,1647455911.262 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3918
2022-03-16T18:38:31.262Z,1647455911.262 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-16T18:38:31.267Z,1647455911.267 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3920
2022-03-16T18:38:31.270Z,1647455911.270 [WetLabsBB2FL](INFO): Powering up
2022-03-16T18:38:31.271Z,1647455911.271 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3922
2022-03-16T18:38:31.278Z,1647455911.278 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3923
2022-03-16T18:38:31.279Z,1647455911.279 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-03-16T18:38:31.281Z,1647455911.281 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-03-16T18:38:31.282Z,1647455911.282 [CBIT](DEBUG): Initializing heartbeat.
2022-03-16T18:38:31.290Z,1647455911.290 [Onboard ThreadHandler](INFO): Handler Thread ID is 3924
2022-03-16T18:38:31.307Z,1647455911.307 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3925
2022-03-16T18:38:31.330Z,1647455911.330 [DAT ThreadHandler](INFO): Handler Thread ID is 3926
2022-03-16T18:38:31.330Z,1647455911.330 [DAT](INFO): Powering up
2022-03-16T18:38:31.330Z,1647455911.330 [DAT](DEBUG): Initializing DAT.
2022-03-16T18:38:31.334Z,1647455911.334 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3928
2022-03-16T18:38:31.338Z,1647455911.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-03-16T18:38:31.338Z,1647455911.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-03-16T18:38:31.338Z,1647455911.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-03-16T18:38:31.338Z,1647455911.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-03-16T18:38:31.338Z,1647455911.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-03-16T18:38:31.338Z,1647455911.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-03-16T18:38:31.339Z,1647455911.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-03-16T18:38:31.339Z,1647455911.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-03-16T18:38:31.339Z,1647455911.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-03-16T18:38:31.339Z,1647455911.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-03-16T18:38:31.339Z,1647455911.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-03-16T18:38:31.340Z,1647455911.340 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-03-16T18:38:31.340Z,1647455911.340 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-03-16T18:38:31.340Z,1647455911.340 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-03-16T18:38:31.340Z,1647455911.340 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-03-16T18:38:31.340Z,1647455911.340 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-03-16T18:38:31.353Z,1647455911.353 [CBIT](DEBUG): Deactivating GF circuits.
2022-03-16T18:38:31.353Z,1647455911.353 [CBIT](DEBUG): Deactivating emergency mode.
2022-03-16T18:38:31.389Z,1647455911.389 [CBIT](DEBUG): Backplane powered.
2022-03-16T18:38:31.390Z,1647455911.390 [MissionManager](INFO): Loading Mission from file: Missions/Startup_lab.xml
2022-03-16T18:38:31.401Z,1647455911.401 [Startup_lab:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-16T18:38:31.409Z,1647455911.409 [Startup_lab:B.Wait](DEBUG): Construct Wait.
2022-03-16T18:38:31.412Z,1647455911.412 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup_lab">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>
    
    <Guidance:Wait>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>3</Value></Setting>
    </Guidance:Wait>

</Mission>


2022-03-16T18:38:31.412Z,1647455911.412 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-03-16T18:38:31.587Z,1647455911.587 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-03-16T18:38:31.588Z,1647455911.588 [Default:A.Wait](DEBUG): Construct Wait.
2022-03-16T18:38:31.606Z,1647455911.606 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-16T18:38:31.645Z,1647455911.645 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-03-16T18:38:31.647Z,1647455911.647 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-03-16T18:38:31.669Z,1647455911.669 [Default:E.Execute](DEBUG): Construct Execute.
2022-03-16T18:38:31.673Z,1647455911.673 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2022-03-16T18:38:31.689Z,1647455911.689 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-03-16T18:38:31.705Z,1647455911.705 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-03-16T18:38:31.713Z,1647455911.713 [Radio_Surface](INFO): Powering up
2022-03-16T18:38:31.782Z,1647455911.782 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2022-03-16T18:38:31.783Z,1647455911.783 [Power24vConverter](INFO): Powering up.
2022-03-16T18:38:31.793Z,1647455911.793 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-03-16T18:38:31.820Z,1647455911.820 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-03-16T18:38:31.825Z,1647455911.825 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-03-16T18:38:31.827Z,1647455911.827 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-03-16T18:38:31.834Z,1647455911.834 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-03-16T18:38:31.835Z,1647455911.835 [MassServo](DEBUG): Initializing EZServoServo.
2022-03-16T18:38:31.842Z,1647455911.842 [MassServo](DEBUG): Initializing MassServo.
2022-03-16T18:38:31.843Z,1647455911.843 [RudderServo](DEBUG): Initializing EZServoServo.
2022-03-16T18:38:31.849Z,1647455911.849 [RudderServo](DEBUG): Initializing RudderServo.
2022-03-16T18:38:31.850Z,1647455911.850 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-03-16T18:38:31.861Z,1647455911.861 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-03-16T18:38:32.849Z,1647455912.849 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-03-16T18:38:32.849Z,1647455912.849 [RudderServo](FAULT): Rudder failed to initialize
2022-03-16T18:38:32.850Z,1647455912.849 [RudderServo] Communications Fault, FailCount= 1
2022-03-16T18:38:32.850Z,1647455912.850 [RudderServo](ERROR): Communications Fault
2022-03-16T18:38:32.853Z,1647455912.853 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-03-16T18:38:33.092Z,1647455913.092 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-03-16T18:38:33.093Z,1647455913.093 [RudderServo](INFO): Powering down
2022-03-16T18:38:33.742Z,1647455913.742 [RudderServo](DEBUG): Initializing EZServoServo.
2022-03-16T18:38:33.862Z,1647455913.862 [RudderServo](DEBUG): Initializing RudderServo.
2022-03-16T18:38:33.868Z,1647455913.868 [CBIT](INFO): Clearing failed state for component RudderServo
2022-03-16T18:38:33.868Z,1647455913.868 [RudderServo] No Fault, FailCount= 1
2022-03-16T18:38:33.881Z,1647455913.881 [WetLabsBB2FL](INFO): Powering down
2022-03-16T18:38:37.470Z,1647455917.470 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-03-16T18:38:38.294Z,1647455918.294 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-03-16T18:38:43.174Z,1647455923.174 [DAT](INFO): DAT read: 

2022-03-16T18:38:43.175Z,1647455923.175 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-03-16T18:38:44.440Z,1647455924.440 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005008
2022-03-16T18:38:44.686Z,1647455924.686 [DAT](INFO): DAT read: MF Frequency Band

2022-03-16T18:38:44.688Z,1647455924.688 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-03-16T18:38:44.688Z,1647455924.688 [DAT](INFO): DAT read: Mar 16 2022 18:38:38 

2022-03-16T18:38:45.946Z,1647455925.946 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-03-16T18:38:45.948Z,1647455925.948 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-03-16T18:38:45.948Z,1647455925.948 [DAT](INFO): commRate: 800
2022-03-16T18:38:48.013Z,1647455928.013 [DAT](INFO): entering command mode
2022-03-16T18:38:48.214Z,1647455928.214 [DAT](INFO): DAT read: 

2022-03-16T18:38:48.214Z,1647455928.214 [DAT](INFO): DAT read: user:1>

2022-03-16T18:38:48.215Z,1647455928.215 [DAT](INFO): setting verbose to 3
2022-03-16T18:38:48.466Z,1647455928.466 [DAT](INFO): DAT read: user:1>

2022-03-16T18:38:48.467Z,1647455928.467 [DAT](INFO): DAT read: Verbose         | 3           

2022-03-16T18:38:48.467Z,1647455928.467 [DAT](INFO): set verbose to 3
2022-03-16T18:38:48.468Z,1647455928.468 [DAT](INFO): setting DatVerbose to 27440
2022-03-16T18:38:48.718Z,1647455928.718 [DAT](INFO): DAT read: user:2>

2022-03-16T18:38:48.719Z,1647455928.719 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-03-16T18:38:48.719Z,1647455928.719 [DAT](INFO): set DatVerbose to 27440
2022-03-16T18:38:48.719Z,1647455928.719 [DAT](INFO): setting transmit power to 8
2022-03-16T18:38:48.970Z,1647455928.970 [DAT](INFO): DAT read: user:3>

2022-03-16T18:38:48.971Z,1647455928.971 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-03-16T18:38:48.971Z,1647455928.971 [DAT](INFO): set transmit power to 8
2022-03-16T18:38:48.971Z,1647455928.971 [DAT](INFO): setting local address to 10
2022-03-16T18:38:49.222Z,1647455929.222 [DAT](INFO): DAT read: user:4>

2022-03-16T18:38:49.223Z,1647455929.223 [DAT](INFO): DAT read: LocalAddr       | 10          

2022-03-16T18:38:49.223Z,1647455929.223 [DAT](INFO): set local address to 10
2022-03-16T18:38:49.224Z,1647455929.224 [DAT](INFO): Setting time to: 18:38:49 And date to:3/16/2022
2022-03-16T18:38:49.474Z,1647455929.474 [DAT](INFO): DAT read: user:5>

2022-03-16T18:38:49.475Z,1647455929.475 [DAT](INFO): DAT read: Wed Mar 16, 2022  18:38:49  

2022-03-16T18:38:49.475Z,1647455929.475 [DAT](INFO): Local DAT time set to Wed Mar 16, 2022  18:38:49  

2022-03-16T18:39:00.791Z,1647455940.791 [NAL9602](INFO): Powering up NAL9602
2022-03-16T18:39:09.724Z,1647455949.724 [SBIT](IMPORTANT): Beginning Startup BIT
2022-03-16T18:39:09.741Z,1647455949.741 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-16T18:39:11.719Z,1647455951.719 [NAL9602](INFO): NAL9602 initialized
2022-03-16T18:39:16.799Z,1647455956.799 [CommandExec](IMPORTANT): got command configSet AHRS_M2.readAccelerations 1.000000 bool 
2022-03-16T18:39:20.681Z,1647455960.681 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000989
CHAN A1 (24V): 0.000952
CHAN A2 (12V): -0.005240
CHAN A3 (5V): -0.002428
CHAN B0 (3.3V): -0.001117
CHAN B1 (3.15aV): -0.001196
CHAN B2 (3.15bV): -0.000913
CHAN B3 (GND): -0.001234
OPEN: 0.006259
Full Scale: +/- 1 mA
2022-03-16T18:39:36.560Z,1647455976.560 [CommandExec](IMPORTANT): got command configSet AHRS_M2.verbosity 2.000000 count
2022-03-16T18:39:45.090Z,1647455985.090 [CommandExec](IMPORTANT): got command failVariable
2022-03-16T18:39:45.090Z,1647455985.090 [CommandExec](IMPORTANT): Failed variables:
2022-03-16T18:39:45.091Z,1647455985.091 [CommandExec](IMPORTANT): acoustic_receive_time is invalid.
2022-03-16T18:39:45.091Z,1647455985.091 [CommandExec](IMPORTANT): acoustic_transmit_time is invalid.
2022-03-16T18:39:45.091Z,1647455985.091 [CommandExec](IMPORTANT): acoustic_contact_direction_vehicle_frame is invalid.
2022-03-16T18:39:45.091Z,1647455985.091 [CommandExec](IMPORTANT): acoustic_contact_address is invalid.
2022-03-16T18:39:45.091Z,1647455985.091 [CommandExec](IMPORTANT): acoustic_contact_range is invalid.
2022-03-16T18:39:45.092Z,1647455985.092 [CommandExec](IMPORTANT): distance_from_shore is invalid.
2022-03-16T18:39:45.092Z,1647455985.092 [CommandExec](IMPORTANT): downwelling_photosynthetic_photon_flux_in_sea_water is invalid.
2022-03-16T18:39:45.092Z,1647455985.092 [CommandExec](IMPORTANT): fix_distance_made_good is invalid.
2022-03-16T18:39:45.092Z,1647455985.092 [CommandExec](IMPORTANT): fix_horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:39:45.093Z,1647455985.093 [CommandExec](IMPORTANT): fix_residual_distance is invalid.
2022-03-16T18:39:45.093Z,1647455985.093 [CommandExec](IMPORTANT): fix_residual_bearing is invalid.
2022-03-16T18:39:45.093Z,1647455985.093 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled is invalid.
2022-03-16T18:39:45.093Z,1647455985.093 [CommandExec](IMPORTANT): height_above_sea_floor is invalid.
2022-03-16T18:39:45.094Z,1647455985.094 [CommandExec](IMPORTANT): horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:39:45.094Z,1647455985.094 [CommandExec](IMPORTANT): latitude is invalid.
2022-03-16T18:39:45.094Z,1647455985.094 [CommandExec](IMPORTANT): latitude_fix is invalid.
2022-03-16T18:39:45.094Z,1647455985.094 [CommandExec](IMPORTANT): location_fix is invalid.
2022-03-16T18:39:45.094Z,1647455985.094 [CommandExec](IMPORTANT): longitude is invalid.
2022-03-16T18:39:45.095Z,1647455985.095 [CommandExec](IMPORTANT): longitude_fix is invalid.
2022-03-16T18:39:45.095Z,1647455985.095 [CommandExec](IMPORTANT): mission_started is invalid.
2022-03-16T18:39:45.095Z,1647455985.095 [CommandExec](IMPORTANT): mass_concentration_of_chlorophyll_in_sea_water is invalid.
2022-03-16T18:39:45.095Z,1647455985.095 [CommandExec](IMPORTANT): mass_concentration_of_oxygen_in_sea_water is invalid.
2022-03-16T18:39:45.095Z,1647455985.095 [CommandExec](IMPORTANT): platform_average_current is invalid.
2022-03-16T18:39:45.096Z,1647455985.096 [CommandExec](IMPORTANT): platform_average_power is invalid.
2022-03-16T18:39:45.096Z,1647455985.096 [CommandExec](IMPORTANT): platform_battery_charge is invalid.
2022-03-16T18:39:45.096Z,1647455985.096 [CommandExec](IMPORTANT): platform_communications is invalid.
2022-03-16T18:39:45.096Z,1647455985.096 [CommandExec](IMPORTANT): platform_conversation is invalid.
2022-03-16T18:39:45.097Z,1647455985.097 [CommandExec](IMPORTANT): platform_course is invalid.
2022-03-16T18:39:45.097Z,1647455985.097 [CommandExec](IMPORTANT): platform_fault is invalid.
2022-03-16T18:39:45.097Z,1647455985.097 [CommandExec](IMPORTANT): platform_fault_leak is invalid.
2022-03-16T18:39:45.098Z,1647455985.098 [CommandExec](IMPORTANT): platform_speed_wrt_ground is invalid.
2022-03-16T18:39:45.098Z,1647455985.098 [CommandExec](IMPORTANT): platform_vehicle_power is invalid.
2022-03-16T18:39:45.100Z,1647455985.100 [CommandExec](IMPORTANT): sea_floor_depth_below_geoid is invalid.
2022-03-16T18:39:45.100Z,1647455985.100 [CommandExec](IMPORTANT): sea_water_density is invalid.
2022-03-16T18:39:45.100Z,1647455985.100 [CommandExec](IMPORTANT): sea_water_electrical_conductivity is invalid.
2022-03-16T18:39:45.100Z,1647455985.100 [CommandExec](IMPORTANT): sea_water_salinity is invalid.
2022-03-16T18:39:45.101Z,1647455985.101 [CommandExec](IMPORTANT): sea_water_temperature is invalid.
2022-03-16T18:39:45.101Z,1647455985.101 [CommandExec](IMPORTANT): speed_of_sound_in_sea_water is invalid.
2022-03-16T18:39:45.101Z,1647455985.101 [CommandExec](IMPORTANT): time_fix is invalid.
2022-03-16T18:39:45.101Z,1647455985.101 [CommandExec](IMPORTANT): upward_derivative_of_sea_water_temperature is invalid.
2022-03-16T18:39:45.102Z,1647455985.102 [CommandExec](IMPORTANT): Dock.docking_state is unavailable.
2022-03-16T18:39:45.102Z,1647455985.102 [CommandExec](IMPORTANT): Dock.docking_state is invalid.
2022-03-16T18:39:45.368Z,1647455985.368 [CommandExec](IMPORTANT): SetNav.time_fix is unavailable.
2022-03-16T18:39:45.369Z,1647455985.369 [CommandExec](IMPORTANT): SetNav.time_fix is invalid.
2022-03-16T18:39:45.369Z,1647455985.369 [CommandExec](IMPORTANT): SetNav.latitude_fix is unavailable.
2022-03-16T18:39:45.369Z,1647455985.369 [CommandExec](IMPORTANT): SetNav.latitude_fix is invalid.
2022-03-16T18:39:45.377Z,1647455985.377 [CommandExec](IMPORTANT): SetNav.longitude_fix is unavailable.
2022-03-16T18:39:45.377Z,1647455985.377 [CommandExec](IMPORTANT): SetNav.longitude_fix is invalid.
2022-03-16T18:39:45.378Z,1647455985.378 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.latitude is invalid.
2022-03-16T18:39:45.378Z,1647455985.378 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.longitude is invalid.
2022-03-16T18:39:45.378Z,1647455985.378 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.depth is invalid.
2022-03-16T18:39:45.378Z,1647455985.378 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.platform_speed_wrt_ground is invalid.
2022-03-16T18:39:45.378Z,1647455985.378 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.platform_course is invalid.
2022-03-16T18:39:45.379Z,1647455985.379 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:39:45.379Z,1647455985.379 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.latitude is invalid.
2022-03-16T18:39:45.380Z,1647455985.380 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.longitude is invalid.
2022-03-16T18:39:45.380Z,1647455985.380 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.depth is invalid.
2022-03-16T18:39:45.380Z,1647455985.380 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.platform_speed_wrt_ground is invalid.
2022-03-16T18:39:45.380Z,1647455985.380 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.platform_course is invalid.
2022-03-16T18:39:45.380Z,1647455985.380 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:39:45.390Z,1647455985.390 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_electrical_conductivity is invalid.
2022-03-16T18:39:45.390Z,1647455985.390 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_temperature is invalid.
2022-03-16T18:39:45.390Z,1647455985.390 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_pressure is invalid.
2022-03-16T18:39:45.390Z,1647455985.390 [CommandExec](IMPORTANT): CTD_Seabird.depth is invalid.
2022-03-16T18:39:45.391Z,1647455985.391 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_salinity is invalid.
2022-03-16T18:39:45.391Z,1647455985.391 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_density is invalid.
2022-03-16T18:39:45.391Z,1647455985.391 [CommandExec](IMPORTANT): CTD_Seabird.speed_of_sound_in_sea_water is invalid.
2022-03-16T18:39:45.392Z,1647455985.392 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water is invalid.
2022-03-16T18:39:45.401Z,1647455985.401 [CommandExec](IMPORTANT): VerticalControl.depthCmd is unavailable.
2022-03-16T18:39:45.401Z,1647455985.401 [CommandExec](IMPORTANT): VerticalControl.depthCmd is invalid.
2022-03-16T18:39:45.402Z,1647455985.402 [CommandExec](IMPORTANT): VerticalControl.pitchRateCmd is unavailable.
2022-03-16T18:39:45.402Z,1647455985.402 [CommandExec](IMPORTANT): VerticalControl.pitchRateCmd is invalid.
2022-03-16T18:39:45.402Z,1647455985.402 [CommandExec](IMPORTANT): VerticalControl.buoyancyCmd is unavailable.
2022-03-16T18:39:45.402Z,1647455985.402 [CommandExec](IMPORTANT): VerticalControl.buoyancyCmd is invalid.
2022-03-16T18:39:45.403Z,1647455985.403 [CommandExec](IMPORTANT): LoopControl.periodCmd is unavailable.
2022-03-16T18:39:45.403Z,1647455985.403 [CommandExec](IMPORTANT): LoopControl.periodCmd is invalid.
2022-03-16T18:39:45.404Z,1647455985.404 [CommandExec](IMPORTANT): HorizontalControl.latitudeCmd is unavailable.
2022-03-16T18:39:45.404Z,1647455985.404 [CommandExec](IMPORTANT): HorizontalControl.latitudeCmd is invalid.
2022-03-16T18:39:45.404Z,1647455985.404 [CommandExec](IMPORTANT): HorizontalControl.longitudeCmd is unavailable.
2022-03-16T18:39:45.404Z,1647455985.404 [CommandExec](IMPORTANT): HorizontalControl.longitudeCmd is invalid.
2022-03-16T18:39:45.404Z,1647455985.404 [CommandExec](IMPORTANT): HorizontalControl.headingCmd is unavailable.
2022-03-16T18:39:45.404Z,1647455985.404 [CommandExec](IMPORTANT): HorizontalControl.headingCmd is invalid.
2022-03-16T18:39:45.405Z,1647455985.405 [CommandExec](IMPORTANT): HorizontalControl.headingRateCmd is unavailable.
2022-03-16T18:39:45.405Z,1647455985.405 [CommandExec](IMPORTANT): HorizontalControl.headingRateCmd is invalid.
2022-03-16T18:39:45.414Z,1647455985.414 [CommandExec](IMPORTANT): HorizontalControl.bearingCmd is unavailable.
2022-03-16T18:39:45.414Z,1647455985.414 [CommandExec](IMPORTANT): HorizontalControl.bearingCmd is invalid.
2022-03-16T18:39:45.414Z,1647455985.414 [CommandExec](IMPORTANT): HorizontalControl.kdHeadingOverride is unavailable.
2022-03-16T18:39:45.414Z,1647455985.414 [CommandExec](IMPORTANT): HorizontalControl.kdHeadingOverride is invalid.
2022-03-16T18:39:45.414Z,1647455985.414 [CommandExec](IMPORTANT): HorizontalControl.kiHeadingOverride is unavailable.
2022-03-16T18:39:45.415Z,1647455985.415 [CommandExec](IMPORTANT): HorizontalControl.kiHeadingOverride is invalid.
2022-03-16T18:39:45.415Z,1647455985.415 [CommandExec](IMPORTANT): HorizontalControl.kpHeadingOverride is unavailable.
2022-03-16T18:39:45.415Z,1647455985.415 [CommandExec](IMPORTANT): HorizontalControl.kpHeadingOverride is invalid.
2022-03-16T18:39:45.498Z,1647455985.498 [CommandExec](IMPORTANT): DAT.queryAddressRequested is unavailable.
2022-03-16T18:39:45.498Z,1647455985.498 [CommandExec](IMPORTANT): DAT.queryAddressRequested is invalid.
2022-03-16T18:39:45.499Z,1647455985.499 [CommandExec](IMPORTANT): DAT.numberOfPingsRequested is unavailable.
2022-03-16T18:39:45.499Z,1647455985.499 [CommandExec](IMPORTANT): DAT.numberOfPingsRequested is invalid.
2022-03-16T18:39:45.501Z,1647455985.501 [CommandExec](IMPORTANT): CBIT.clearFaultCmd is unavailable.
2022-03-16T18:39:45.501Z,1647455985.501 [CommandExec](IMPORTANT): CBIT.clearFaultCmd is invalid.
2022-03-16T18:39:45.501Z,1647455985.501 [CommandExec](IMPORTANT): CBIT.clearLeakFaultCmd is unavailable.
2022-03-16T18:39:45.509Z,1647455985.509 [CommandExec](IMPORTANT): CBIT.clearLeakFaultCmd is invalid.
2022-03-16T18:39:45.510Z,1647455985.510 [CommandExec](IMPORTANT): BPC1.BattTemp_0 is unavailable.
2022-03-16T18:39:45.510Z,1647455985.510 [CommandExec](IMPORTANT): BPC1.BattTemp_0 is invalid.
2022-03-16T18:39:51.662Z,1647455991.662 [CommandExec](IMPORTANT): got command failComponent
2022-03-16T18:39:51.662Z,1647455991.662 [CommandExec](IMPORTANT): Failed components:
2022-03-16T18:39:51.662Z,1647455991.662 [CommandExec](IMPORTANT): No failed Components.
2022-03-16T18:40:00.728Z,1647456000.728 [CommandExec](IMPORTANT): got command failVariable
2022-03-16T18:40:00.729Z,1647456000.729 [CommandExec](IMPORTANT): Failed variables:
2022-03-16T18:40:00.729Z,1647456000.729 [CommandExec](IMPORTANT): acoustic_receive_time is invalid.
2022-03-16T18:40:00.729Z,1647456000.729 [CommandExec](IMPORTANT): acoustic_transmit_time is invalid.
2022-03-16T18:40:00.729Z,1647456000.729 [CommandExec](IMPORTANT): acoustic_contact_direction_vehicle_frame is invalid.
2022-03-16T18:40:00.730Z,1647456000.730 [CommandExec](IMPORTANT): acoustic_contact_address is invalid.
2022-03-16T18:40:00.730Z,1647456000.730 [CommandExec](IMPORTANT): acoustic_contact_range is invalid.
2022-03-16T18:40:00.730Z,1647456000.730 [CommandExec](IMPORTANT): distance_from_shore is invalid.
2022-03-16T18:40:00.730Z,1647456000.730 [CommandExec](IMPORTANT): downwelling_photosynthetic_photon_flux_in_sea_water is invalid.
2022-03-16T18:40:00.731Z,1647456000.731 [CommandExec](IMPORTANT): fix_distance_made_good is invalid.
2022-03-16T18:40:00.731Z,1647456000.731 [CommandExec](IMPORTANT): fix_horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:40:00.731Z,1647456000.731 [CommandExec](IMPORTANT): fix_residual_distance is invalid.
2022-03-16T18:40:00.731Z,1647456000.731 [CommandExec](IMPORTANT): fix_residual_bearing is invalid.
2022-03-16T18:40:00.731Z,1647456000.731 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled is invalid.
2022-03-16T18:40:00.732Z,1647456000.732 [CommandExec](IMPORTANT): height_above_sea_floor is invalid.
2022-03-16T18:40:00.732Z,1647456000.732 [CommandExec](IMPORTANT): horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:40:00.732Z,1647456000.732 [CommandExec](IMPORTANT): latitude is invalid.
2022-03-16T18:40:00.732Z,1647456000.732 [CommandExec](IMPORTANT): latitude_fix is invalid.
2022-03-16T18:40:00.732Z,1647456000.732 [CommandExec](IMPORTANT): location_fix is invalid.
2022-03-16T18:40:00.733Z,1647456000.733 [CommandExec](IMPORTANT): longitude is invalid.
2022-03-16T18:40:00.733Z,1647456000.733 [CommandExec](IMPORTANT): longitude_fix is invalid.
2022-03-16T18:40:00.733Z,1647456000.733 [CommandExec](IMPORTANT): mission_started is invalid.
2022-03-16T18:40:00.733Z,1647456000.733 [CommandExec](IMPORTANT): mass_concentration_of_chlorophyll_in_sea_water is invalid.
2022-03-16T18:40:00.734Z,1647456000.734 [CommandExec](IMPORTANT): mass_concentration_of_oxygen_in_sea_water is invalid.
2022-03-16T18:40:00.734Z,1647456000.734 [CommandExec](IMPORTANT): platform_average_current is invalid.
2022-03-16T18:40:00.734Z,1647456000.734 [CommandExec](IMPORTANT): platform_average_power is invalid.
2022-03-16T18:40:00.734Z,1647456000.734 [CommandExec](IMPORTANT): platform_battery_charge is invalid.
2022-03-16T18:40:00.735Z,1647456000.735 [CommandExec](IMPORTANT): platform_communications is invalid.
2022-03-16T18:40:00.735Z,1647456000.735 [CommandExec](IMPORTANT): platform_conversation is invalid.
2022-03-16T18:40:00.735Z,1647456000.735 [CommandExec](IMPORTANT): platform_course is invalid.
2022-03-16T18:40:00.735Z,1647456000.735 [CommandExec](IMPORTANT): platform_fault is invalid.
2022-03-16T18:40:00.736Z,1647456000.736 [CommandExec](IMPORTANT): platform_fault_leak is invalid.
2022-03-16T18:40:00.736Z,1647456000.736 [CommandExec](IMPORTANT): platform_speed_wrt_ground is invalid.
2022-03-16T18:40:00.737Z,1647456000.737 [CommandExec](IMPORTANT): platform_vehicle_power is invalid.
2022-03-16T18:40:00.738Z,1647456000.738 [CommandExec](IMPORTANT): sea_floor_depth_below_geoid is invalid.
2022-03-16T18:40:00.738Z,1647456000.738 [CommandExec](IMPORTANT): sea_water_density is invalid.
2022-03-16T18:40:00.738Z,1647456000.738 [CommandExec](IMPORTANT): sea_water_electrical_conductivity is invalid.
2022-03-16T18:40:00.739Z,1647456000.739 [CommandExec](IMPORTANT): sea_water_salinity is invalid.
2022-03-16T18:40:00.739Z,1647456000.739 [CommandExec](IMPORTANT): sea_water_temperature is invalid.
2022-03-16T18:40:00.739Z,1647456000.739 [CommandExec](IMPORTANT): speed_of_sound_in_sea_water is invalid.
2022-03-16T18:40:00.739Z,1647456000.739 [CommandExec](IMPORTANT): time_fix is invalid.
2022-03-16T18:40:00.740Z,1647456000.740 [CommandExec](IMPORTANT): upward_derivative_of_sea_water_temperature is invalid.
2022-03-16T18:40:00.740Z,1647456000.740 [CommandExec](IMPORTANT): Dock.docking_state is unavailable.
2022-03-16T18:40:00.740Z,1647456000.740 [CommandExec](IMPORTANT): Dock.docking_state is invalid.
2022-03-16T18:40:00.838Z,1647456000.838 [CommandExec](IMPORTANT): SetNav.time_fix is unavailable.
2022-03-16T18:40:00.838Z,1647456000.838 [CommandExec](IMPORTANT): SetNav.time_fix is invalid.
2022-03-16T18:40:00.838Z,1647456000.838 [CommandExec](IMPORTANT): SetNav.latitude_fix is unavailable.
2022-03-16T18:40:00.838Z,1647456000.838 [CommandExec](IMPORTANT): SetNav.latitude_fix is invalid.
2022-03-16T18:40:00.839Z,1647456000.839 [CommandExec](IMPORTANT): SetNav.longitude_fix is unavailable.
2022-03-16T18:40:00.839Z,1647456000.839 [CommandExec](IMPORTANT): SetNav.longitude_fix is invalid.
2022-03-16T18:40:00.839Z,1647456000.839 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.latitude is invalid.
2022-03-16T18:40:00.839Z,1647456000.839 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.longitude is invalid.
2022-03-16T18:40:00.839Z,1647456000.839 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.depth is invalid.
2022-03-16T18:40:00.840Z,1647456000.840 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.platform_speed_wrt_ground is invalid.
2022-03-16T18:40:00.840Z,1647456000.840 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.platform_course is invalid.
2022-03-16T18:40:00.840Z,1647456000.840 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:40:00.841Z,1647456000.841 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.latitude is invalid.
2022-03-16T18:40:00.845Z,1647456000.845 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.longitude is invalid.
2022-03-16T18:40:00.846Z,1647456000.846 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.depth is invalid.
2022-03-16T18:40:00.846Z,1647456000.846 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.platform_speed_wrt_ground is invalid.
2022-03-16T18:40:00.846Z,1647456000.846 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.platform_course is invalid.
2022-03-16T18:40:00.846Z,1647456000.846 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.horizontal_path_length_since_last_fix is invalid.
2022-03-16T18:40:00.847Z,1647456000.847 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_electrical_conductivity is invalid.
2022-03-16T18:40:00.848Z,1647456000.848 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_temperature is invalid.
2022-03-16T18:40:00.848Z,1647456000.848 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_pressure is invalid.
2022-03-16T18:40:00.848Z,1647456000.848 [CommandExec](IMPORTANT): CTD_Seabird.depth is invalid.
2022-03-16T18:40:00.848Z,1647456000.848 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_salinity is invalid.
2022-03-16T18:40:00.848Z,1647456000.848 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_density is invalid.
2022-03-16T18:40:00.849Z,1647456000.849 [CommandExec](IMPORTANT): CTD_Seabird.speed_of_sound_in_sea_water is invalid.
2022-03-16T18:40:00.854Z,1647456000.854 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water is invalid.
2022-03-16T18:40:00.855Z,1647456000.855 [CommandExec](IMPORTANT): VerticalControl.depthCmd is unavailable.
2022-03-16T18:40:00.855Z,1647456000.855 [CommandExec](IMPORTANT): VerticalControl.depthCmd is invalid.
2022-03-16T18:40:00.855Z,1647456000.855 [CommandExec](IMPORTANT): VerticalControl.pitchRateCmd is unavailable.
2022-03-16T18:40:00.855Z,1647456000.855 [CommandExec](IMPORTANT): VerticalControl.pitchRateCmd is invalid.
2022-03-16T18:40:00.856Z,1647456000.856 [CommandExec](IMPORTANT): VerticalControl.buoyancyCmd is unavailable.
2022-03-16T18:40:00.856Z,1647456000.856 [CommandExec](IMPORTANT): VerticalControl.buoyancyCmd is invalid.
2022-03-16T18:40:00.856Z,1647456000.856 [CommandExec](IMPORTANT): LoopControl.periodCmd is unavailable.
2022-03-16T18:40:00.856Z,1647456000.856 [CommandExec](IMPORTANT): LoopControl.periodCmd is invalid.
2022-03-16T18:40:00.862Z,1647456000.862 [CommandExec](IMPORTANT): HorizontalControl.latitudeCmd is unavailable.
2022-03-16T18:40:00.862Z,1647456000.862 [CommandExec](IMPORTANT): HorizontalControl.latitudeCmd is invalid.
2022-03-16T18:40:00.862Z,1647456000.862 [CommandExec](IMPORTANT): HorizontalControl.longitudeCmd is unavailable.
2022-03-16T18:40:00.862Z,1647456000.862 [CommandExec](IMPORTANT): HorizontalControl.longitudeCmd is invalid.
2022-03-16T18:40:00.862Z,1647456000.862 [CommandExec](IMPORTANT): HorizontalControl.headingCmd is unavailable.
2022-03-16T18:40:00.862Z,1647456000.862 [CommandExec](IMPORTANT): HorizontalControl.headingCmd is invalid.
2022-03-16T18:40:00.863Z,1647456000.863 [CommandExec](IMPORTANT): HorizontalControl.headingRateCmd is unavailable.
2022-03-16T18:40:00.863Z,1647456000.863 [CommandExec](IMPORTANT): HorizontalControl.headingRateCmd is invalid.
2022-03-16T18:40:00.863Z,1647456000.863 [CommandExec](IMPORTANT): HorizontalControl.bearingCmd is unavailable.
2022-03-16T18:40:00.863Z,1647456000.863 [CommandExec](IMPORTANT): HorizontalControl.bearingCmd is invalid.
2022-03-16T18:40:00.863Z,1647456000.863 [CommandExec](IMPORTANT): HorizontalControl.kdHeadingOverride is unavailable.
2022-03-16T18:40:00.863Z,1647456000.863 [CommandExec](IMPORTANT): HorizontalControl.kdHeadingOverride is invalid.
2022-03-16T18:40:00.864Z,1647456000.864 [CommandExec](IMPORTANT): HorizontalControl.kiHeadingOverride is unavailable.
2022-03-16T18:40:00.864Z,1647456000.864 [CommandExec](IMPORTANT): HorizontalControl.kiHeadingOverride is invalid.
2022-03-16T18:40:00.864Z,1647456000.864 [CommandExec](IMPORTANT): HorizontalControl.kpHeadingOverride is unavailable.
2022-03-16T18:40:00.864Z,1647456000.864 [CommandExec](IMPORTANT): HorizontalControl.kpHeadingOverride is invalid.
2022-03-16T18:40:00.942Z,1647456000.942 [CommandExec](IMPORTANT): DAT.queryAddressRequested is unavailable.
2022-03-16T18:40:00.943Z,1647456000.943 [CommandExec](IMPORTANT): DAT.queryAddressRequested is invalid.
2022-03-16T18:40:00.943Z,1647456000.943 [CommandExec](IMPORTANT): DAT.numberOfPingsRequested is unavailable.
2022-03-16T18:40:00.943Z,1647456000.943 [CommandExec](IMPORTANT): DAT.numberOfPingsRequested is invalid.
2022-03-16T18:40:00.945Z,1647456000.945 [CommandExec](IMPORTANT): CBIT.clearFaultCmd is unavailable.
2022-03-16T18:40:00.945Z,1647456000.945 [CommandExec](IMPORTANT): CBIT.clearFaultCmd is invalid.
2022-03-16T18:40:00.949Z,1647456000.949 [CommandExec](IMPORTANT): CBIT.clearLeakFaultCmd is unavailable.
2022-03-16T18:40:00.949Z,1647456000.949 [CommandExec](IMPORTANT): CBIT.clearLeakFaultCmd is invalid.
2022-03-16T18:40:00.950Z,1647456000.950 [CommandExec](IMPORTANT): BPC1.BattTemp_0 is unavailable.
2022-03-16T18:40:00.950Z,1647456000.950 [CommandExec](IMPORTANT): BPC1.BattTemp_0 is invalid.
2022-03-16T18:40:03.850Z,1647456003.850 [SBIT](IMPORTANT): SBIT PASSED
2022-03-16T18:40:03.850Z,1647456003.850 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-03-16T18:40:03.851Z,1647456003.851 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2022-03-16T18:40:03.851Z,1647456003.851 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): DAT.sbdAddress=21 enum;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): DAT.surfaceThreshold=3 meter;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 10.000000 gigabyte;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 100.000000 count;

2022-03-16T18:40:03.852Z,1647456003.852 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_size 2.500000 millimeter;

2022-03-16T18:40:03.853Z,1647456003.853 [SBIT](IMPORTANT): Express linearApproximation _.eyeris_particle_counts 100.000000 count;

2022-03-16T18:40:03.853Z,1647456003.853 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour;

2022-03-16T18:40:03.853Z,1647456003.853 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt;

2022-03-16T18:40:03.854Z,1647456003.854 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2022-03-16T18:40:03.854Z,1647456003.854 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=115.741138 cubic_centimeter;

2022-03-16T18:40:03.854Z,1647456003.854 [SBIT](IMPORTANT): VerticalControl.massDefault=-4.597572 millimeter;

2022-03-16T18:40:04.240Z,1647456004.240 [MissionManager](IMPORTANT): Started mission Startup_lab
2022-03-16T18:40:04.240Z,1647456004.240 [Startup_lab] Running Loop=1
2022-03-16T18:40:04.240Z,1647456004.240 [Startup_lab](DEBUG): Aggregate::initialize Startup_lab
2022-03-16T18:40:04.240Z,1647456004.240 [Startup_lab:A.GoToSurface] Running Loop=1
2022-03-16T18:40:04.240Z,1647456004.240 [Startup_lab:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-16T18:40:04.241Z,1647456004.241 [Startup_lab:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-16T18:40:04.242Z,1647456004.242 [Startup_lab:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-16T18:40:04.242Z,1647456004.242 [Startup_lab:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-16T18:40:04.242Z,1647456004.242 [Startup_lab:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-16T18:40:04.243Z,1647456004.243 [Startup_lab:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-16T18:40:04.245Z,1647456004.245 [Startup_lab:B.Wait] Running Loop=1
2022-03-16T18:40:04.245Z,1647456004.245 [Startup_lab:B.Wait](DEBUG): Initialize Wait Component.
2022-03-16T18:40:07.880Z,1647456007.880 [Startup_lab:B.Wait](INFO): Done Waiting.
2022-03-16T18:40:07.880Z,1647456007.880 [Startup_lab:B.Wait] Stopped
2022-03-16T18:40:07.880Z,1647456007.880 [Startup_lab:B.Wait](DEBUG): Uninitialize Wait Component.
2022-03-16T18:40:07.881Z,1647456007.881 [Startup_lab](INFO): Completed Startup_lab
2022-03-16T18:40:07.882Z,1647456007.882 [MissionManager](INFO): Startup_lab is completed.
2022-03-16T18:40:07.882Z,1647456007.882 [MissionManager](INFO): Uninitializing Mission Startup_lab
2022-03-16T18:40:07.882Z,1647456007.882 [Startup_lab] Stopped
2022-03-16T18:40:07.882Z,1647456007.882 [Startup_lab](DEBUG): Aggregate::uninitialize Startup_lab
2022-03-16T18:40:07.882Z,1647456007.882 [Startup_lab:A.GoToSurface] Stopped
2022-03-16T18:40:07.882Z,1647456007.882 [Startup_lab:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-16T18:40:08.282Z,1647456008.282 [MissionManager](IMPORTANT): Started mission Default
2022-03-16T18:40:08.282Z,1647456008.282 [Default] Running Loop=1
2022-03-16T18:40:08.282Z,1647456008.282 [Default](DEBUG): Aggregate::initialize Default
2022-03-16T18:40:08.282Z,1647456008.282 [Default:B.GoToSurface] Running Loop=1
2022-03-16T18:40:08.282Z,1647456008.282 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-16T18:40:08.283Z,1647456008.283 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-16T18:40:08.283Z,1647456008.283 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-16T18:40:08.283Z,1647456008.283 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-16T18:40:08.283Z,1647456008.283 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-16T18:40:08.284Z,1647456008.284 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-16T18:40:08.284Z,1647456008.284 [Default:A.Wait] Running Loop=1
2022-03-16T18:40:08.284Z,1647456008.284 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-03-16T18:40:21.614Z,1647456021.614 [Default:A.Wait](INFO): Done Waiting.
2022-03-16T18:40:21.615Z,1647456021.615 [Default:A.Wait] Stopped
2022-03-16T18:40:21.615Z,1647456021.615 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-03-16T18:40:22.028Z,1647456022.028 [Default:CheckIn] Running Loop=1
2022-03-16T18:40:22.028Z,1647456022.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-16T18:40:22.028Z,1647456022.028 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-16T18:40:22.448Z,1647456022.448 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-03-16T18:40:37.100Z,1647456037.100 [CommandExec](IMPORTANT): got command get AHRS_M2.loadAtStartup
2022-03-16T18:40:37.101Z,1647456037.101 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup 1 bool
2022-03-16T18:40:48.166Z,1647456048.166 [CommandExec](IMPORTANT): got command failComponent hardware AHRS_M2
2022-03-16T18:40:48.167Z,1647456048.167 [AHRS_M2] Hardware Fault, FailCount= 1
2022-03-16T18:40:48.167Z,1647456048.167 [AHRS_M2](ERROR): Hardware Fault
2022-03-16T18:40:48.170Z,1647456048.170 [CommandExec](IMPORTANT): AHRS_M2 failureMode is Hardware Fault
2022-03-16T18:40:48.254Z,1647456048.254 [AHRS_M2](INFO): Powering down
2022-03-16T18:40:48.358Z,1647456048.358 [CBIT](ERROR): Hardware Fault in component: AHRS_M2
2022-03-16T18:40:49.097Z,1647456049.097 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-03-16T18:40:49.105Z,1647456049.105 [AHRS_M2] No Fault, FailCount= 1
2022-03-16T18:40:49.466Z,1647456049.466 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-03-16T18:41:14.751Z,1647456074.751 [CommandExec](IMPORTANT): got command configSet AHRS_M2.verbosity 3.000000 count
2022-03-16T18:41:21.002Z,1647456081.002 [CommandExec](IMPORTANT): got command failComponent
2022-03-16T18:41:21.002Z,1647456081.002 [CommandExec](IMPORTANT): Failed components:
2022-03-16T18:41:21.002Z,1647456081.002 [CommandExec](IMPORTANT): No failed Components.
2022-03-16T18:41:31.546Z,1647456091.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-03-16T18:41:31.546Z,1647456091.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-16T18:41:31.547Z,1647456091.547 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2022-03-16T18:41:31.547Z,1647456091.547 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-03-16T18:41:31.627Z,1647456091.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-16T18:41:31.627Z,1647456091.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-03-16T18:41:31.996Z,1647456091.996 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-16T18:41:31.996Z,1647456091.996 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-03-16T18:41:31.996Z,1647456091.996 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-03-16T18:41:31.997Z,1647456091.997 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2022-03-16T18:41:32.317Z,1647456092.317 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-03-16T18:41:39.370Z,1647456099.370 [CommandExec](IMPORTANT): got command get AHRS_M2.verbosity
2022-03-16T18:41:39.371Z,1647456099.371 [CommandExec](IMPORTANT): AHRS_M2.verbosity 0 count
2022-03-16T18:44:14.716Z,1647456254.716 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-03-16T18:44:32.500Z,1647456272.500 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-03-16T18:44:32.500Z,1647456272.500 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-16T18:44:32.518Z,1647456272.518 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2022-03-16T18:44:32.518Z,1647456272.518 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2022-03-16T18:44:32.541Z,1647456272.541 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-16T18:44:32.545Z,1647456272.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2022-03-16T18:44:32.946Z,1647456272.946 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-16T18:44:32.946Z,1647456272.946 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-03-16T18:44:32.946Z,1647456272.946 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2022-03-16T18:44:32.946Z,1647456272.946 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2022-03-16T18:44:33.330Z,1647456273.330 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2022-03-16T18:44:48.672Z,1647456288.672 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-03-16T18:45:22.187Z,1647456322.187 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T18:40:22.0Z
2022-03-16T18:45:22.187Z,1647456322.187 [Default:CheckIn:Read_GPS] Stopped
2022-03-16T18:45:22.188Z,1647456322.188 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-16T18:45:22.596Z,1647456322.596 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-16T18:45:28.884Z,1647456328.884 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220217T031250/Courier0013.lzma
2022-03-16T18:45:29.879Z,1647456329.879 [DataOverHttps](INFO): Moved sent file to Logs/20220217T031250/Courier0013.lzma.bak
2022-03-16T18:45:29.879Z,1647456329.879 [DataOverHttps](INFO): SBD MOMSN=16574266
2022-03-16T18:45:45.709Z,1647456345.709 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220309T184600/Courier0000.lzma
2022-03-16T18:45:46.711Z,1647456346.711 [DataOverHttps](INFO): Moved sent file to Logs/20220309T184600/Courier0000.lzma.bak
2022-03-16T18:45:46.711Z,1647456346.711 [DataOverHttps](INFO): SBD MOMSN=16574269
2022-03-16T18:46:02.202Z,1647456362.202 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-03-16T18:46:02.205Z,1647456362.205 [BPC1](INFO): Received data from all battery sticks.
2022-03-16T18:46:02.758Z,1647456362.758 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220316T183821/Courier0000.lzma
2022-03-16T18:46:03.759Z,1647456363.759 [DataOverHttps](INFO): Moved sent file to Logs/20220316T183821/Courier0000.lzma.bak
2022-03-16T18:46:03.759Z,1647456363.759 [DataOverHttps](INFO): SBD MOMSN=16574272
2022-03-16T18:46:19.302Z,1647456379.302 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20220217T031250/Express0014.lzma
2022-03-16T18:46:20.303Z,1647456380.303 [DataOverHttps](INFO): Moved sent file to Logs/20220217T031250/Express0014.lzma.bak
2022-03-16T18:46:20.303Z,1647456380.303 [DataOverHttps](INFO): SBD MOMSN=16574278
2022-03-16T18:46:40.574Z,1647456400.574 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20220309T184600/Express0001.lzma
2022-03-16T18:46:41.575Z,1647456401.575 [DataOverHttps](INFO): Moved sent file to Logs/20220309T184600/Express0001.lzma.bak
2022-03-16T18:46:41.575Z,1647456401.575 [DataOverHttps](INFO): SBD MOMSN=16574284
2022-03-16T18:47:09.081Z,1647456429.081 [DataOverHttps](INFO): Sending 2686 bytes from file Logs/20220316T183821/Express0001.lzma
2022-03-16T18:47:10.115Z,1647456430.115 [DataOverHttps](INFO): Moved sent file to Logs/20220316T183821/Express0001.lzma.bak
2022-03-16T18:47:10.115Z,1647456430.115 [DataOverHttps](INFO): SBD MOMSN=16574292
2022-03-16T18:47:11.739Z,1647456431.739 [Default:CheckIn:Read_Iridium] Stopped
2022-03-16T18:47:11.739Z,1647456431.739 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-16T18:47:11.739Z,1647456431.739 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-16T18:47:15.506Z,1647456435.506 [CommandExec](IMPORTANT): got command restart application
2022-03-16T18:47:16.845Z,1647456436.845 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-03-16T18:47:16.845Z,1647456436.845 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:16.846Z,1647456436.846 [CommandExec](INFO): Uninitializing the command executive.
2022-03-16T18:47:16.846Z,1647456436.846 [CommandExec](INFO): Uninitializing the command scheduler.
2022-03-16T18:47:16.846Z,1647456436.846 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:16.897Z,1647456436.897 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-03-16T18:47:16.897Z,1647456436.897 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-03-16T18:47:16.897Z,1647456436.897 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:16.898Z,1647456436.898 [NavChartDb](INFO): Join timeout helper Thread ID is 3958
2022-03-16T18:47:16.921Z,1647456436.921 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:16.922Z,1647456436.922 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:16.965Z,1647456436.965 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-03-16T18:47:16.965Z,1647456436.965 [DAT ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:16.966Z,1647456436.966 [DAT](INFO): Join timeout helper Thread ID is 3959
2022-03-16T18:47:17.089Z,1647456437.089 [DAT](INFO): Powering down
2022-03-16T18:47:17.162Z,1647456437.162 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:17.162Z,1647456437.162 [DAT](INFO): Powering down
2022-03-16T18:47:17.163Z,1647456437.163 [DAT ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:17.174Z,1647456437.174 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-03-16T18:47:17.174Z,1647456437.174 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:17.174Z,1647456437.174 [Radio_Surface](INFO): Join timeout helper Thread ID is 3960
2022-03-16T18:47:17.481Z,1647456437.481 [Radio_Surface](INFO): Powering down
2022-03-16T18:47:17.482Z,1647456437.482 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:17.482Z,1647456437.482 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:17.483Z,1647456437.483 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-03-16T18:47:17.483Z,1647456437.483 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:17.483Z,1647456437.483 [Onboard](INFO): Join timeout helper Thread ID is 3961
2022-03-16T18:47:21.713Z,1647456441.713 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:21.714Z,1647456441.714 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:21.733Z,1647456441.733 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-03-16T18:47:21.733Z,1647456441.733 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:21.734Z,1647456441.734 [DataOverHttps](INFO): Join timeout helper Thread ID is 3962
2022-03-16T18:47:21.953Z,1647456441.953 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:21.956Z,1647456441.956 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:21.971Z,1647456441.971 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-03-16T18:47:21.971Z,1647456441.971 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:21.973Z,1647456441.973 [BackseatComponent](INFO): Join timeout helper Thread ID is 3963
2022-03-16T18:47:22.073Z,1647456442.073 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:22.074Z,1647456442.074 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.078Z,1647456442.078 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-03-16T18:47:22.078Z,1647456442.078 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.078Z,1647456442.078 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3964
2022-03-16T18:47:22.220Z,1647456442.220 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:22.220Z,1647456442.220 [WetLabsBB2FL](INFO): Powering down
2022-03-16T18:47:22.221Z,1647456442.221 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.250Z,1647456442.250 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-03-16T18:47:22.250Z,1647456442.250 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.250Z,1647456442.250 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3965
2022-03-16T18:47:22.493Z,1647456442.493 [CTD_Seabird](INFO): Powering down
2022-03-16T18:47:22.505Z,1647456442.505 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:22.505Z,1647456442.505 [CTD_Seabird](INFO): Powering down
2022-03-16T18:47:22.517Z,1647456442.517 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.526Z,1647456442.526 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-03-16T18:47:22.526Z,1647456442.526 [logger ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.526Z,1647456442.526 [logger](INFO): Join timeout helper Thread ID is 3966
2022-03-16T18:47:22.537Z,1647456442.537 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:22.538Z,1647456442.538 [logger ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.546Z,1647456442.546 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-03-16T18:47:22.546Z,1647456442.546 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.546Z,1647456442.546 [CommandLine](INFO): Join timeout helper Thread ID is 3967
2022-03-16T18:47:22.593Z,1647456442.593 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:22.594Z,1647456442.594 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.610Z,1647456442.610 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-03-16T18:47:22.610Z,1647456442.610 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.611Z,1647456442.611 [CommandExec](INFO): Join timeout helper Thread ID is 3968
2022-03-16T18:47:22.613Z,1647456442.613 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-03-16T18:47:22.613Z,1647456442.613 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.614Z,1647456442.614 [controlThread](INFO): Join timeout helper Thread ID is 3969
2022-03-16T18:47:22.651Z,1647456442.651 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-03-16T18:47:22.652Z,1647456442.652 [controlThread](DEBUG): Uninitializing ControlThread
2022-03-16T18:47:22.654Z,1647456442.654 [AHRS_M2](INFO): Powering down
2022-03-16T18:47:22.726Z,1647456442.726 [NAL9602](INFO): Powering down
2022-03-16T18:47:22.770Z,1647456442.770 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-03-16T18:47:22.771Z,1647456442.771 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-03-16T18:47:22.772Z,1647456442.772 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-03-16T18:47:22.772Z,1647456442.772 [MissionManager](INFO): Uninitializing Mission Default
2022-03-16T18:47:22.772Z,1647456442.772 [Default] Stopped
2022-03-16T18:47:22.773Z,1647456442.773 [Default](DEBUG): Aggregate::uninitialize Default
2022-03-16T18:47:22.773Z,1647456442.773 [Default:B.GoToSurface] Stopped
2022-03-16T18:47:22.773Z,1647456442.773 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-16T18:47:22.773Z,1647456442.773 [Default:CheckIn] Stopped
2022-03-16T18:47:22.773Z,1647456442.773 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-16T18:47:22.773Z,1647456442.773 [Default:CheckIn:C.Wait] Stopped
2022-03-16T18:47:22.773Z,1647456442.773 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-16T18:47:22.776Z,1647456442.776 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-03-16T18:47:22.776Z,1647456442.776 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-03-16T18:47:22.776Z,1647456442.776 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-03-16T18:47:22.777Z,1647456442.777 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-03-16T18:47:22.777Z,1647456442.777 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-03-16T18:47:22.777Z,1647456442.777 [BuoyancyServo](INFO): Powering down
2022-03-16T18:47:22.789Z,1647456442.789 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-03-16T18:47:22.789Z,1647456442.789 [ElevatorServo](INFO): Powering down
2022-03-16T18:47:22.790Z,1647456442.790 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-03-16T18:47:22.790Z,1647456442.790 [MassServo](INFO): Powering down
2022-03-16T18:47:22.791Z,1647456442.791 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-03-16T18:47:22.791Z,1647456442.791 [RudderServo](INFO): Powering down
2022-03-16T18:47:22.792Z,1647456442.792 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-03-16T18:47:22.792Z,1647456442.792 [ThrusterHE](INFO): Powering down
2022-03-16T18:47:22.793Z,1647456442.793 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-03-16T18:47:22.793Z,1647456442.793 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-03-16T18:47:22.794Z,1647456442.794 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-03-16T18:47:22.794Z,1647456442.794 [CBIT](DEBUG): Powering off loads.
2022-03-16T18:47:22.805Z,1647456442.805 [CBIT](DEBUG): Disabling WDT.
2022-03-16T18:47:22.817Z,1647456442.817 [CBIT](DEBUG): Opening all GF detection circuits.
2022-03-16T18:47:22.818Z,1647456442.818 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.860Z,1647456442.860 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.864Z,1647456442.864 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.917Z,1647456442.917 [DAT ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.921Z,1647456442.921 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.923Z,1647456442.923 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.928Z,1647456442.928 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:22.971Z,1647456442.971 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:23.016Z,1647456443.016 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-03-16T18:47:23.078Z,1647456443.078 [logger ThreadHandler](INFO): Thread cancelled.