2021-11-12T00:32:11.801Z,1636677131.801 [Supervisor](DEBUG): Initializing supervisor.
2021-11-12T00:32:11.806Z,1636677131.806 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-11-12T00:32:11.806Z,1636677131.806 [SyncHandler](INFO): Protected caller Thread ID is 6215
2021-11-12T00:32:11.807Z,1636677131.807 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-11-12T00:32:11.808Z,1636677131.808 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-11-12T00:32:11.808Z,1636677131.808 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6216
2021-11-12T00:32:11.813Z,1636677131.813 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-11-12T00:32:11.832Z,1636677131.832 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-11-12T00:32:11.833Z,1636677131.833 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-11-12T00:32:11.834Z,1636677131.834 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6217
2021-11-12T00:32:11.838Z,1636677131.838 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-11-12T00:32:11.839Z,1636677131.839 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-11-12T00:32:11.840Z,1636677131.840 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6218
2021-11-12T00:32:11.842Z,1636677131.842 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-11-12T00:32:11.843Z,1636677131.843 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-11-12T00:32:11.843Z,1636677131.843 [logger ThreadHandler](INFO): Protected caller Thread ID is 6219
2021-11-12T00:32:11.847Z,1636677131.847 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-11-12T00:32:11.848Z,1636677131.848 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-11-12T00:32:11.850Z,1636677131.850 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-11-12T00:32:12.087Z,1636677132.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-11-12T00:32:12.087Z,1636677132.087 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-11-12T00:32:12.168Z,1636677132.168 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-11-12T00:32:12.707Z,1636677132.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-11-12T00:32:12.708Z,1636677132.708 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-11-12T00:32:13.053Z,1636677133.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-11-12T00:32:13.054Z,1636677133.054 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-11-12T00:32:13.151Z,1636677133.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-11-12T00:32:13.152Z,1636677133.152 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-11-12T00:32:13.607Z,1636677133.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-11-12T00:32:13.607Z,1636677133.607 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-11-12T00:32:13.814Z,1636677133.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-11-12T00:32:13.814Z,1636677133.814 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-11-12T00:32:14.294Z,1636677134.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-11-12T00:32:14.295Z,1636677134.295 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-11-12T00:32:14.424Z,1636677134.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-11-12T00:32:14.425Z,1636677134.425 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-11-12T00:32:14.504Z,1636677134.504 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-11-12T00:32:15.203Z,1636677135.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-11-12T00:32:15.204Z,1636677135.204 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-11-12T00:32:15.577Z,1636677135.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-11-12T00:32:15.578Z,1636677135.578 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-11-12T00:32:15.776Z,1636677135.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-11-12T00:32:15.777Z,1636677135.777 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-11-12T00:32:15.889Z,1636677135.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-11-12T00:32:15.889Z,1636677135.889 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-11-12T00:32:16.043Z,1636677136.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-11-12T00:32:16.044Z,1636677136.044 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-11-12T00:32:16.278Z,1636677136.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-11-12T00:32:16.280Z,1636677136.280 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-11-12T00:32:16.281Z,1636677136.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-11-12T00:32:16.372Z,1636677136.372 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-11-12T00:32:16.457Z,1636677136.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-11-12T00:32:16.559Z,1636677136.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-11-12T00:32:16.641Z,1636677136.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-11-12T00:32:16.736Z,1636677136.736 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-11-12T00:32:16.893Z,1636677136.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-11-12T00:32:17.415Z,1636677137.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-11-12T00:32:17.587Z,1636677137.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-11-12T00:32:17.794Z,1636677137.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-11-12T00:32:17.956Z,1636677137.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-11-12T00:32:18.075Z,1636677138.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-11-12T00:32:18.342Z,1636677138.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-11-12T00:32:18.343Z,1636677138.343 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-11-12T00:32:18.343Z,1636677138.343 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-11-12T00:32:18.345Z,1636677138.345 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-11-12T00:32:18.421Z,1636677138.421 [VerticalControl](DEBUG): Construct VerticalControl.
2021-11-12T00:32:18.485Z,1636677138.485 [VerticalControl] Loaded
2021-11-12T00:32:18.486Z,1636677138.486 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-11-12T00:32:18.489Z,1636677138.489 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-11-12T00:32:18.528Z,1636677138.528 [HorizontalControl] Loaded
2021-11-12T00:32:18.529Z,1636677138.529 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-11-12T00:32:18.531Z,1636677138.531 [SpeedControl](DEBUG): Construct SpeedControl.
2021-11-12T00:32:18.534Z,1636677138.534 [SpeedControl] Loaded
2021-11-12T00:32:18.535Z,1636677138.535 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-11-12T00:32:18.537Z,1636677138.537 [LoopControl](DEBUG): Construct LoopControl.
2021-11-12T00:32:18.538Z,1636677138.538 [LoopControl] Loaded
2021-11-12T00:32:18.538Z,1636677138.538 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-11-12T00:32:18.538Z,1636677138.538 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-11-12T00:32:18.539Z,1636677138.539 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-11-12T00:32:18.556Z,1636677138.556 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-11-12T00:32:18.556Z,1636677138.556 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-11-12T00:32:18.694Z,1636677138.694 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-11-12T00:32:18.694Z,1636677138.694 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-11-12T00:32:18.880Z,1636677138.880 [BuoyancyServo] Loaded
2021-11-12T00:32:18.881Z,1636677138.881 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-11-12T00:32:18.901Z,1636677138.901 [ElevatorServo] Loaded
2021-11-12T00:32:18.901Z,1636677138.901 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-11-12T00:32:18.920Z,1636677138.920 [MassServo] Loaded
2021-11-12T00:32:18.921Z,1636677138.921 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-11-12T00:32:18.939Z,1636677138.939 [RudderServo] Loaded
2021-11-12T00:32:18.939Z,1636677138.939 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-11-12T00:32:18.958Z,1636677138.958 [ThrusterServo] Loaded
2021-11-12T00:32:18.958Z,1636677138.958 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-11-12T00:32:18.958Z,1636677138.958 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-11-12T00:32:18.959Z,1636677138.959 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-11-12T00:32:19.062Z,1636677139.062 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-11-12T00:32:19.062Z,1636677139.062 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-11-12T00:32:19.076Z,1636677139.076 [NavChart] Loaded
2021-11-12T00:32:19.076Z,1636677139.076 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-11-12T00:32:19.082Z,1636677139.082 [UniversalFixResidualReporter] Loaded
2021-11-12T00:32:19.083Z,1636677139.083 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-11-12T00:32:19.083Z,1636677139.083 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-11-12T00:32:19.084Z,1636677139.084 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-11-12T00:32:19.169Z,1636677139.169 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-11-12T00:32:19.169Z,1636677139.169 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-11-12T00:32:19.729Z,1636677139.729 [AHRS_M2] Loaded
2021-11-12T00:32:19.730Z,1636677139.730 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-11-12T00:32:20.558Z,1636677140.558 [BPC1] Loaded
2021-11-12T00:32:20.558Z,1636677140.558 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-11-12T00:32:20.631Z,1636677140.631 [DataOverHttps] Loaded
2021-11-12T00:32:20.631Z,1636677140.631 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-11-12T00:32:20.633Z,1636677140.633 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2021-11-12T00:32:20.633Z,1636677140.633 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6305
2021-11-12T00:32:20.654Z,1636677140.654 [Depth_Keller] Loaded
2021-11-12T00:32:20.654Z,1636677140.654 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-11-12T00:32:20.659Z,1636677140.659 [DropWeight] Loaded
2021-11-12T00:32:20.660Z,1636677140.660 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-11-12T00:32:20.722Z,1636677140.722 [NAL9602] Loaded
2021-11-12T00:32:20.722Z,1636677140.722 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-11-12T00:32:20.753Z,1636677140.753 [Onboard] Loaded
2021-11-12T00:32:20.753Z,1636677140.753 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-11-12T00:32:20.754Z,1636677140.754 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2021-11-12T00:32:20.754Z,1636677140.754 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6306
2021-11-12T00:32:20.768Z,1636677140.768 [Power24vConverter] Loaded
2021-11-12T00:32:20.768Z,1636677140.768 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-11-12T00:32:20.782Z,1636677140.782 [Radio_Surface] Loaded
2021-11-12T00:32:20.782Z,1636677140.782 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-11-12T00:32:20.783Z,1636677140.783 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2021-11-12T00:32:20.784Z,1636677140.784 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6307
2021-11-12T00:32:20.805Z,1636677140.805 [AMEcho] Loaded
2021-11-12T00:32:20.805Z,1636677140.805 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2021-11-12T00:32:20.890Z,1636677140.890 [DAT] Loaded
2021-11-12T00:32:20.890Z,1636677140.890 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-11-12T00:32:20.891Z,1636677140.891 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-11-12T00:32:20.891Z,1636677140.891 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-11-12T00:32:20.953Z,1636677140.953 [DepthRateCalculator] Loaded
2021-11-12T00:32:20.954Z,1636677140.954 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-11-12T00:32:20.958Z,1636677140.958 [PitchRateCalculator] Loaded
2021-11-12T00:32:20.959Z,1636677140.959 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-11-12T00:32:20.968Z,1636677140.968 [SpeedCalculator] Loaded
2021-11-12T00:32:20.969Z,1636677140.969 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-11-12T00:32:20.983Z,1636677140.983 [TempGradientCalculator] Loaded
2021-11-12T00:32:20.983Z,1636677140.983 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-11-12T00:32:20.988Z,1636677140.988 [YawRateCalculator] Loaded
2021-11-12T00:32:20.988Z,1636677140.988 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-11-12T00:32:21.007Z,1636677141.007 [ElevatorOffsetCalculator] Loaded
2021-11-12T00:32:21.007Z,1636677141.007 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-11-12T00:32:21.007Z,1636677141.007 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-11-12T00:32:21.008Z,1636677141.008 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-11-12T00:32:21.235Z,1636677141.235 [CTD_Seabird] Loaded
2021-11-12T00:32:21.235Z,1636677141.235 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-11-12T00:32:21.237Z,1636677141.237 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2021-11-12T00:32:21.237Z,1636677141.237 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6308
2021-11-12T00:32:21.257Z,1636677141.257 [PAR_Licor] Loaded
2021-11-12T00:32:21.258Z,1636677141.258 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-11-12T00:32:21.291Z,1636677141.291 [WetLabsBB2FL] Loaded
2021-11-12T00:32:21.291Z,1636677141.291 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-11-12T00:32:21.292Z,1636677141.292 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2021-11-12T00:32:21.293Z,1636677141.293 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6309
2021-11-12T00:32:21.294Z,1636677141.294 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-11-12T00:32:21.294Z,1636677141.294 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-11-12T00:32:21.337Z,1636677141.337 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-11-12T00:32:21.338Z,1636677141.338 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-11-12T00:32:21.734Z,1636677141.734 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-11-12T00:32:21.735Z,1636677141.735 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-11-12T00:32:22.088Z,1636677142.088 [SBIT](DEBUG): Construct Startup Built In Test.
2021-11-12T00:32:22.117Z,1636677142.117 [SBIT] Loaded
2021-11-12T00:32:22.117Z,1636677142.117 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-11-12T00:32:22.120Z,1636677142.120 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-11-12T00:32:22.157Z,1636677142.157 [IBIT] Loaded
2021-11-12T00:32:22.157Z,1636677142.157 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-11-12T00:32:22.163Z,1636677142.163 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-11-12T00:32:22.270Z,1636677142.270 [CBIT] Loaded
2021-11-12T00:32:22.270Z,1636677142.270 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-11-12T00:32:22.271Z,1636677142.271 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-11-12T00:32:22.272Z,1636677142.272 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-11-12T00:32:22.396Z,1636677142.396 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-11-12T00:32:22.402Z,1636677142.402 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-11-12T00:32:22.406Z,1636677142.406 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-11-12T00:32:22.417Z,1636677142.417 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-11-12T00:32:22.418Z,1636677142.418 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0
2021-11-12T00:32:22.419Z,1636677142.419 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6310
2021-11-12T00:32:22.424Z,1636677142.424 [Supervisor](INFO): Main Thread ID is 6214
2021-11-12T00:32:22.424Z,1636677142.424 [Supervisor](DEBUG): Running supervisor.
2021-11-12T00:32:22.425Z,1636677142.425 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6311
2021-11-12T00:32:22.425Z,1636677142.425 [CommandExec](INFO): Initializing the command executive.
2021-11-12T00:32:22.427Z,1636677142.427 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6312
2021-11-12T00:32:22.429Z,1636677142.429 [controlThread ThreadHandler](INFO): Handler Thread ID is 6313
2021-11-12T00:32:22.430Z,1636677142.430 [controlThread](DEBUG): Initializing ControlThread
2021-11-12T00:32:22.430Z,1636677142.430 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-11-12T00:32:22.432Z,1636677142.432 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-11-12T00:32:22.433Z,1636677142.433 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-11-12T00:32:22.434Z,1636677142.434 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-11-12T00:32:22.436Z,1636677142.436 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-11-12T00:32:22.436Z,1636677142.436 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-11-12T00:32:22.442Z,1636677142.442 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-11-12T00:32:22.442Z,1636677142.442 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-11-12T00:32:22.442Z,1636677142.442 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-11-12T00:32:22.443Z,1636677142.443 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-11-12T00:32:22.443Z,1636677142.443 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-11-12T00:32:22.444Z,1636677142.444 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-11-12T00:32:22.448Z,1636677142.448 [SBIT](INFO): Initialize SBIT Component.
2021-11-12T00:32:22.448Z,1636677142.448 [SBIT](IMPORTANT): git: 2021-10-11a
2021-11-12T00:32:22.449Z,1636677142.449 [SBIT](INFO): git hash: 36f3a97de7a122c995f034a375e3cefac6406048
2021-11-12T00:32:22.449Z,1636677142.449 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-11-12T00:32:22.450Z,1636677142.450 [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
2021-11-12T00:32:22.451Z,1636677142.451 [SBIT](INFO): Beginning SBIT in 41.000000 seconds.
2021-11-12T00:32:22.452Z,1636677142.452 [IBIT](INFO): Initialize IBIT Component.
2021-11-12T00:32:22.453Z,1636677142.453 [CBIT](DEBUG): Initialize CBIT Component.
2021-11-12T00:32:22.454Z,1636677142.454 [logger ThreadHandler](INFO): Handler Thread ID is 6314
2021-11-12T00:32:22.467Z,1636677142.467 [CBIT](DEBUG): Initialized mux pins.
2021-11-12T00:32:22.468Z,1636677142.468 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2021-11-12T00:32:22.468Z,1636677142.468 [CBIT](DEBUG): Initializing the watchdog timer.
2021-11-12T00:32:22.472Z,1636677142.472 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6315
2021-11-12T00:32:22.473Z,1636677142.473 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-11-12T00:32:22.480Z,1636677142.480 [Onboard ThreadHandler](INFO): Handler Thread ID is 6316
2021-11-12T00:32:22.492Z,1636677142.492 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-11-12T00:32:22.492Z,1636677142.492 [CBIT](DEBUG): Initializing heartbeat.
2021-11-12T00:32:22.506Z,1636677142.506 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6317
2021-11-12T00:32:22.512Z,1636677142.512 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6318
2021-11-12T00:32:22.513Z,1636677142.513 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-11-12T00:32:22.516Z,1636677142.516 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6320
2021-11-12T00:32:22.520Z,1636677142.520 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6322
2021-11-12T00:32:22.522Z,1636677142.522 [WetLabsBB2FL](INFO): Powering up
2021-11-12T00:32:22.530Z,1636677142.530 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-11-12T00:32:22.530Z,1636677142.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-11-12T00:32:22.531Z,1636677142.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-11-12T00:32:22.531Z,1636677142.531 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-11-12T00:32:22.531Z,1636677142.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-11-12T00:32:22.532Z,1636677142.532 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-11-12T00:32:22.532Z,1636677142.532 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-11-12T00:32:22.533Z,1636677142.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-11-12T00:32:22.533Z,1636677142.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-11-12T00:32:22.533Z,1636677142.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-11-12T00:32:22.533Z,1636677142.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-11-12T00:32:22.533Z,1636677142.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-11-12T00:32:22.534Z,1636677142.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-11-12T00:32:22.534Z,1636677142.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-11-12T00:32:22.534Z,1636677142.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-11-12T00:32:22.534Z,1636677142.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-11-12T00:32:22.563Z,1636677142.563 [CBIT](DEBUG): Deactivating GF circuits.
2021-11-12T00:32:22.564Z,1636677142.564 [CBIT](DEBUG): Deactivating emergency mode.
2021-11-12T00:32:22.599Z,1636677142.599 [CBIT](DEBUG): Backplane powered.
2021-11-12T00:32:22.600Z,1636677142.600 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-11-12T00:32:22.616Z,1636677142.616 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-12T00:32:22.652Z,1636677142.652 [MissionManager](DEBUG):
2021-11-12T00:32:22.660Z,1636677142.660 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-11-12T00:32:22.732Z,1636677142.732 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-11-12T00:32:22.733Z,1636677142.733 [Default:A.Wait](DEBUG): Construct Wait.
2021-11-12T00:32:22.748Z,1636677142.748 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-12T00:32:22.775Z,1636677142.775 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-11-12T00:32:22.778Z,1636677142.778 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-11-12T00:32:22.812Z,1636677142.812 [Default:E.Execute](DEBUG): Construct Execute.
2021-11-12T00:32:22.816Z,1636677142.816 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2021-11-12T00:32:22.821Z,1636677142.821 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,AMEcho,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-11-12T00:32:22.844Z,1636677142.844 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-11-12T00:32:22.916Z,1636677142.916 [Radio_Surface](INFO): Powering up
2021-11-12T00:32:22.925Z,1636677142.925 [Depth_Keller](ERROR): Pressure reading out of range: 915.616455 decibar
2021-11-12T00:32:22.926Z,1636677142.926 [Power24vConverter](INFO): Powering up.
2021-11-12T00:32:22.927Z,1636677142.927 [AMEcho](INFO): Powering up.
2021-11-12T00:32:22.944Z,1636677142.944 [DAT](INFO): Powering up
2021-11-12T00:32:22.944Z,1636677142.944 [DAT](DEBUG): Initializing DAT.
2021-11-12T00:32:22.980Z,1636677142.980 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-11-12T00:32:23.014Z,1636677143.014 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-11-12T00:32:23.020Z,1636677143.020 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-11-12T00:32:23.021Z,1636677143.021 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-11-12T00:32:23.028Z,1636677143.028 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-11-12T00:32:23.029Z,1636677143.029 [MassServo](DEBUG): Initializing EZServoServo.
2021-11-12T00:32:23.036Z,1636677143.036 [MassServo](DEBUG): Initializing MassServo.
2021-11-12T00:32:23.037Z,1636677143.037 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-12T00:32:23.044Z,1636677143.044 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-12T00:32:23.045Z,1636677143.045 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-11-12T00:32:23.052Z,1636677143.052 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-11-12T00:32:23.253Z,1636677143.253 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2021-11-12T00:32:23.253Z,1636677143.253 [DropWeight] Hardware Fault, FailCount= 1
2021-11-12T00:32:23.253Z,1636677143.253 [DropWeight](ERROR): Hardware Fault
2021-11-12T00:32:23.420Z,1636677143.420 [AMEcho](INFO): Powering down.
2021-11-12T00:32:23.458Z,1636677143.458 [CommandExec](FAULT): Scheduling is paused
2021-11-12T00:32:23.458Z,1636677143.458 [CBIT](INFO): Critical error at 20211112T003223
2021-11-12T00:32:23.458Z,1636677143.458 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-11-12T00:32:23.461Z,1636677143.461 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-11-12T00:32:23.461Z,1636677143.461 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-11-12T00:32:23.963Z,1636677143.963 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-11-12T00:32:23.964Z,1636677143.964 [RudderServo](FAULT): Rudder failed to initialize
2021-11-12T00:32:23.964Z,1636677143.964 [RudderServo] Communications Fault, FailCount= 1
2021-11-12T00:32:23.964Z,1636677143.964 [RudderServo](ERROR): Communications Fault
2021-11-12T00:32:24.062Z,1636677144.062 [CBIT](INFO): Critical error at 20211112T003223
2021-11-12T00:32:24.064Z,1636677144.064 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-11-12T00:32:24.077Z,1636677144.077 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2021-11-12T00:32:24.273Z,1636677144.273 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-12T00:32:24.273Z,1636677144.273 [RudderServo](INFO): Powering down
2021-11-12T00:32:24.628Z,1636677144.628 [WetLabsBB2FL](INFO): Powering down
2021-11-12T00:32:24.900Z,1636677144.900 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-12T00:32:25.020Z,1636677145.020 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-12T00:32:25.025Z,1636677145.025 [CBIT](INFO): Clearing failed state for component RudderServo
2021-11-12T00:32:25.025Z,1636677145.025 [RudderServo] No Fault, FailCount= 1
2021-11-12T00:32:29.556Z,1636677149.556 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-11-12T00:32:50.741Z,1636677170.741 [NAL9602](INFO): Powering up NAL9602
2021-11-12T00:32:51.729Z,1636677171.729 [CommandExec](IMPORTANT): got command get CTD_Seabird.loadAtStartup
2021-11-12T00:32:51.729Z,1636677171.729 [CommandExec](IMPORTANT): CTD_Seabird.loadAtStartup 1 bool
2021-11-12T00:32:56.242Z,1636677176.242 [CommandExec](IMPORTANT): got command get depth
2021-11-12T00:32:56.243Z,1636677176.243 [CommandExec](IMPORTANT): depth 0.150825 m
2021-11-12T00:32:58.846Z,1636677178.846 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:32:58.846Z,1636677178.846 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth
2021-11-12T00:33:01.653Z,1636677181.653 [NAL9602](INFO): NAL9602 initialized
2021-11-12T00:33:04.113Z,1636677184.113 [SBIT](IMPORTANT): Beginning Startup BIT
2021-11-12T00:33:04.121Z,1636677184.121 [CBIT](IMPORTANT): Beginning ground fault scan
2021-11-12T00:33:05.413Z,1636677185.413 [CommandExec](IMPORTANT): got command show variable ctd_seabird
2021-11-12T00:33:05.514Z,1636677185.514 [CommandExec](IMPORTANT): CTD_Seabird.loadControl (none)
2021-11-12T00:33:05.514Z,1636677185.514 [CommandExec](IMPORTANT): CTD_Seabird.uart (none)
2021-11-12T00:33:05.515Z,1636677185.515 [CommandExec](IMPORTANT): CTD_Seabird.baud (bit_per_second)
2021-11-12T00:33:05.554Z,1636677185.554 [CommandExec](IMPORTANT): CTD_Seabird.loadAtStartup (bool)
2021-11-12T00:33:05.554Z,1636677185.554 [CommandExec](IMPORTANT): CTD_Seabird.simulateHardware (bool)
2021-11-12T00:33:05.555Z,1636677185.555 [CommandExec](IMPORTANT): CTD_Seabird.maxPressBound (decibar)
2021-11-12T00:33:05.591Z,1636677185.591 [CommandExec](IMPORTANT): CTD_Seabird.minPressBound (decibar)
2021-11-12T00:33:05.592Z,1636677185.592 [CommandExec](IMPORTANT): CTD_Seabird.maxSalinityBound (practical_salinity_unit)
2021-11-12T00:33:05.592Z,1636677185.592 [CommandExec](IMPORTANT): CTD_Seabird.minSalinityBound (practical_salinity_unit)
2021-11-12T00:33:05.593Z,1636677185.593 [CommandExec](IMPORTANT): CTD_Seabird.offset (decibar)
2021-11-12T00:33:05.593Z,1636677185.593 [CommandExec](IMPORTANT): CTD_Seabird.verbosity (count)
2021-11-12T00:33:05.594Z,1636677185.594 [CommandExec](IMPORTANT): CTD_Seabird.SBE43FSerialNumber (none)
2021-11-12T00:33:05.594Z,1636677185.594 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffFOffset (none)
2021-11-12T00:33:05.594Z,1636677185.594 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffSoc (none)
2021-11-12T00:33:05.595Z,1636677185.595 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffA (none)
2021-11-12T00:33:05.595Z,1636677185.595 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffB (none)
2021-11-12T00:33:05.607Z,1636677185.607 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffC (none)
2021-11-12T00:33:05.615Z,1636677185.615 [CommandExec](IMPORTANT): CTD_Seabird.oxygenCalCoeffE (none)
2021-11-12T00:33:05.901Z,1636677185.901 [CommandExec](IMPORTANT): CTD_Seabird.enableBroadcast (bool)
2021-11-12T00:33:05.902Z,1636677185.902 [CommandExec](IMPORTANT): CTD_Seabird.component_voltage (volt)
2021-11-12T00:33:05.902Z,1636677185.902 [CommandExec](IMPORTANT): CTD_Seabird.component_avgVoltage (volt)
2021-11-12T00:33:05.903Z,1636677185.903 [CommandExec](IMPORTANT): CTD_Seabird.component_current (milliampere)
2021-11-12T00:33:05.947Z,1636677185.947 [CommandExec](IMPORTANT): CTD_Seabird.component_avgCurrent (milliampere)
2021-11-12T00:33:05.948Z,1636677185.948 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_electrical_conductivity (siemens_per_meter)
2021-11-12T00:33:05.948Z,1636677185.948 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_temperature (celsius)
2021-11-12T00:33:05.949Z,1636677185.949 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_pressure (decibar)
2021-11-12T00:33:05.949Z,1636677185.949 [CommandExec](IMPORTANT): CTD_Seabird.depth (meter)
2021-11-12T00:33:05.950Z,1636677185.950 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_salinity (practical_salinity_unit)
2021-11-12T00:33:05.950Z,1636677185.950 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_density (kilogram_per_cubic_meter)
2021-11-12T00:33:05.950Z,1636677185.950 [CommandExec](IMPORTANT): CTD_Seabird.speed_of_sound_in_sea_water (meter_per_second)
2021-11-12T00:33:05.951Z,1636677185.951 [CommandExec](IMPORTANT): CTD_Seabird.mass_concentration_of_oxygen_in_sea_water (microgram_per_liter)
2021-11-12T00:33:05.963Z,1636677185.963 [CommandExec](IMPORTANT): CTD_Seabird.sea_water_dissolved_oxygen_freq (hertz)
2021-11-12T00:33:05.964Z,1636677185.964 [CommandExec](IMPORTANT): CTD_Seabird.bin_median_sea_water_temperature (celsius)
2021-11-12T00:33:05.964Z,1636677185.964 [CommandExec](IMPORTANT): CTD_Seabird.bin_mean_sea_water_temperature (celsius)
2021-11-12T00:33:05.965Z,1636677185.965 [CommandExec](IMPORTANT): CTD_Seabird.bin_standard_deviation_sea_water_temperature (celsius)
2021-11-12T00:33:05.965Z,1636677185.965 [CommandExec](IMPORTANT): CTD_Seabird.bin_median_sea_water_salinity (practical_salinity_unit)
2021-11-12T00:33:05.966Z,1636677185.966 [CommandExec](IMPORTANT): CTD_Seabird.bin_mean_sea_water_salinity (practical_salinity_unit)
2021-11-12T00:33:05.966Z,1636677185.966 [CommandExec](IMPORTANT): CTD_Seabird.bin_standard_deviation_sea_water_salinity (practical_salinity_unit)
2021-11-12T00:33:13.830Z,1636677193.830 [CommandExec](IMPORTANT): got command get CTD_Seabird.depth
2021-11-12T00:33:13.831Z,1636677193.831 [CommandExec](IMPORTANT): CTD_Seabird.depth nan m
2021-11-12T00:33:15.033Z,1636677195.033 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2021-11-12T00:33:15.452Z,1636677195.452 [CBIT](IMPORTANT): Beginning ground fault scan
2021-11-12T00:33:23.070Z,1636677203.070 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-12T00:33:23.070Z,1636677203.070 [DAT] Communications Fault, FailCount= 1
2021-11-12T00:33:23.070Z,1636677203.070 [DAT](ERROR): Communications Fault
2021-11-12T00:33:23.193Z,1636677203.193 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-12T00:33:23.494Z,1636677203.494 [DAT](INFO): Powering down
2021-11-12T00:33:24.396Z,1636677204.396 [CBIT](INFO): Clearing failed state for component DAT
2021-11-12T00:33:24.397Z,1636677204.397 [DAT] No Fault, FailCount= 1
2021-11-12T00:33:26.357Z,1636677206.357 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2021-11-12T00:33:26.716Z,1636677206.716 [DAT](INFO): Powering up
2021-11-12T00:33:26.717Z,1636677206.717 [DAT](DEBUG): Initializing DAT.
2021-11-12T00:33:26.797Z,1636677206.797 [CBIT](IMPORTANT): Beginning ground fault scan
2021-11-12T00:33:27.785Z,1636677207.785 [CommandExec](IMPORTANT): got command report mod depth
2021-11-12T00:33:27.982Z,1636677207.982 [Reporter](INFO): depth 0.156024 m
2021-11-12T00:33:28.469Z,1636677208.469 [Reporter](INFO): depth 0.150391 m
2021-11-12T00:33:29.186Z,1636677209.186 [Reporter](INFO): depth 0.150825 m
2021-11-12T00:33:29.689Z,1636677209.689 [Reporter](INFO): depth 0.143459 m
2021-11-12T00:33:30.019Z,1636677210.019 [Reporter](INFO): depth 0.147358 m
2021-11-12T00:33:30.451Z,1636677210.451 [Reporter](INFO): depth 0.169022 m
2021-11-12T00:33:30.837Z,1636677210.837 [Reporter](INFO): depth 0.123095 m
2021-11-12T00:33:31.231Z,1636677211.231 [Reporter](INFO): depth 0.126561 m
2021-11-12T00:33:31.634Z,1636677211.634 [Reporter](INFO): depth 0.138259 m
2021-11-12T00:33:32.042Z,1636677212.042 [Reporter](INFO): depth 0.149525 m
2021-11-12T00:33:32.353Z,1636677212.353 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:32.353Z,1636677212.353 [Depth_Keller] Hardware Fault, FailCount= 1
2021-11-12T00:33:32.353Z,1636677212.353 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:33:32.354Z,1636677212.354 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:32.432Z,1636677212.432 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:33:32.432Z,1636677212.432 [CBIT](INFO): Clearing failed state for component Depth_Keller
2021-11-12T00:33:32.432Z,1636677212.432 [Depth_Keller] No Fault, FailCount= 1
2021-11-12T00:33:33.227Z,1636677213.227 [Reporter](INFO): depth 0.158190 m
2021-11-12T00:33:33.697Z,1636677213.697 [Reporter](INFO): depth 0.140859 m
2021-11-12T00:33:34.041Z,1636677214.041 [Reporter](INFO): depth 0.154291 m
2021-11-12T00:33:34.427Z,1636677214.427 [Reporter](INFO): depth 0.151691 m
2021-11-12T00:33:34.945Z,1636677214.945 [Reporter](INFO): depth 0.156024 m
2021-11-12T00:33:35.241Z,1636677215.241 [Reporter](INFO): depth 0.126561 m
2021-11-12T00:33:35.726Z,1636677215.726 [Reporter](INFO): depth 0.123961 m
2021-11-12T00:33:36.113Z,1636677216.113 [Reporter](INFO): depth 0.148225 m
2021-11-12T00:33:36.437Z,1636677216.437 [Reporter](INFO): depth 0.127427 m
2021-11-12T00:33:36.635Z,1636677216.635 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:33:36.635Z,1636677216.635 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth
2021-11-12T00:33:36.851Z,1636677216.851 [Reporter](INFO): depth 0.148658 m
2021-11-12T00:33:37.313Z,1636677217.313 [Reporter](INFO): depth 0.151258 m
2021-11-12T00:33:37.634Z,1636677217.634 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2021-11-12T00:33:37.635Z,1636677217.635 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2021-11-12T00:33:37.636Z,1636677217.636 [Reporter](INFO): depth 0.155157 m
2021-11-12T00:33:38.048Z,1636677218.048 [Reporter](INFO): depth 0.129594 m
2021-11-12T00:33:38.460Z,1636677218.460 [Reporter](INFO): depth 0.133060 m
2021-11-12T00:33:38.842Z,1636677218.842 [Reporter](INFO): depth 0.146925 m
2021-11-12T00:33:39.249Z,1636677219.249 [Reporter](INFO): depth 0.151691 m
2021-11-12T00:33:39.658Z,1636677219.658 [Reporter](INFO): depth 0.159057 m
2021-11-12T00:33:40.065Z,1636677220.065 [Reporter](INFO): depth 0.148658 m
2021-11-12T00:33:40.459Z,1636677220.459 [Reporter](INFO): depth 0.156457 m
2021-11-12T00:33:40.866Z,1636677220.866 [Reporter](INFO): depth 0.147792 m
2021-11-12T00:33:41.279Z,1636677221.279 [Reporter](INFO): depth 0.143892 m
2021-11-12T00:33:41.697Z,1636677221.697 [Reporter](INFO): depth 0.146492 m
2021-11-12T00:33:42.106Z,1636677222.106 [Reporter](INFO): depth 0.148658 m
2021-11-12T00:33:42.522Z,1636677222.522 [Reporter](INFO): depth 0.158190 m
2021-11-12T00:33:42.942Z,1636677222.942 [Reporter](INFO): depth 0.160790 m
2021-11-12T00:33:43.369Z,1636677223.369 [Reporter](INFO): depth 0.148225 m
2021-11-12T00:33:43.643Z,1636677223.643 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:43.644Z,1636677223.644 [Depth_Keller] Hardware Fault, FailCount= 1
2021-11-12T00:33:43.644Z,1636677223.644 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:33:43.644Z,1636677223.644 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:43.726Z,1636677223.726 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:33:43.726Z,1636677223.726 [CBIT](INFO): Clearing failed state for component Depth_Keller
2021-11-12T00:33:43.726Z,1636677223.726 [Depth_Keller] No Fault, FailCount= 1
2021-11-12T00:33:44.320Z,1636677224.320 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:44.320Z,1636677224.320 [Depth_Keller] Hardware Fault, FailCount= 2
2021-11-12T00:33:44.320Z,1636677224.320 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:33:44.320Z,1636677224.320 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:44.510Z,1636677224.510 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:33:44.510Z,1636677224.510 [CBIT](INFO): Clearing failed state for component Depth_Keller
2021-11-12T00:33:44.510Z,1636677224.510 [Depth_Keller] No Fault, FailCount= 2
2021-11-12T00:33:44.684Z,1636677224.684 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:44.684Z,1636677224.684 [Depth_Keller] Hardware Fault, FailCount= 3
2021-11-12T00:33:44.684Z,1636677224.684 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:33:44.684Z,1636677224.684 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:44.898Z,1636677224.898 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:33:44.898Z,1636677224.898 [CBIT](CRITICAL): Hardware Fault in component: Depth_Keller
2021-11-12T00:33:44.948Z,1636677224.948 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:44.948Z,1636677224.948 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:45.207Z,1636677225.207 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:45.208Z,1636677225.208 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:45.311Z,1636677225.311 [CBIT](INFO): Critical error at 20211112T003344
2021-11-12T00:33:45.416Z,1636677225.416 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:45.416Z,1636677225.416 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:45.624Z,1636677225.624 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:33:45.624Z,1636677225.624 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:33:47.861Z,1636677227.861 [CTD_Seabird](ERROR): Failed to parse device response:
2021-11-12T00:33:48.866Z,1636677228.866 [CTD_Seabird](ERROR): Salinity reading out of range: 0.088638 psu
2021-11-12T00:33:49.087Z,1636677229.087 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:33:49.204Z,1636677229.204 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:33:49.204Z,1636677229.204 [CommandExec](IMPORTANT): depth best is CTD_Seabird.depth
2021-11-12T00:33:49.869Z,1636677229.869 [CTD_Seabird](ERROR): Salinity reading out of range: 0.085570 psu
2021-11-12T00:33:50.873Z,1636677230.873 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087737 psu
2021-11-12T00:33:51.878Z,1636677231.878 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086574 psu
2021-11-12T00:33:52.222Z,1636677232.222 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:33:52.882Z,1636677232.882 [CTD_Seabird](ERROR): Salinity reading out of range: 0.088742 psu
2021-11-12T00:33:53.035Z,1636677233.035 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:33:53.886Z,1636677233.886 [CTD_Seabird](ERROR): Salinity reading out of range: 0.090224 psu
2021-11-12T00:33:54.889Z,1636677234.889 [CTD_Seabird](ERROR): Salinity reading out of range: 0.089747 psu
2021-11-12T00:33:55.893Z,1636677235.893 [CTD_Seabird](ERROR): Salinity reading out of range: 0.088582 psu
2021-11-12T00:33:56.798Z,1636677236.798 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:33:56.798Z,1636677236.798 [CommandExec](IMPORTANT): depth best is CTD_Seabird.depth
2021-11-12T00:33:56.897Z,1636677236.897 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087420 psu
2021-11-12T00:33:57.901Z,1636677237.901 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087102 psu
2021-11-12T00:33:58.239Z,1636677238.239 [SBIT](IMPORTANT): SBIT PASSED
2021-11-12T00:33:58.268Z,1636677238.268 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-11-12T00:33:58.273Z,1636677238.273 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:33:58.626Z,1636677238.626 [MissionManager](IMPORTANT): Started mission Startup
2021-11-12T00:33:58.626Z,1636677238.626 [Startup] Running Loop=1
2021-11-12T00:33:58.626Z,1636677238.626 [Startup](DEBUG): Aggregate::initialize Startup
2021-11-12T00:33:58.626Z,1636677238.626 [Startup:A.GoToSurface] Running Loop=1
2021-11-12T00:33:58.626Z,1636677238.626 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-11-12T00:33:58.627Z,1636677238.627 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-11-12T00:33:58.628Z,1636677238.628 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-11-12T00:33:58.628Z,1636677238.628 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-11-12T00:33:58.629Z,1636677238.629 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-11-12T00:33:58.629Z,1636677238.629 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-11-12T00:33:58.630Z,1636677238.630 [Startup:StartupSatComms] Running Loop=1
2021-11-12T00:33:58.630Z,1636677238.630 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-11-12T00:33:58.631Z,1636677238.631 [Startup:StartupSatComms:A] Running Loop=1
2021-11-12T00:33:58.906Z,1636677238.906 [CTD_Seabird](ERROR): Salinity reading out of range: 0.084512 psu
2021-11-12T00:33:59.038Z,1636677239.038 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-11-12T00:33:59.049Z,1636677239.049 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:33:59.419Z,1636677239.419 [AMEcho](INFO): Powering up.
2021-11-12T00:33:59.910Z,1636677239.910 [CTD_Seabird](ERROR): Salinity reading out of range: 0.089217 psu
2021-11-12T00:34:00.263Z,1636677240.263 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:34:00.913Z,1636677240.913 [CTD_Seabird](ERROR): Salinity reading out of range: 0.090117 psu
2021-11-12T00:34:01.057Z,1636677241.057 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:34:01.917Z,1636677241.917 [CTD_Seabird](ERROR): Salinity reading out of range: 0.085940 psu
2021-11-12T00:34:02.293Z,1636677242.293 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:34:02.921Z,1636677242.921 [CTD_Seabird](ERROR): Salinity reading out of range: 0.085411 psu
2021-11-12T00:34:03.087Z,1636677243.087 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:34:03.925Z,1636677243.925 [CTD_Seabird](ERROR): Salinity reading out of range: 0.088213 psu
2021-11-12T00:34:04.930Z,1636677244.930 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086416 psu
2021-11-12T00:34:05.933Z,1636677245.933 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086363 psu
2021-11-12T00:34:06.937Z,1636677246.937 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087261 psu
2021-11-12T00:34:07.138Z,1636677247.138 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:34:07.942Z,1636677247.942 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087579 psu
2021-11-12T00:34:07.949Z,1636677247.949 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:34:08.945Z,1636677248.945 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086732 psu
2021-11-12T00:34:09.164Z,1636677249.164 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:34:09.950Z,1636677249.950 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087314 psu
2021-11-12T00:34:09.981Z,1636677249.981 [CommandExec](IMPORTANT): got command failComponent none Depth_Keller
2021-11-12T00:34:09.981Z,1636677249.981 [Depth_Keller] No Fault, FailCount= 3
2021-11-12T00:34:09.981Z,1636677249.981 [CommandExec](IMPORTANT): Depth_Keller failureMode is No Fault
2021-11-12T00:34:10.057Z,1636677250.057 [Reporter](INFO): depth 0.148225 m
2021-11-12T00:34:10.381Z,1636677250.381 [Reporter](INFO): depth 0.159057 m
2021-11-12T00:34:10.792Z,1636677250.792 [Reporter](INFO): depth 0.146925 m
2021-11-12T00:34:11.169Z,1636677251.169 [Reporter](INFO): depth 0.151258 m
2021-11-12T00:34:11.579Z,1636677251.579 [Reporter](INFO): depth 0.150825 m
2021-11-12T00:34:11.987Z,1636677251.987 [Reporter](INFO): depth 0.129161 m
2021-11-12T00:34:12.370Z,1636677252.370 [Reporter](INFO): depth 0.160357 m
2021-11-12T00:34:12.797Z,1636677252.797 [Reporter](INFO): depth 0.125694 m
2021-11-12T00:34:13.206Z,1636677253.206 [Reporter](INFO): depth 0.152991 m
2021-11-12T00:34:13.626Z,1636677253.626 [Reporter](INFO): depth 0.135226 m
2021-11-12T00:34:14.081Z,1636677254.081 [Reporter](INFO): depth 0.160790 m
2021-11-12T00:34:14.135Z,1636677254.135 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:34:14.135Z,1636677254.135 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth
2021-11-12T00:34:14.406Z,1636677254.406 [Reporter](INFO): depth 0.153857 m
2021-11-12T00:34:14.814Z,1636677254.814 [Reporter](INFO): depth 0.144325 m
2021-11-12T00:34:15.201Z,1636677255.201 [Reporter](INFO): depth 0.130894 m
2021-11-12T00:34:15.602Z,1636677255.602 [Reporter](INFO): depth 0.153424 m
2021-11-12T00:34:16.017Z,1636677256.017 [Reporter](INFO): depth 0.155157 m
2021-11-12T00:34:16.432Z,1636677256.432 [Reporter](INFO): depth 0.142592 m
2021-11-12T00:34:16.818Z,1636677256.818 [Reporter](INFO): depth 0.141726 m
2021-11-12T00:34:17.280Z,1636677257.280 [Reporter](INFO): depth 0.145625 m
2021-11-12T00:34:17.704Z,1636677257.704 [Reporter](INFO): depth 0.128294 m
2021-11-12T00:34:18.073Z,1636677258.073 [Reporter](INFO): depth 0.141726 m
2021-11-12T00:34:18.500Z,1636677258.500 [Reporter](INFO): depth 0.152991 m
2021-11-12T00:34:18.864Z,1636677258.864 [Reporter](INFO): depth 0.147792 m
2021-11-12T00:34:19.242Z,1636677259.242 [Reporter](INFO): depth 0.150825 m
2021-11-12T00:34:19.648Z,1636677259.648 [Reporter](INFO): depth 0.160790 m
2021-11-12T00:34:20.087Z,1636677260.087 [Reporter](INFO): depth 0.144325 m
2021-11-12T00:34:20.482Z,1636677260.482 [Reporter](INFO): depth 0.143892 m
2021-11-12T00:34:20.861Z,1636677260.861 [Reporter](INFO): depth 0.145625 m
2021-11-12T00:34:21.313Z,1636677261.313 [Reporter](INFO): depth 0.155591 m
2021-11-12T00:34:21.700Z,1636677261.700 [Reporter](INFO): depth 0.154291 m
2021-11-12T00:34:22.111Z,1636677262.111 [Reporter](INFO): depth 0.156024 m
2021-11-12T00:34:22.230Z,1636677262.230 [CommandExec](IMPORTANT): got command get CTD_Seabird.depth
2021-11-12T00:34:22.231Z,1636677262.231 [CommandExec](IMPORTANT): CTD_Seabird.depth -0.079410 m
2021-11-12T00:34:22.505Z,1636677262.505 [Reporter](INFO): depth 0.166423 m
2021-11-12T00:34:23.032Z,1636677263.032 [Reporter](INFO): depth 0.132627 m
2021-11-12T00:34:23.360Z,1636677263.360 [Reporter](INFO): depth 0.156457 m
2021-11-12T00:34:23.727Z,1636677263.727 [Reporter](INFO): depth 0.146925 m
2021-11-12T00:34:24.129Z,1636677264.129 [Reporter](INFO): depth 0.157324 m
2021-11-12T00:34:24.512Z,1636677264.512 [Reporter](INFO): depth 0.130027 m
2021-11-12T00:34:24.912Z,1636677264.912 [Reporter](INFO): depth 0.157757 m
2021-11-12T00:34:25.303Z,1636677265.303 [Reporter](INFO): depth 0.159057 m
2021-11-12T00:34:25.721Z,1636677265.721 [Reporter](INFO): depth 0.156890 m
2021-11-12T00:34:26.132Z,1636677266.132 [Reporter](INFO): depth 0.146058 m
2021-11-12T00:34:26.517Z,1636677266.517 [Reporter](INFO): depth 0.136093 m
2021-11-12T00:34:26.898Z,1636677266.898 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-11-12T00:34:26.898Z,1636677266.898 [DAT] Communications Fault, FailCount= 2
2021-11-12T00:34:26.899Z,1636677266.899 [DAT](ERROR): Communications Fault
2021-11-12T00:34:26.921Z,1636677266.921 [CBIT](ERROR): Communications Fault in component: DAT
2021-11-12T00:34:26.922Z,1636677266.922 [Reporter](INFO): depth 0.132627 m
2021-11-12T00:34:27.302Z,1636677267.302 [DAT](INFO): Powering down
2021-11-12T00:34:27.386Z,1636677267.386 [Reporter](INFO): depth 0.133927 m
2021-11-12T00:34:27.754Z,1636677267.754 [Reporter](INFO): depth 0.158190 m
2021-11-12T00:34:28.148Z,1636677268.148 [CBIT](INFO): Clearing failed state for component DAT
2021-11-12T00:34:28.148Z,1636677268.148 [DAT] No Fault, FailCount= 2
2021-11-12T00:34:28.150Z,1636677268.150 [Reporter](INFO): depth 0.140426 m
2021-11-12T00:34:28.589Z,1636677268.589 [Reporter](INFO): depth 0.130460 m
2021-11-12T00:34:28.971Z,1636677268.971 [Reporter](INFO): depth 0.143026 m
2021-11-12T00:34:29.385Z,1636677269.385 [Reporter](INFO): depth 0.152124 m
2021-11-12T00:34:29.759Z,1636677269.759 [Reporter](INFO): depth 0.146058 m
2021-11-12T00:34:30.169Z,1636677270.169 [Reporter](INFO): depth 0.143892 m
2021-11-12T00:34:30.280Z,1636677270.280 [CommandExec](IMPORTANT): got command get CTD_Seabird.component_voltage
2021-11-12T00:34:30.281Z,1636677270.281 [CommandExec](IMPORTANT): CTD_Seabird.component_voltage 0.000000 V
2021-11-12T00:34:30.534Z,1636677270.534 [DAT](INFO): Powering up
2021-11-12T00:34:30.534Z,1636677270.534 [DAT](DEBUG): Initializing DAT.
2021-11-12T00:34:30.589Z,1636677270.589 [Reporter](INFO): depth 0.151691 m
2021-11-12T00:34:30.988Z,1636677270.988 [Reporter](INFO): depth 0.159923 m
2021-11-12T00:34:31.365Z,1636677271.365 [Reporter](INFO): depth 0.143892 m
2021-11-12T00:34:31.778Z,1636677271.778 [Reporter](INFO): depth 0.152124 m
2021-11-12T00:34:32.189Z,1636677272.189 [Reporter](INFO): depth 0.156457 m
2021-11-12T00:34:32.593Z,1636677272.593 [Reporter](INFO): depth 0.145625 m
2021-11-12T00:34:32.991Z,1636677272.991 [Reporter](INFO): depth 0.165989 m
2021-11-12T00:34:33.410Z,1636677273.410 [Reporter](INFO): depth 0.142592 m
2021-11-12T00:34:33.821Z,1636677273.821 [Reporter](INFO): depth 0.165123 m
2021-11-12T00:34:34.233Z,1636677274.233 [Reporter](INFO): depth 0.141292 m
2021-11-12T00:34:34.621Z,1636677274.621 [Reporter](INFO): depth 0.147358 m
2021-11-12T00:34:34.885Z,1636677274.885 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:34:34.886Z,1636677274.886 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth
2021-11-12T00:34:35.029Z,1636677275.029 [Reporter](INFO): depth 0.164256 m
2021-11-12T00:34:35.405Z,1636677275.405 [Reporter](INFO): depth 0.151258 m
2021-11-12T00:34:35.808Z,1636677275.808 [Reporter](INFO): depth 0.152124 m
2021-11-12T00:34:36.276Z,1636677276.276 [Reporter](INFO): depth 0.143892 m
2021-11-12T00:34:36.691Z,1636677276.691 [Reporter](INFO): depth 0.154291 m
2021-11-12T00:34:37.067Z,1636677277.067 [Reporter](INFO): depth 0.142592 m
2021-11-12T00:34:37.470Z,1636677277.470 [Reporter](INFO): depth 0.162523 m
2021-11-12T00:34:37.837Z,1636677277.837 [Reporter](INFO): depth 0.143026 m
2021-11-12T00:34:38.244Z,1636677278.244 [Reporter](INFO): depth 0.142159 m
2021-11-12T00:34:38.666Z,1636677278.666 [Reporter](INFO): depth 0.131327 m
2021-11-12T00:34:39.078Z,1636677279.078 [Reporter](INFO): depth 0.146925 m
2021-11-12T00:34:39.463Z,1636677279.463 [Reporter](INFO): depth 0.130027 m
2021-11-12T00:34:39.864Z,1636677279.864 [Reporter](INFO): depth 0.150391 m
2021-11-12T00:34:40.281Z,1636677280.281 [Reporter](INFO): depth 0.136526 m
2021-11-12T00:34:40.657Z,1636677280.657 [Reporter](INFO): depth 0.160790 m
2021-11-12T00:34:41.067Z,1636677281.067 [Reporter](INFO): depth 0.148658 m
2021-11-12T00:34:41.474Z,1636677281.474 [Reporter](INFO): depth 0.139993 m
2021-11-12T00:34:41.740Z,1636677281.740 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:41.741Z,1636677281.741 [Depth_Keller] Hardware Fault, FailCount= 1
2021-11-12T00:34:41.741Z,1636677281.741 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:34:41.741Z,1636677281.741 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:41.887Z,1636677281.887 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:34:41.888Z,1636677281.888 [CBIT](INFO): Clearing failed state for component Depth_Keller
2021-11-12T00:34:41.888Z,1636677281.888 [Depth_Keller] No Fault, FailCount= 1
2021-11-12T00:34:42.571Z,1636677282.571 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:42.572Z,1636677282.572 [Depth_Keller] Hardware Fault, FailCount= 2
2021-11-12T00:34:42.572Z,1636677282.572 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:34:42.572Z,1636677282.572 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:42.700Z,1636677282.700 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:34:42.700Z,1636677282.700 [CBIT](INFO): Clearing failed state for component Depth_Keller
2021-11-12T00:34:42.700Z,1636677282.700 [Depth_Keller] No Fault, FailCount= 2
2021-11-12T00:34:42.893Z,1636677282.893 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:42.893Z,1636677282.893 [Depth_Keller] Hardware Fault, FailCount= 3
2021-11-12T00:34:42.893Z,1636677282.893 [Depth_Keller](ERROR): Hardware Fault
2021-11-12T00:34:42.894Z,1636677282.894 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:43.208Z,1636677283.208 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:43.208Z,1636677283.208 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:43.216Z,1636677283.216 [CBIT](ERROR): Hardware Fault in component: Depth_Keller
2021-11-12T00:34:43.216Z,1636677283.216 [CBIT](CRITICAL): Hardware Fault in component: Depth_Keller
2021-11-12T00:34:43.480Z,1636677283.480 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:43.480Z,1636677283.480 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:43.614Z,1636677283.614 [CBIT](INFO): Critical error at 20211112T003443
2021-11-12T00:34:43.736Z,1636677283.736 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:43.736Z,1636677283.736 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:44.006Z,1636677284.006 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:44.006Z,1636677284.006 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:44.316Z,1636677284.316 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:44.317Z,1636677284.317 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:44.529Z,1636677284.529 [CommandExec](IMPORTANT): got command failComponent hardware Depth_Keller
2021-11-12T00:34:44.529Z,1636677284.529 [CommandExec](IMPORTANT): Depth_Keller failureMode is Hardware Fault
2021-11-12T00:34:46.093Z,1636677286.093 [CTD_Seabird](ERROR): Failed to parse device response:
2021-11-12T00:34:48.102Z,1636677288.102 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086686 psu
2021-11-12T00:34:48.410Z,1636677288.410 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:34:49.105Z,1636677289.105 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086739 psu
2021-11-12T00:34:50.110Z,1636677290.110 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086843 psu
2021-11-12T00:34:51.114Z,1636677291.114 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086686 psu
2021-11-12T00:34:52.117Z,1636677292.117 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086580 psu
2021-11-12T00:34:53.121Z,1636677293.121 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086738 psu
2021-11-12T00:34:54.125Z,1636677294.125 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086739 psu
2021-11-12T00:34:55.129Z,1636677295.129 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086632 psu
2021-11-12T00:34:56.133Z,1636677296.133 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086687 psu
2021-11-12T00:34:56.899Z,1636677296.899 [CommandExec](IMPORTANT): got command show best depth
2021-11-12T00:34:56.899Z,1636677296.899 [CommandExec](IMPORTANT): depth best is CTD_Seabird.depth
2021-11-12T00:34:57.137Z,1636677297.137 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086580 psu
2021-11-12T00:34:57.238Z,1636677297.238 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:34:58.141Z,1636677298.141 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086951 psu
2021-11-12T00:34:58.838Z,1636677298.838 [Startup:StartupSatComms:A](INFO): Timed out from 2021-11-12T00:33:58.6Z
2021-11-12T00:34:58.838Z,1636677298.838 [Startup:StartupSatComms:A] Stopped
2021-11-12T00:34:58.838Z,1636677298.838 [Startup:StartupSatComms:B] Running Loop=1
2021-11-12T00:34:59.146Z,1636677299.146 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086844 psu
2021-11-12T00:34:59.246Z,1636677299.246 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-11-12T00:34:59.258Z,1636677299.258 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:35:00.150Z,1636677300.150 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086793 psu
2021-11-12T00:35:01.154Z,1636677301.154 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086792 psu
2021-11-12T00:35:02.157Z,1636677302.157 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086634 psu
2021-11-12T00:35:02.497Z,1636677302.497 [Reporter](INFO): depth -0.069484 m
2021-11-12T00:35:03.161Z,1636677303.161 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086898 psu
2021-11-12T00:35:03.569Z,1636677303.569 [Reporter](INFO): depth -0.079410 m
2021-11-12T00:35:04.165Z,1636677304.165 [CTD_Seabird](ERROR): Salinity reading out of range: 0.087216 psu
2021-11-12T00:35:05.169Z,1636677305.169 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086793 psu
2021-11-12T00:35:05.822Z,1636677305.822 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004883
2021-11-12T00:35:06.173Z,1636677306.173 [CTD_Seabird](ERROR): Salinity reading out of range: 0.086899 psu
2021-11-12T00:35:06.192Z,1636677306.192 [CommandExec](IMPORTANT): got command failComponent none Depth_Keller
2021-11-12T00:35:06.192Z,1636677306.192 [Depth_Keller] No Fault, FailCount= 3
2021-11-12T00:35:06.192Z,1636677306.192 [CommandExec](IMPORTANT): Depth_Keller failureMode is No Fault
2021-11-12T00:35:06.553Z,1636677306.553 [Reporter](INFO): depth 0.139993 m
2021-11-12T00:35:06.966Z,1636677306.966 [Reporter](INFO): depth 0.150391 m
2021-11-12T00:35:07.349Z,1636677307.349 [Reporter](INFO): depth 0.144325 m
2021-11-12T00:35:07.757Z,1636677307.757 [Reporter](INFO): depth 0.146058 m
2021-11-12T00:35:08.144Z,1636677308.144 [Reporter](INFO): depth 0.149525 m
2021-11-12T00:35:08.557Z,1636677308.557 [Reporter](INFO): depth 0.148658 m
2021-11-12T00:35:08.992Z,1636677308.992 [Reporter](INFO): depth 0.142159 m
2021-11-12T00:35:09.049Z,1636677309.049 [CommandExec](IMPORTANT): got command report clear
2021-11-12T00:35:09.992Z,1636677309.992 [CommandExec](IMPORTANT): got command quit
2021-11-12T00:35:10.996Z,1636677310.996 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:10.996Z,1636677310.996 [CommandExec](INFO): Uninitializing the command executive.
2021-11-12T00:35:10.996Z,1636677310.996 [CommandExec](INFO): Uninitializing the command scheduler.
2021-11-12T00:35:10.996Z,1636677310.996 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:11.148Z,1636677311.148 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2021-11-12T00:35:11.148Z,1636677311.148 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-11-12T00:35:11.148Z,1636677311.148 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:11.149Z,1636677311.149 [NavChartDb](INFO): Join timeout helper Thread ID is 6348
2021-11-12T00:35:11.520Z,1636677311.520 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:11.520Z,1636677311.520 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:11.527Z,1636677311.527 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-11-12T00:35:11.528Z,1636677311.528 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:11.528Z,1636677311.528 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6349
2021-11-12T00:35:11.920Z,1636677311.920 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:11.920Z,1636677311.920 [WetLabsBB2FL](INFO): Powering down
2021-11-12T00:35:11.921Z,1636677311.921 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:11.940Z,1636677311.940 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-11-12T00:35:11.940Z,1636677311.940 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:11.940Z,1636677311.940 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6350
2021-11-12T00:35:12.197Z,1636677312.197 [CTD_Seabird](INFO): Powering down
2021-11-12T00:35:12.212Z,1636677312.212 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:12.212Z,1636677312.212 [CTD_Seabird](INFO): Powering down
2021-11-12T00:35:12.228Z,1636677312.228 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:12.239Z,1636677312.239 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-11-12T00:35:12.240Z,1636677312.240 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:12.240Z,1636677312.240 [Radio_Surface](INFO): Join timeout helper Thread ID is 6351
2021-11-12T00:35:12.248Z,1636677312.248 [Radio_Surface](INFO): Powering down
2021-11-12T00:35:12.248Z,1636677312.248 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:12.249Z,1636677312.249 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:12.260Z,1636677312.260 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-11-12T00:35:12.260Z,1636677312.260 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:12.260Z,1636677312.260 [Onboard](INFO): Join timeout helper Thread ID is 6352
2021-11-12T00:35:12.648Z,1636677312.648 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:12.648Z,1636677312.648 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:12.664Z,1636677312.664 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-11-12T00:35:12.664Z,1636677312.664 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:12.665Z,1636677312.665 [DataOverHttps](INFO): Join timeout helper Thread ID is 6353
2021-11-12T00:35:13.488Z,1636677313.488 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:13.491Z,1636677313.491 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.508Z,1636677313.508 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-11-12T00:35:13.508Z,1636677313.508 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.509Z,1636677313.509 [logger](INFO): Join timeout helper Thread ID is 6354
2021-11-12T00:35:13.536Z,1636677313.536 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:13.536Z,1636677313.536 [logger ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.548Z,1636677313.548 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-11-12T00:35:13.548Z,1636677313.548 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.549Z,1636677313.549 [CommandLine](INFO): Join timeout helper Thread ID is 6355
2021-11-12T00:35:13.580Z,1636677313.580 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:13.580Z,1636677313.580 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.588Z,1636677313.588 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-11-12T00:35:13.588Z,1636677313.588 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.589Z,1636677313.589 [CommandExec](INFO): Join timeout helper Thread ID is 6356
2021-11-12T00:35:13.590Z,1636677313.590 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-11-12T00:35:13.590Z,1636677313.590 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:13.590Z,1636677313.590 [controlThread](INFO): Join timeout helper Thread ID is 6357
2021-11-12T00:35:13.760Z,1636677313.760 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-11-12T00:35:13.760Z,1636677313.760 [controlThread](DEBUG): Uninitializing ControlThread
2021-11-12T00:35:13.760Z,1636677313.760 [AHRS_M2](INFO): Powering down
2021-11-12T00:35:13.833Z,1636677313.833 [NAL9602](INFO): Powering down
2021-11-12T00:35:13.000Z,1636677314.000 [AMEcho](INFO): Powering down.
2021-11-12T00:35:14.001Z,1636677314.001 [DAT](INFO): Powering down
2021-11-12T00:35:14.072Z,1636677314.072 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-11-12T00:35:14.073Z,1636677314.073 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-11-12T00:35:14.074Z,1636677314.074 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-11-12T00:35:14.074Z,1636677314.074 [MissionManager](INFO): Uninitializing Mission Default
2021-11-12T00:35:14.077Z,1636677314.077 [MissionManager](INFO): Uninitializing Mission Startup
2021-11-12T00:35:14.077Z,1636677314.077 [Startup] Stopped
2021-11-12T00:35:14.077Z,1636677314.077 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-11-12T00:35:14.077Z,1636677314.077 [Startup:A.GoToSurface] Stopped
2021-11-12T00:35:14.078Z,1636677314.078 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-11-12T00:35:14.078Z,1636677314.078 [Startup:StartupSatComms] Stopped
2021-11-12T00:35:14.078Z,1636677314.078 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-11-12T00:35:14.078Z,1636677314.078 [Startup:StartupSatComms:B] Stopped
2021-11-12T00:35:14.080Z,1636677314.080 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-11-12T00:35:14.080Z,1636677314.080 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-11-12T00:35:14.080Z,1636677314.080 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-11-12T00:35:14.081Z,1636677314.081 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-11-12T00:35:14.081Z,1636677314.081 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-11-12T00:35:14.081Z,1636677314.081 [BuoyancyServo](INFO): Powering down
2021-11-12T00:35:14.096Z,1636677314.096 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-11-12T00:35:14.096Z,1636677314.096 [ElevatorServo](INFO): Powering down
2021-11-12T00:35:14.097Z,1636677314.097 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-11-12T00:35:14.097Z,1636677314.097 [MassServo](INFO): Powering down
2021-11-12T00:35:14.098Z,1636677314.098 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-12T00:35:14.098Z,1636677314.098 [RudderServo](INFO): Powering down
2021-11-12T00:35:14.099Z,1636677314.099 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-11-12T00:35:14.099Z,1636677314.099 [ThrusterServo](INFO): Powering down
2021-11-12T00:35:14.100Z,1636677314.100 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-11-12T00:35:14.101Z,1636677314.101 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-11-12T00:35:14.101Z,1636677314.101 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-11-12T00:35:14.101Z,1636677314.101 [CBIT](DEBUG): Powering off loads.
2021-11-12T00:35:14.115Z,1636677314.115 [CBIT](DEBUG): Disabling WDT.
2021-11-12T00:35:14.127Z,1636677314.127 [CBIT](DEBUG): Opening all GF detection circuits.
2021-11-12T00:35:14.128Z,1636677314.128 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.188Z,1636677314.188 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.191Z,1636677314.191 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.198Z,1636677314.198 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.270Z,1636677314.270 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.274Z,1636677314.274 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.313Z,1636677314.313 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-11-12T00:35:14.377Z,1636677314.377 [logger ThreadHandler](INFO): Thread cancelled.