2021-06-25T21:37:18.252Z,1624657038.252 [Supervisor](DEBUG): Initializing supervisor.
2021-06-25T21:37:18.257Z,1624657038.257 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-06-25T21:37:18.257Z,1624657038.257 [SyncHandler](INFO): Protected caller Thread ID is 2591
2021-06-25T21:37:18.258Z,1624657038.258 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-06-25T21:37:18.259Z,1624657038.259 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-06-25T21:37:18.259Z,1624657038.259 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2592
2021-06-25T21:37:18.263Z,1624657038.263 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-06-25T21:37:18.284Z,1624657038.284 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-06-25T21:37:18.285Z,1624657038.285 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-06-25T21:37:18.286Z,1624657038.286 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2593
2021-06-25T21:37:18.288Z,1624657038.288 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-06-25T21:37:18.289Z,1624657038.289 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-06-25T21:37:18.289Z,1624657038.289 [logger ThreadHandler](INFO): Protected caller Thread ID is 2594
2021-06-25T21:37:18.293Z,1624657038.293 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-06-25T21:37:18.294Z,1624657038.294 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-06-25T21:37:18.295Z,1624657038.295 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-06-25T21:37:18.722Z,1624657038.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-06-25T21:37:18.723Z,1624657038.723 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-06-25T21:37:18.870Z,1624657038.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-06-25T21:37:18.871Z,1624657038.871 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-06-25T21:37:18.952Z,1624657038.952 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-06-25T21:37:19.176Z,1624657039.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-06-25T21:37:19.176Z,1624657039.176 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-06-25T21:37:19.255Z,1624657039.255 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-06-25T21:37:19.351Z,1624657039.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-06-25T21:37:19.352Z,1624657039.352 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-06-25T21:37:19.813Z,1624657039.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-06-25T21:37:19.813Z,1624657039.813 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-06-25T21:37:19.941Z,1624657039.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-06-25T21:37:19.942Z,1624657039.942 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-06-25T21:37:20.483Z,1624657040.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-06-25T21:37:20.483Z,1624657040.483 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-06-25T21:37:20.678Z,1624657040.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-06-25T21:37:20.679Z,1624657040.679 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-06-25T21:37:21.063Z,1624657041.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-06-25T21:37:21.064Z,1624657041.064 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-06-25T21:37:21.308Z,1624657041.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-06-25T21:37:21.308Z,1624657041.308 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-06-25T21:37:21.418Z,1624657041.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-06-25T21:37:21.419Z,1624657041.419 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-06-25T21:37:22.087Z,1624657042.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-06-25T21:37:22.087Z,1624657042.087 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-06-25T21:37:22.476Z,1624657042.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-06-25T21:37:22.476Z,1624657042.476 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-06-25T21:37:23.105Z,1624657043.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-06-25T21:37:23.107Z,1624657043.107 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-06-25T21:37:23.108Z,1624657043.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-06-25T21:37:23.305Z,1624657043.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-06-25T21:37:23.389Z,1624657043.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-06-25T21:37:23.526Z,1624657043.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-06-25T21:37:23.611Z,1624657043.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-06-25T21:37:23.877Z,1624657043.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-06-25T21:37:23.878Z,1624657043.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-06-25T21:37:23.982Z,1624657043.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-06-25T21:37:24.094Z,1624657044.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-06-25T21:37:24.187Z,1624657044.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-06-25T21:37:24.333Z,1624657044.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-06-25T21:37:24.433Z,1624657044.433 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-06-25T21:37:24.533Z,1624657044.533 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2021-06-25T21:37:24.534Z,1624657044.534 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-06-25T21:37:24.549Z,1624657044.549 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-06-25T21:37:24.567Z,1624657044.567 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-06-25T21:37:24.567Z,1624657044.567 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-06-25T21:37:24.693Z,1624657044.693 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-06-25T21:37:24.694Z,1624657044.694 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-06-25T21:37:24.769Z,1624657044.769 [VerticalControl](DEBUG): Construct VerticalControl.
2021-06-25T21:37:24.831Z,1624657044.831 [VerticalControl] Loaded
2021-06-25T21:37:24.831Z,1624657044.831 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-06-25T21:37:24.834Z,1624657044.834 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-06-25T21:37:24.873Z,1624657044.873 [HorizontalControl] Loaded
2021-06-25T21:37:24.873Z,1624657044.873 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-06-25T21:37:24.876Z,1624657044.876 [SpeedControl](DEBUG): Construct SpeedControl.
2021-06-25T21:37:24.879Z,1624657044.879 [SpeedControl] Loaded
2021-06-25T21:37:24.879Z,1624657044.879 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-06-25T21:37:24.882Z,1624657044.882 [LoopControl](DEBUG): Construct LoopControl.
2021-06-25T21:37:24.882Z,1624657044.882 [LoopControl] Loaded
2021-06-25T21:37:24.882Z,1624657044.882 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-06-25T21:37:24.883Z,1624657044.883 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-06-25T21:37:24.883Z,1624657044.883 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-06-25T21:37:25.017Z,1624657045.017 [BuoyancyServo] Loaded
2021-06-25T21:37:25.017Z,1624657045.017 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-06-25T21:37:25.036Z,1624657045.036 [ElevatorServo] Loaded
2021-06-25T21:37:25.037Z,1624657045.037 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-06-25T21:37:25.055Z,1624657045.055 [MassServo] Loaded
2021-06-25T21:37:25.056Z,1624657045.056 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-06-25T21:37:25.074Z,1624657045.074 [RudderServo] Loaded
2021-06-25T21:37:25.075Z,1624657045.075 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-06-25T21:37:25.075Z,1624657045.075 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-06-25T21:37:25.076Z,1624657045.076 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-06-25T21:37:25.139Z,1624657045.139 [DepthRateCalculator] Loaded
2021-06-25T21:37:25.139Z,1624657045.139 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-06-25T21:37:25.144Z,1624657045.144 [PitchRateCalculator] Loaded
2021-06-25T21:37:25.144Z,1624657045.144 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-06-25T21:37:25.154Z,1624657045.154 [SpeedCalculator] Loaded
2021-06-25T21:37:25.155Z,1624657045.155 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-06-25T21:37:25.168Z,1624657045.168 [TempGradientCalculator] Loaded
2021-06-25T21:37:25.169Z,1624657045.169 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-06-25T21:37:25.173Z,1624657045.173 [YawRateCalculator] Loaded
2021-06-25T21:37:25.173Z,1624657045.173 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-06-25T21:37:25.191Z,1624657045.191 [ElevatorOffsetCalculator] Loaded
2021-06-25T21:37:25.191Z,1624657045.191 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-06-25T21:37:25.192Z,1624657045.192 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-06-25T21:37:25.192Z,1624657045.192 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-06-25T21:37:25.294Z,1624657045.294 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-06-25T21:37:25.294Z,1624657045.294 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-06-25T21:37:25.308Z,1624657045.308 [NavChart] Loaded
2021-06-25T21:37:25.308Z,1624657045.308 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-06-25T21:37:25.314Z,1624657045.314 [UniversalFixResidualReporter] Loaded
2021-06-25T21:37:25.314Z,1624657045.314 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-06-25T21:37:25.315Z,1624657045.315 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-06-25T21:37:25.315Z,1624657045.315 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-06-25T21:37:25.874Z,1624657045.874 [AHRS_M2] Loaded
2021-06-25T21:37:25.875Z,1624657045.875 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-06-25T21:37:26.112Z,1624657046.112 [BackseatComponent] Loaded
2021-06-25T21:37:26.112Z,1624657046.112 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-06-25T21:37:26.113Z,1624657046.113 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408684E0
2021-06-25T21:37:26.114Z,1624657046.114 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2677
2021-06-25T21:37:26.116Z,1624657046.116 [LcmUniversalReporter] Loaded
2021-06-25T21:37:26.117Z,1624657046.117 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-06-25T21:37:26.954Z,1624657046.954 [BPC1] Loaded
2021-06-25T21:37:26.954Z,1624657046.954 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-06-25T21:37:27.050Z,1624657047.050 [DataOverHttps] Loaded
2021-06-25T21:37:27.050Z,1624657047.050 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-06-25T21:37:27.051Z,1624657047.051 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408984E0
2021-06-25T21:37:27.052Z,1624657047.052 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2678
2021-06-25T21:37:27.072Z,1624657047.072 [Depth_Keller] Loaded
2021-06-25T21:37:27.073Z,1624657047.073 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-06-25T21:37:27.133Z,1624657047.133 [NAL9602] Loaded
2021-06-25T21:37:27.133Z,1624657047.133 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-06-25T21:37:27.167Z,1624657047.167 [Onboard] Loaded
2021-06-25T21:37:27.167Z,1624657047.167 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-06-25T21:37:27.169Z,1624657047.169 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C84E0
2021-06-25T21:37:27.169Z,1624657047.169 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2679
2021-06-25T21:37:27.183Z,1624657047.183 [Power24vConverter] Loaded
2021-06-25T21:37:27.184Z,1624657047.184 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-06-25T21:37:27.197Z,1624657047.197 [Radio_Surface] Loaded
2021-06-25T21:37:27.197Z,1624657047.197 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-06-25T21:37:27.198Z,1624657047.198 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F84E0
2021-06-25T21:37:27.199Z,1624657047.199 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2680
2021-06-25T21:37:27.200Z,1624657047.200 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-06-25T21:37:27.200Z,1624657047.200 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-06-25T21:37:27.284Z,1624657047.284 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-06-25T21:37:27.285Z,1624657047.285 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-06-25T21:37:27.429Z,1624657047.429 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-06-25T21:37:27.430Z,1624657047.430 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-06-25T21:37:27.506Z,1624657047.506 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-06-25T21:37:27.506Z,1624657047.506 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-06-25T21:37:28.561Z,1624657048.561 [CTD_Seabird] Loaded
2021-06-25T21:37:28.562Z,1624657048.562 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-06-25T21:37:28.563Z,1624657048.563 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2021-06-25T21:37:28.563Z,1624657048.563 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2681
2021-06-25T21:37:28.583Z,1624657048.583 [PAR_Licor] Loaded
2021-06-25T21:37:28.583Z,1624657048.583 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-06-25T21:37:28.617Z,1624657048.617 [WetLabsBB2FL] Loaded
2021-06-25T21:37:28.617Z,1624657048.617 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-06-25T21:37:28.618Z,1624657048.618 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2021-06-25T21:37:28.619Z,1624657048.619 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2682
2021-06-25T21:37:28.620Z,1624657048.620 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-06-25T21:37:28.620Z,1624657048.620 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-06-25T21:37:29.033Z,1624657049.033 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-06-25T21:37:29.033Z,1624657049.033 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-06-25T21:37:29.223Z,1624657049.223 [SBIT](DEBUG): Construct Startup Built In Test.
2021-06-25T21:37:29.233Z,1624657049.233 [SBIT] Loaded
2021-06-25T21:37:29.233Z,1624657049.233 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-06-25T21:37:29.236Z,1624657049.236 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-06-25T21:37:29.249Z,1624657049.249 [IBIT] Loaded
2021-06-25T21:37:29.249Z,1624657049.249 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-06-25T21:37:29.255Z,1624657049.255 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-06-25T21:37:29.357Z,1624657049.357 [CBIT] Loaded
2021-06-25T21:37:29.357Z,1624657049.357 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-06-25T21:37:29.358Z,1624657049.358 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-06-25T21:37:29.364Z,1624657049.364 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-06-25T21:37:29.367Z,1624657049.367 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-06-25T21:37:29.378Z,1624657049.378 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-06-25T21:37:29.379Z,1624657049.379 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B2D4E0
2021-06-25T21:37:29.379Z,1624657049.379 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2683
2021-06-25T21:37:29.384Z,1624657049.384 [Supervisor](INFO): Main Thread ID is 2590
2021-06-25T21:37:29.384Z,1624657049.384 [Supervisor](DEBUG): Running supervisor.
2021-06-25T21:37:29.385Z,1624657049.385 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2684
2021-06-25T21:37:29.388Z,1624657049.388 [controlThread ThreadHandler](INFO): Handler Thread ID is 2685
2021-06-25T21:37:29.388Z,1624657049.388 [controlThread](DEBUG): Initializing ControlThread
2021-06-25T21:37:29.389Z,1624657049.389 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-06-25T21:37:29.391Z,1624657049.391 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-06-25T21:37:29.392Z,1624657049.392 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-06-25T21:37:29.392Z,1624657049.392 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-06-25T21:37:29.393Z,1624657049.393 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-06-25T21:37:29.394Z,1624657049.394 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-06-25T21:37:29.394Z,1624657049.394 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-06-25T21:37:29.395Z,1624657049.395 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-06-25T21:37:29.395Z,1624657049.395 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-06-25T21:37:29.396Z,1624657049.396 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-06-25T21:37:29.397Z,1624657049.397 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-06-25T21:37:29.397Z,1624657049.397 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-06-25T21:37:29.405Z,1624657049.405 [SBIT](INFO): Initialize SBIT Component.
2021-06-25T21:37:29.405Z,1624657049.405 [SBIT](IMPORTANT): git: 2021-06-25
2021-06-25T21:37:29.405Z,1624657049.405 [SBIT](INFO): git hash: ce6655dd71cbacf6863957f250954322ec92cc29
2021-06-25T21:37:29.406Z,1624657049.406 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-06-25T21:37:29.407Z,1624657049.407 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-06-25T21:37:29.408Z,1624657049.408 [SBIT](INFO): Beginning SBIT in 64.000000 seconds.
2021-06-25T21:37:29.409Z,1624657049.409 [IBIT](INFO): Initialize IBIT Component.
2021-06-25T21:37:29.410Z,1624657049.410 [CBIT](DEBUG): Initialize CBIT Component.
2021-06-25T21:37:29.411Z,1624657049.411 [logger ThreadHandler](INFO): Handler Thread ID is 2686
2021-06-25T21:37:29.424Z,1624657049.424 [CBIT](DEBUG): Initialized mux pins.
2021-06-25T21:37:29.424Z,1624657049.424 [CBIT](DEBUG): Initializing the watchdog timer.
2021-06-25T21:37:29.429Z,1624657049.429 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2687
2021-06-25T21:37:29.437Z,1624657049.437 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2688
2021-06-25T21:37:29.438Z,1624657049.438 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-06-25T21:37:29.445Z,1624657049.445 [Onboard ThreadHandler](INFO): Handler Thread ID is 2689
2021-06-25T21:37:29.449Z,1624657049.449 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-06-25T21:37:29.449Z,1624657049.449 [CBIT](DEBUG): Initializing heartbeat.
2021-06-25T21:37:29.465Z,1624657049.465 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2690
2021-06-25T21:37:29.477Z,1624657049.477 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2691
2021-06-25T21:37:29.478Z,1624657049.478 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-06-25T21:37:29.483Z,1624657049.483 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2693
2021-06-25T21:37:29.494Z,1624657049.494 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2695
2021-06-25T21:37:29.497Z,1624657049.497 [WetLabsBB2FL](INFO): Powering up
2021-06-25T21:37:29.500Z,1624657049.500 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-06-25T21:37:29.500Z,1624657049.500 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-06-25T21:37:29.500Z,1624657049.500 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-06-25T21:37:29.501Z,1624657049.501 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-06-25T21:37:29.501Z,1624657049.501 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-06-25T21:37:29.501Z,1624657049.501 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-06-25T21:37:29.501Z,1624657049.501 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-06-25T21:37:29.501Z,1624657049.501 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-06-25T21:37:29.501Z,1624657049.501 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-06-25T21:37:29.502Z,1624657049.502 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-06-25T21:37:29.502Z,1624657049.502 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-06-25T21:37:29.502Z,1624657049.502 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-06-25T21:37:29.502Z,1624657049.502 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-06-25T21:37:29.502Z,1624657049.502 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-06-25T21:37:29.503Z,1624657049.503 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-06-25T21:37:29.503Z,1624657049.503 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-06-25T21:37:29.520Z,1624657049.520 [CBIT](DEBUG): Deactivating GF circuits.
2021-06-25T21:37:29.520Z,1624657049.520 [CBIT](DEBUG): Deactivating emergency mode.
2021-06-25T21:37:29.556Z,1624657049.556 [CBIT](DEBUG): Backplane powered.
2021-06-25T21:37:29.557Z,1624657049.557 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-06-25T21:37:29.585Z,1624657049.585 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-25T21:37:29.618Z,1624657049.618 [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">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-06-25T21:37:29.619Z,1624657049.619 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-06-25T21:37:29.690Z,1624657049.690 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-06-25T21:37:29.691Z,1624657049.691 [Default:A.Wait](DEBUG): Construct Wait.
2021-06-25T21:37:29.710Z,1624657049.710 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-25T21:37:29.758Z,1624657049.758 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-06-25T21:37:29.761Z,1624657049.761 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-06-25T21:37:29.783Z,1624657049.783 [Default:E.Execute](DEBUG): Construct Execute.
2021-06-25T21:37:29.786Z,1624657049.786 [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>


2021-06-25T21:37:29.791Z,1624657049.791 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-06-25T21:37:29.834Z,1624657049.834 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-06-25T21:37:29.872Z,1624657049.872 [Radio_Surface](INFO): Powering up
2021-06-25T21:37:29.907Z,1624657049.907 [Power24vConverter](INFO): Powering up.
2021-06-25T21:37:29.942Z,1624657049.942 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-06-25T21:37:29.949Z,1624657049.949 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-06-25T21:37:29.950Z,1624657049.950 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-06-25T21:37:29.957Z,1624657049.957 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-06-25T21:37:29.958Z,1624657049.958 [MassServo](DEBUG): Initializing EZServoServo.
2021-06-25T21:37:29.968Z,1624657049.968 [MassServo](DEBUG): Initializing MassServo.
2021-06-25T21:37:29.969Z,1624657049.969 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-25T21:37:29.977Z,1624657049.977 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-25T21:37:31.592Z,1624657051.592 [WetLabsBB2FL](INFO): Powering down
2021-06-25T21:37:36.509Z,1624657056.509 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-06-25T21:37:42.957Z,1624657062.957 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.042747
2021-06-25T21:37:57.274Z,1624657077.274 [NAL9602](INFO): Powering up NAL9602
2021-06-25T21:38:08.182Z,1624657088.182 [NAL9602](INFO): NAL9602 initialized
2021-06-25T21:38:34.130Z,1624657114.130 [SBIT](IMPORTANT): Beginning Startup BIT
2021-06-25T21:38:34.135Z,1624657114.135 [CBIT](IMPORTANT): Beginning ground fault scan
2021-06-25T21:38:45.121Z,1624657125.121 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.224576
CHAN A1 (24V): -0.011689
CHAN A2 (12V): 0.150585
CHAN A3 (5V): 0.044471
CHAN B0 (3.3V): 0.028250
CHAN B1 (3.15aV): 0.027942
CHAN B2 (3.15bV): 0.028419
CHAN B3 (GND): -0.012159
OPEN: 0.012527
Full Scale Calc: 4.765 mA, -1.589 mA
2021-06-25T21:39:16.090Z,1624657156.090 [CommandLine](IMPORTANT): got command strobe off
2021-06-25T21:39:16.091Z,1624657156.091 [CommandLine](IMPORTANT): Deactivating strobe
2021-06-25T21:39:28.200Z,1624657168.200 [SBIT](IMPORTANT): SBIT PASSED
2021-06-25T21:39:28.200Z,1624657168.200 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-06-25T21:39:28.201Z,1624657168.201 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;

2021-06-25T21:39:28.202Z,1624657168.202 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2021-06-25T21:39:28.202Z,1624657168.202 [SBIT](IMPORTANT): BPC1.batteryStickCommsTimeout=10 second;

2021-06-25T21:39:28.203Z,1624657168.203 [SBIT](IMPORTANT): CBIT.abortDepth=115 meter;

2021-06-25T21:39:28.203Z,1624657168.203 [SBIT](IMPORTANT): CBIT.gf24Offset=-27 microampere;

2021-06-25T21:39:28.203Z,1624657168.203 [SBIT](IMPORTANT): CBIT.stopDepth=110 meter;

2021-06-25T21:39:28.205Z,1624657168.205 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-06-25T21:39:28.206Z,1624657168.206 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-06-25T21:39:28.206Z,1624657168.206 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2021-06-25T21:39:28.206Z,1624657168.206 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 2.000000 degree;

2021-06-25T21:39:28.206Z,1624657168.206 [SBIT](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2021-06-25T21:39:28.206Z,1624657168.206 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=173.000802 cubic_centimeter;

2021-06-25T21:39:28.206Z,1624657168.206 [SBIT](IMPORTANT): VerticalControl.massDefault=11.098200 millimeter;

2021-06-25T21:39:28.609Z,1624657168.609 [MissionManager](IMPORTANT): Started mission Startup
2021-06-25T21:39:28.609Z,1624657168.609 [Startup] Running Loop=1
2021-06-25T21:39:28.609Z,1624657168.609 [Startup](DEBUG): Aggregate::initialize Startup
2021-06-25T21:39:28.609Z,1624657168.609 [Startup:A.GoToSurface] Running Loop=1
2021-06-25T21:39:28.609Z,1624657168.609 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-25T21:39:28.610Z,1624657168.610 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-25T21:39:28.610Z,1624657168.610 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-25T21:39:28.611Z,1624657168.611 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-25T21:39:28.611Z,1624657168.611 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-25T21:39:28.612Z,1624657168.612 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-25T21:39:28.614Z,1624657168.614 [Startup:StartupSatComms] Running Loop=1
2021-06-25T21:39:28.614Z,1624657168.614 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-06-25T21:39:28.614Z,1624657168.614 [Startup:StartupSatComms:A] Running Loop=1
2021-06-25T21:39:29.026Z,1624657169.026 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-06-25T21:39:41.018Z,1624657181.018 [CommandLine](IMPORTANT): got command get ThrusterHE.loadAtStartup
2021-06-25T21:39:41.019Z,1624657181.019 [CommandLine](IMPORTANT): ThrusterHE.loadAtStartup 1 bool
2021-06-25T21:40:28.798Z,1624657228.798 [Startup:StartupSatComms:A](INFO): Timed out from 2021-06-25T21:39:28.6Z
2021-06-25T21:40:28.798Z,1624657228.798 [Startup:StartupSatComms:A] Stopped
2021-06-25T21:40:28.799Z,1624657228.799 [Startup:StartupSatComms:B] Running Loop=1
2021-06-25T21:40:29.210Z,1624657229.210 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-25T21:40:29.706Z,1624657229.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-25T21:40:29.706Z,1624657229.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:40:29.761Z,1624657229.761 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:40:30.114Z,1624657230.114 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:40:30.114Z,1624657230.114 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-25T21:40:33.108Z,1624657233.108 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210624T144152/Courier0508.lzma
2021-06-25T21:40:33.354Z,1624657233.354 [DataOverHttps](INFO): Moved sent file to Logs/20210624T144152/Courier0508.lzma.bak
2021-06-25T21:40:33.355Z,1624657233.355 [DataOverHttps](INFO): SBD MOMSN=15733474
2021-06-25T21:40:48.757Z,1624657248.757 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210625T213214/Courier0000.lzma
2021-06-25T21:40:49.758Z,1624657249.758 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213214/Courier0000.lzma.bak
2021-06-25T21:40:49.759Z,1624657249.759 [DataOverHttps](INFO): SBD MOMSN=15733476
2021-06-25T21:41:05.324Z,1624657265.324 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210625T213718/Courier0000.lzma
2021-06-25T21:41:06.326Z,1624657266.326 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Courier0000.lzma.bak
2021-06-25T21:41:06.326Z,1624657266.326 [DataOverHttps](INFO): SBD MOMSN=15733479
2021-06-25T21:41:11.862Z,1624657271.862 [CommandLine](IMPORTANT): got command failComponent
2021-06-25T21:41:11.862Z,1624657271.862 [CommandLine](IMPORTANT): Failed components:
2021-06-25T21:41:11.862Z,1624657271.862 [CommandLine](IMPORTANT): No failed Components.
2021-06-25T21:41:20.695Z,1624657280.695 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210624T144152/Express0509.lzma
2021-06-25T21:41:21.698Z,1624657281.698 [DataOverHttps](INFO): Moved sent file to Logs/20210624T144152/Express0509.lzma.bak
2021-06-25T21:41:21.698Z,1624657281.698 [DataOverHttps](INFO): SBD MOMSN=15733482
2021-06-25T21:41:29.084Z,1624657289.084 [Startup:StartupSatComms:B](INFO): Timed out from 2021-06-25T21:40:28.8Z
2021-06-25T21:41:29.084Z,1624657289.084 [Startup:StartupSatComms:B] Stopped
2021-06-25T21:41:29.084Z,1624657289.084 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-06-25T21:41:29.084Z,1624657289.084 [Startup:StartupSatComms] Stopped
2021-06-25T21:41:29.084Z,1624657289.084 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-06-25T21:41:29.085Z,1624657289.085 [Startup](INFO): Completed Startup
2021-06-25T21:41:29.085Z,1624657289.085 [MissionManager](INFO): Startup is completed.
2021-06-25T21:41:29.085Z,1624657289.085 [MissionManager](INFO): Uninitializing Mission Startup
2021-06-25T21:41:29.085Z,1624657289.085 [Startup] Stopped
2021-06-25T21:41:29.086Z,1624657289.086 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-06-25T21:41:29.086Z,1624657289.086 [Startup:A.GoToSurface] Stopped
2021-06-25T21:41:29.086Z,1624657289.086 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-25T21:41:29.509Z,1624657289.509 [MissionManager](IMPORTANT): Started mission Default
2021-06-25T21:41:29.510Z,1624657289.510 [Default] Running Loop=1
2021-06-25T21:41:29.510Z,1624657289.510 [Default](DEBUG): Aggregate::initialize Default
2021-06-25T21:41:29.510Z,1624657289.510 [Default:B.GoToSurface] Running Loop=1
2021-06-25T21:41:29.510Z,1624657289.510 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-25T21:41:29.510Z,1624657289.510 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-25T21:41:29.511Z,1624657289.511 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-25T21:41:29.511Z,1624657289.511 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-25T21:41:29.511Z,1624657289.511 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-25T21:41:29.512Z,1624657289.512 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-25T21:41:29.512Z,1624657289.512 [Default:A.Wait] Running Loop=1
2021-06-25T21:41:29.512Z,1624657289.512 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-06-25T21:41:40.238Z,1624657300.238 [DataOverHttps](INFO): Sending 627 bytes from file Logs/20210625T213214/Express0001.lzma
2021-06-25T21:41:41.238Z,1624657301.238 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213214/Express0001.lzma.bak
2021-06-25T21:41:41.238Z,1624657301.238 [DataOverHttps](INFO): SBD MOMSN=15733484
2021-06-25T21:41:42.898Z,1624657302.898 [Default:A.Wait](INFO): Done Waiting.
2021-06-25T21:41:42.899Z,1624657302.898 [Default:A.Wait] Stopped
2021-06-25T21:41:42.899Z,1624657302.899 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-06-25T21:41:43.275Z,1624657303.275 [Default:CheckIn] Running Loop=1
2021-06-25T21:41:43.275Z,1624657303.275 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-25T21:41:43.275Z,1624657303.275 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-25T21:41:43.695Z,1624657303.695 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-06-25T21:42:49.505Z,1624657369.505 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:43:02.836Z,1624657382.836 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:43:11.322Z,1624657391.322 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-06-25T21:43:16.168Z,1624657396.168 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:43:29.500Z,1624657409.500 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:43:30.737Z,1624657410.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-25T21:43:30.737Z,1624657410.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:43:30.748Z,1624657410.748 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:43:31.138Z,1624657411.138 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:43:31.138Z,1624657411.138 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-25T21:43:42.832Z,1624657422.832 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:43:56.164Z,1624657436.164 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:44:09.502Z,1624657449.502 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:44:22.829Z,1624657462.829 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:44:35.756Z,1624657475.756 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:44:49.088Z,1624657489.088 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:45:02.824Z,1624657502.824 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:45:23.032Z,1624657523.032 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2021-06-25T21:45:23.035Z,1624657523.035 [BPC1](INFO): Received data from all battery sticks.
2021-06-25T21:46:31.726Z,1624657591.726 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-06-25T21:46:31.726Z,1624657591.726 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:46:31.737Z,1624657591.737 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:46:32.161Z,1624657592.161 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:46:32.162Z,1624657592.162 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-06-25T21:46:43.486Z,1624657603.486 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-06-25T21:41:43.3Z
2021-06-25T21:46:43.486Z,1624657603.486 [Default:CheckIn:Read_GPS] Stopped
2021-06-25T21:46:43.486Z,1624657603.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-25T21:46:43.890Z,1624657603.890 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-25T21:46:54.048Z,1624657614.048 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210625T213718/Courier0004.lzma
2021-06-25T21:46:55.050Z,1624657615.050 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Courier0004.lzma.bak
2021-06-25T21:46:55.050Z,1624657615.050 [DataOverHttps](INFO): SBD MOMSN=15733496
2021-06-25T21:47:10.338Z,1624657630.338 [DataOverHttps](INFO): Sending 1080 bytes from file Logs/20210625T213718/Express0001.lzma
2021-06-25T21:47:11.338Z,1624657631.338 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Express0001.lzma.bak
2021-06-25T21:47:11.338Z,1624657631.338 [DataOverHttps](INFO): SBD MOMSN=15733498
2021-06-25T21:47:27.157Z,1624657647.157 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20210625T213718/Express0005.lzma
2021-06-25T21:47:28.154Z,1624657648.154 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Express0005.lzma.bak
2021-06-25T21:47:28.154Z,1624657648.154 [DataOverHttps](INFO): SBD MOMSN=15733526
2021-06-25T21:47:30.051Z,1624657650.051 [Default:CheckIn:Read_Iridium] Stopped
2021-06-25T21:47:30.051Z,1624657650.051 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-25T21:47:30.051Z,1624657650.051 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-25T21:47:30.821Z,1624657650.821 [Power24vConverter](INFO): Powering down.
2021-06-25T21:48:09.181Z,1624657689.181 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-06-25T21:48:09.182Z,1624657689.182 [NAL9602] Data Fault, FailCount= 1
2021-06-25T21:48:09.182Z,1624657689.182 [NAL9602](ERROR): Data Fault
2021-06-25T21:48:09.253Z,1624657689.253 [CBIT](ERROR): Data Fault in component: NAL9602
2021-06-25T21:48:09.586Z,1624657689.586 [NAL9602](INFO): Powering down
2021-06-25T21:48:10.423Z,1624657690.423 [CBIT](INFO): Clearing failed state for component NAL9602
2021-06-25T21:48:10.423Z,1624657690.423 [NAL9602] No Fault, FailCount= 1
2021-06-25T21:48:39.886Z,1624657719.886 [NAL9602](INFO): Powering up NAL9602
2021-06-25T21:48:50.850Z,1624657730.850 [NAL9602](INFO): NAL9602 initialized
2021-06-25T21:49:32.871Z,1624657772.871 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-06-25T21:49:32.871Z,1624657772.871 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:49:32.882Z,1624657772.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:49:33.299Z,1624657773.299 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:49:33.299Z,1624657773.299 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-06-25T21:52:22.948Z,1624657942.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215221.00,A,3648.16830,N,12147.28378,W,0.369,73.90,250621,,,A*4A
2021-06-25T21:52:22.968Z,1624657942.968 [NAL9602](INFO): GPS fix at 20210625T215221: (36.802805, -121.788063)
2021-06-25T21:52:30.686Z,1624657950.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-06-25T21:52:30.686Z,1624657950.686 [Default:CheckIn:C.Wait] Stopped
2021-06-25T21:52:30.686Z,1624657950.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-25T21:52:30.686Z,1624657950.686 [Default:CheckIn:D] Running Loop=1
2021-06-25T21:52:31.077Z,1624657951.077 [Default:CheckIn:D] Stopped
2021-06-25T21:52:31.077Z,1624657951.077 [Default:CheckIn:E] Running Loop=1
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.026114 min 
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn:E] Stopped
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn] Stopped
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn](INFO): Running loop #2
2021-06-25T21:52:31.490Z,1624657951.490 [Default:CheckIn] Running Loop=2
2021-06-25T21:52:31.491Z,1624657951.491 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-25T21:52:31.491Z,1624657951.491 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-25T21:52:33.480Z,1624657953.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215231.00,A,3648.16809,N,12147.28499,W,0.369,73.90,250621,,,A*49
2021-06-25T21:52:33.482Z,1624657953.482 [NAL9602](INFO): GPS fix at 20210625T215231: (36.802802, -121.788083)
2021-06-25T21:52:33.497Z,1624657953.497 [Default:CheckIn:Read_GPS] Stopped
2021-06-25T21:52:33.497Z,1624657953.497 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-25T21:52:33.914Z,1624657953.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-25T21:52:33.914Z,1624657953.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:52:34.006Z,1624657954.006 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:52:34.908Z,1624657954.908 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:52:34.908Z,1624657954.908 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-25T21:52:45.604Z,1624657965.604 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20210625T213718/Courier0007.lzma
2021-06-25T21:52:46.821Z,1624657966.821 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Courier0007.lzma.bak
2021-06-25T21:52:46.821Z,1624657966.821 [DataOverHttps](INFO): SBD MOMSN=15733533
2021-06-25T21:52:54.926Z,1624657974.926 [CommandLine](IMPORTANT): got command failComponent
2021-06-25T21:52:54.926Z,1624657974.926 [CommandLine](IMPORTANT): Failed components:
2021-06-25T21:52:54.927Z,1624657974.927 [CommandLine](IMPORTANT): No failed Components.
2021-06-25T21:53:05.241Z,1624657985.241 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-25T21:53:06.633Z,1624657986.633 [DataOverHttps](INFO): Sending 524 bytes from file Logs/20210625T213718/Express0008.lzma
2021-06-25T21:53:07.634Z,1624657987.634 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Express0008.lzma.bak
2021-06-25T21:53:07.634Z,1624657987.634 [DataOverHttps](INFO): SBD MOMSN=15733536
2021-06-25T21:53:09.712Z,1624657989.712 [Default:CheckIn:Read_Iridium] Stopped
2021-06-25T21:53:09.712Z,1624657989.712 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-25T21:53:09.712Z,1624657989.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-25T21:55:35.545Z,1624658135.545 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-25T21:55:35.545Z,1624658135.545 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:55:35.556Z,1624658135.556 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:55:35.967Z,1624658135.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:55:35.967Z,1624658135.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-25T21:55:45.727Z,1624658145.727 [DataOverHttps](IMPORTANT): SBD MTMSN=20210625T215544
2021-06-25T21:55:52.941Z,1624658152.941 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/tank_ballast_and_trim_backseat.xml;set tank_ballast_and_trim_backseat.MissionTimeout 40 min;set tank_ballast_and_trim_backseat.SettleTime 5 min;set tank_ballast_and_trim_backseat.EstimationTimeout 30 min" 78wf 1 2
2021-06-25T21:55:52.981Z,1624658152.981 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/tank_ballast_and_trim_backseat.xml;set tank_ballast_and_trim_backseat.MissionTimeout 40 min;set tank_ballast_and_trim_backseat.SettleTime 5 min;set tank_ballast_and_trim_backseat.EstimationTimeout 30 min" 78wf 1 2.000000
2021-06-25T21:55:52.982Z,1624658152.982 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=78wf
2021-06-25T21:55:52.983Z,1624658152.983 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='78wf'): "load Maintenance/tank_ballast_and_trim_backseat.xml;set tank_ballast_and_trim_backseat.MissionTimeout 40 min;set tank_ballast_and_trim_backseat.SettleTime 5 min;set tank_ballast_and_trim_backseat.EstimationTimeout 30 min" ASAP
2021-06-25T21:55:53.942Z,1624658153.942 [DataOverHttps](IMPORTANT): SBD MTMSN=20210625T215552
2021-06-25T21:56:01.072Z,1624658161.072 [DataOverHttps](INFO): Received command:sched asap "set tank_ballast_and_trim_backseat.MassEstimationErrorBound 5 mm;set tank_ballast_and_trim_backseat.BuoyEstimationErrorBound 25 cc;run" 78wf 2 2
2021-06-25T21:56:01.107Z,1624658161.107 [CommandLine](IMPORTANT): got command schedule asap "set tank_ballast_and_trim_backseat.MassEstimationErrorBound 5 mm;set tank_ballast_and_trim_backseat.BuoyEstimationErrorBound 25 cc;run" 78wf 2 2.000000
2021-06-25T21:56:01.107Z,1624658161.107 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=78wf
2021-06-25T21:56:01.108Z,1624658161.108 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='78wf'): "set tank_ballast_and_trim_backseat.MassEstimationErrorBound 5 mm;set tank_ballast_and_trim_backseat.BuoyEstimationErrorBound 25 cc;run" ASAP
2021-06-25T21:56:01.183Z,1624658161.183 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim_backseat.xml
2021-06-25T21:56:01.183Z,1624658161.183 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim_backseat.xml
2021-06-25T21:56:01.256Z,1624658161.256 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.MissionTimeout = 120.000000 min
2021-06-25T21:56:01.259Z,1624658161.259 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.SkipComms = 0 bool
2021-06-25T21:56:01.262Z,1624658161.262 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.Depth1 = 7.000000 m
2021-06-25T21:56:01.265Z,1624658161.265 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.Depth2 = nan m
2021-06-25T21:56:01.268Z,1624658161.268 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.DepthDeadband = 0.100000 m
2021-06-25T21:56:01.271Z,1624658161.271 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.SurfaceThreshold = 2.500000 m
2021-06-25T21:56:01.274Z,1624658161.274 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.MaxWaitOnSurface = 20.000000 min
2021-06-25T21:56:01.277Z,1624658161.277 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.SettleTime = 10.000000 min
2021-06-25T21:56:01.280Z,1624658161.280 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.EstimationTimeout = 45.000000 min
2021-06-25T21:56:01.283Z,1624658161.283 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.MinEstimationTime = 10.000000 min
2021-06-25T21:56:01.286Z,1624658161.286 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.MassEstimationErrorBound = 0.250000 mm
2021-06-25T21:56:01.289Z,1624658161.289 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.BuoyEstimationErrorBound = 15.000000 cc
2021-06-25T21:56:01.293Z,1624658161.293 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.EstimationConfidence = 99.900000 %
2021-06-25T21:56:01.296Z,1624658161.296 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat.WaitToFloat = 5.000000 min
2021-06-25T21:56:01.296Z,1624658161.296 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-06-25T21:56:01.829Z,1624658161.829 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakDetectChlActive = 0 bool
2021-06-25T21:56:01.832Z,1624658161.832 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.TimeWindowPeakReport = nan min
2021-06-25T21:56:01.834Z,1624658161.834 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.HighestChlPeakReportActive = 0 bool
2021-06-25T21:56:01.847Z,1624658161.847 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.HighestSaltPeakReportActive = 0 bool
2021-06-25T21:56:01.850Z,1624658161.850 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.HighestOilPeakReportActive = 0 bool
2021-06-25T21:56:01.853Z,1624658161.853 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PatchTracking = 0 bool
2021-06-25T21:56:01.856Z,1624658161.856 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.FilterWidthHorizontal = 3.000000 count
2021-06-25T21:56:01.897Z,1624658161.897 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.NumProfilesSlidingwindow = 100.000000 count
2021-06-25T21:56:01.900Z,1624658161.900 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.OffPeakFractionHorizontal = 80.000000 %
2021-06-25T21:56:01.903Z,1624658161.903 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakDetectNO3Active = 0 bool
2021-06-25T21:56:01.934Z,1624658161.934 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakDetectOilActive = 0 bool
2021-06-25T21:56:01.937Z,1624658161.937 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakDetectFDOMActive = 0 bool
2021-06-25T21:56:01.940Z,1624658161.940 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakDetectSalinityActive = 0 bool
2021-06-25T21:56:01.943Z,1624658161.943 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-06-25T21:56:01.968Z,1624658161.968 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.EnabledAanderaaO2 = 0.000000 bool
2021-06-25T21:56:01.972Z,1624658161.972 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.EnabledNeilBrown = 0.000000 bool
2021-06-25T21:56:01.976Z,1624658161.976 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.EnabledSeabird = 1.000000 bool
2021-06-25T21:56:01.992Z,1624658161.992 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-06-25T21:56:01.995Z,1624658161.995 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-06-25T21:56:01.999Z,1624658161.999 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-06-25T21:56:02.038Z,1624658162.038 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.LowPassWindowLength = 20.000000 count
2021-06-25T21:56:02.042Z,1624658162.042 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.MedianFilterLen = 5.000000 count
2021-06-25T21:56:02.045Z,1624658162.045 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakShallowBound = nan m
2021-06-25T21:56:02.048Z,1624658162.048 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.PeakDeepBound = nan m
2021-06-25T21:56:02.067Z,1624658162.067 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-06-25T21:56:02.070Z,1624658162.070 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakChl = 0.000000 ug/l
2021-06-25T21:56:02.095Z,1624658162.095 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakChlDepth = 0.000000 m
2021-06-25T21:56:02.102Z,1624658162.102 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakChlTemperature = 0.000000 degC
2021-06-25T21:56:02.106Z,1624658162.106 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakChlLatitude = nan arcdeg
2021-06-25T21:56:02.109Z,1624658162.109 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakChlLongitude = nan arcdeg
2021-06-25T21:56:02.134Z,1624658162.134 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakNO3 = 0.000000 umol/l
2021-06-25T21:56:02.137Z,1624658162.137 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakNO3Depth = 0.000000 m
2021-06-25T21:56:02.140Z,1624658162.140 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakNO3Latitude = nan arcdeg
2021-06-25T21:56:02.144Z,1624658162.144 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakNO3Longitude = nan arcdeg
2021-06-25T21:56:02.171Z,1624658162.171 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakOil = 0.000000 kg/m3
2021-06-25T21:56:02.175Z,1624658162.175 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakOilDepth = 0.000000 m
2021-06-25T21:56:02.178Z,1624658162.178 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakOilLatitude = nan arcdeg
2021-06-25T21:56:02.209Z,1624658162.209 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakOilLongitude = nan arcdeg
2021-06-25T21:56:02.213Z,1624658162.213 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakFDOM = 0.000000 ppb
2021-06-25T21:56:02.236Z,1624658162.236 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakFDOMDepth = 0.000000 m
2021-06-25T21:56:02.239Z,1624658162.239 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakFDOMTemperature = nan degC
2021-06-25T21:56:02.243Z,1624658162.243 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakFDOMLatitude = nan arcdeg
2021-06-25T21:56:02.266Z,1624658162.266 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakFDOMLongitude = nan arcdeg
2021-06-25T21:56:02.269Z,1624658162.269 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchFDOMDistance = nan m
2021-06-25T21:56:02.285Z,1624658162.285 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakSalt = 0.000000 psu
2021-06-25T21:56:02.289Z,1624658162.289 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakSaltDepth = 0.000000 m
2021-06-25T21:56:02.318Z,1624658162.318 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakSaltTemperature = 0.000000 degC
2021-06-25T21:56:02.321Z,1624658162.321 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakSaltLatitude = nan arcdeg
2021-06-25T21:56:02.340Z,1624658162.340 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PeakSaltLongitude = nan arcdeg
2021-06-25T21:56:02.344Z,1624658162.344 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchChl = 0.000000 ug/l
2021-06-25T21:56:02.347Z,1624658162.347 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchChlDepth = 0.000000 m
2021-06-25T21:56:02.350Z,1624658162.350 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchChlLatitude = nan arcdeg
2021-06-25T21:56:02.368Z,1624658162.368 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchChlLongitude = nan arcdeg
2021-06-25T21:56:02.372Z,1624658162.372 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchChlDistance = nan m
2021-06-25T21:56:02.375Z,1624658162.375 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchSalt = 0.000000 psu
2021-06-25T21:56:02.405Z,1624658162.405 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchSaltDepth = 0.000000 m
2021-06-25T21:56:02.409Z,1624658162.409 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchSaltLatitude = nan arcdeg
2021-06-25T21:56:02.412Z,1624658162.412 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchSaltLongitude = nan arcdeg
2021-06-25T21:56:02.441Z,1624658162.441 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchSaltDistance = nan m
2021-06-25T21:56:02.445Z,1624658162.445 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchOil = 0.000000 psu
2021-06-25T21:56:02.464Z,1624658162.464 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchOilDepth = 0.000000 m
2021-06-25T21:56:02.468Z,1624658162.468 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchOilLatitude = nan arcdeg
2021-06-25T21:56:02.471Z,1624658162.471 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchOilLongitude = nan arcdeg
2021-06-25T21:56:02.492Z,1624658162.492 [MissionManager](INFO): DefineOutput tank_ballast_and_trim_backseat:Science.PatchOilDistance = nan m
2021-06-25T21:56:02.576Z,1624658162.576 [tank_ballast_and_trim_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-06-25T21:56:02.651Z,1624658162.651 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-06-25T21:56:02.742Z,1624658162.742 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-06-25T21:56:02.774Z,1624658162.774 [tank_ballast_and_trim_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-06-25T21:56:02.807Z,1624658162.807 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-06-25T21:56:02.854Z,1624658162.854 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-06-25T21:56:02.889Z,1624658162.889 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-06-25T21:56:02.919Z,1624658162.919 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-06-25T21:56:02.989Z,1624658162.989 [tank_ballast_and_trim_backseat:RunBallastAndTrim:B.BackseatDriver](DEBUG): Construct BackseatDriver.
2021-06-25T21:56:02.993Z,1624658162.993 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2021-06-25T21:56:03.333Z,1624658163.333 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2021-06-25T21:56:03.336Z,1624658163.336 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2021-06-25T21:56:03.340Z,1624658163.340 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2021-06-25T21:56:03.343Z,1624658163.343 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2021-06-25T21:56:03.382Z,1624658163.382 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2021-06-25T21:56:03.386Z,1624658163.386 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2021-06-25T21:56:03.393Z,1624658163.393 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2021-06-25T21:56:03.396Z,1624658163.396 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2021-06-25T21:56:03.456Z,1624658163.456 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2021-06-25T21:56:03.460Z,1624658163.460 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2021-06-25T21:56:03.463Z,1624658163.463 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2021-06-25T21:56:03.502Z,1624658163.502 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 %
2021-06-25T21:56:03.506Z,1624658163.506 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2021-06-25T21:56:03.531Z,1624658163.531 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = 0.300000 1/s
2021-06-25T21:56:03.535Z,1624658163.535 [MissionManager](INFO): DefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2021-06-25T21:56:03.590Z,1624658163.590 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2021-06-25T21:56:03.622Z,1624658163.622 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-06-25T21:56:03.626Z,1624658163.626 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2021-06-25T21:56:03.652Z,1624658163.652 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2021-06-25T21:56:03.656Z,1624658163.656 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2021-06-25T21:56:03.659Z,1624658163.659 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2021-06-25T21:56:03.679Z,1624658163.679 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-06-25T21:56:03.683Z,1624658163.683 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2021-06-25T21:56:03.702Z,1624658163.702 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2021-06-25T21:56:03.710Z,1624658163.710 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2021-06-25T21:56:03.729Z,1624658163.729 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2021-06-25T21:56:03.757Z,1624658163.757 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:tank_ballast_and_trim_backseat.DepthDeadband
2021-06-25T21:56:03.759Z,1624658163.759 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:tank_ballast_and_trim_backseat.MaxWaitOnSurface
2021-06-25T21:56:03.761Z,1624658163.761 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:tank_ballast_and_trim_backseat.SettleTime
2021-06-25T21:56:03.763Z,1624658163.763 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:tank_ballast_and_trim_backseat.EstimationTimeout
2021-06-25T21:56:03.777Z,1624658163.777 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:tank_ballast_and_trim_backseat.MinEstimationTime
2021-06-25T21:56:03.779Z,1624658163.779 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:tank_ballast_and_trim_backseat.MassEstimationErrorBound
2021-06-25T21:56:03.780Z,1624658163.780 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:tank_ballast_and_trim_backseat.BuoyEstimationErrorBound
2021-06-25T21:56:03.782Z,1624658163.782 [MissionManager](INFO): RedefineArg tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:tank_ballast_and_trim_backseat.EstimationConfidence
2021-06-25T21:56:03.849Z,1624658163.849 [tank_ballast_and_trim_backseat:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2021-06-25T21:56:03.859Z,1624658163.859 [tank_ballast_and_trim_backseat:Float_Up:B.Wait](DEBUG): Construct Wait.
2021-06-25T21:56:03.910Z,1624658163.910 [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="tank_ballast_and_trim_backseat">

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed durnig the
        mission.
    </Description><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="SurfaceThreshold"><Description>
        Surface threshold depth.
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>20</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="WaitToFloat"><Units:minute/><Value>5</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml"/>


    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Syslog>Powering up backseat...</Syslog>
        <Guidance:BackseatDriver>
            <Parallel/>
        </Guidance:BackseatDriver>
        <Syslog>Backseat powered.</Syslog>
        
        <Insert Filename="Insert/BallastAndTrim.xml">
            <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
            <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
            <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
            <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
            <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
        </Insert>

        <Aggregate Id="Depth1">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth1"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

        <Aggregate Id="Depth2">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth2"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="Float_Up">

        <Description>
            Float to surface instead of driving.
        </Description>

        <Sequence/>

        <Break>
            <Universal:depth/>
            <Lt><Control:VerticalControl.surfaceThreshold/></Lt>
        </Break>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyDefault/></Setting>
        </Guidance:Buoyancy>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="WaitToFloat"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2021-06-25T21:56:03.910Z,1624658163.910 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim_backseat.xml
2021-06-25T21:56:13.793Z,1624658173.793 [CommandLine](IMPORTANT): got command set tank_ballast_and_trim_backseat.MissionTimeout 40.000000 minute
2021-06-25T21:56:13.794Z,1624658173.794 [CommandLine](IMPORTANT): got command set tank_ballast_and_trim_backseat.SettleTime 5.000000 minute
2021-06-25T21:56:13.795Z,1624658173.795 [CommandLine](IMPORTANT): got command set tank_ballast_and_trim_backseat.EstimationTimeout 30.000000 minute
2021-06-25T21:56:20.729Z,1624658180.729 [CommandLine](IMPORTANT): got command set tank_ballast_and_trim_backseat.MassEstimationErrorBound 5.000000 millimeter
2021-06-25T21:56:20.730Z,1624658180.730 [CommandLine](IMPORTANT): got command set tank_ballast_and_trim_backseat.BuoyEstimationErrorBound 25.000000 cubic_centimeter
2021-06-25T21:56:20.731Z,1624658180.731 [CommandLine](IMPORTANT): got command run
2021-06-25T21:56:20.735Z,1624658180.735 [CommandLine](IMPORTANT): Running 
2021-06-25T21:56:20.813Z,1624658180.813 [Default] Stopped
2021-06-25T21:56:20.813Z,1624658180.813 [Default](DEBUG): Aggregate::uninitialize Default
2021-06-25T21:56:20.813Z,1624658180.813 [Default:B.GoToSurface] Stopped
2021-06-25T21:56:20.813Z,1624658180.813 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-25T21:56:20.813Z,1624658180.813 [Default:CheckIn] Stopped
2021-06-25T21:56:20.813Z,1624658180.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-25T21:56:20.813Z,1624658180.813 [Default:CheckIn:C.Wait] Stopped
2021-06-25T21:56:20.813Z,1624658180.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-06-25T21:56:20.814Z,1624658180.814 [MissionManager](IMPORTANT): Started mission tank_ballast_and_trim_backseat
2021-06-25T21:56:20.814Z,1624658180.814 [tank_ballast_and_trim_backseat] Running Loop=1
2021-06-25T21:56:20.814Z,1624658180.814 [tank_ballast_and_trim_backseat](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat
2021-06-25T21:56:20.814Z,1624658180.814 [tank_ballast_and_trim_backseat:Science] Running Loop=1
2021-06-25T21:56:20.814Z,1624658180.814 [tank_ballast_and_trim_backseat:Science](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science
2021-06-25T21:56:20.814Z,1624658180.814 [tank_ballast_and_trim_backseat:Science:A] Running Loop=1
2021-06-25T21:56:20.814Z,1624658180.814 [tank_ballast_and_trim_backseat:Science:B] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:C] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:D] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:E] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:F] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:Read_Oil] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:PeakDetectChl] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:PeakDetectChl](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:PeakDetectChl
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-06-25T21:56:20.815Z,1624658180.815 [tank_ballast_and_trim_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-06-25T21:56:20.837Z,1624658180.837 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport] Running Loop=1
2021-06-25T21:56:20.837Z,1624658180.837 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:HighestChlPeakReport
2021-06-25T21:56:20.837Z,1624658180.837 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-06-25T21:56:20.837Z,1624658180.837 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-06-25T21:56:20.838Z,1624658180.838 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3] Running Loop=1
2021-06-25T21:56:20.838Z,1624658180.838 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:PeakDetectNO3
2021-06-25T21:56:20.838Z,1624658180.838 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-06-25T21:56:20.838Z,1624658180.838 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-06-25T21:56:20.844Z,1624658180.844 [tank_ballast_and_trim_backseat:Science:PeakDetectOil] Running Loop=1
2021-06-25T21:56:20.845Z,1624658180.845 [tank_ballast_and_trim_backseat:Science:PeakDetectOil](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:PeakDetectOil
2021-06-25T21:56:20.845Z,1624658180.845 [tank_ballast_and_trim_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-06-25T21:56:20.845Z,1624658180.845 [tank_ballast_and_trim_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-06-25T21:56:20.847Z,1624658180.847 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport] Running Loop=1
2021-06-25T21:56:20.847Z,1624658180.847 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:HighestOilPeakReport
2021-06-25T21:56:20.847Z,1624658180.847 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-06-25T21:56:20.847Z,1624658180.847 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-06-25T21:56:20.848Z,1624658180.848 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM] Running Loop=1
2021-06-25T21:56:20.852Z,1624658180.852 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:PeakDetectFDOM
2021-06-25T21:56:20.852Z,1624658180.852 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-06-25T21:56:20.852Z,1624658180.852 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-06-25T21:56:20.855Z,1624658180.855 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity] Running Loop=1
2021-06-25T21:56:20.855Z,1624658180.855 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:PeakDetectSalinity
2021-06-25T21:56:20.855Z,1624658180.855 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-06-25T21:56:20.855Z,1624658180.855 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-06-25T21:56:20.857Z,1624658180.857 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport] Running Loop=1
2021-06-25T21:56:20.857Z,1624658180.857 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport
2021-06-25T21:56:20.857Z,1624658180.857 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-06-25T21:56:20.857Z,1624658180.857 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-06-25T21:56:20.858Z,1624658180.858 [tank_ballast_and_trim_backseat:Science:OceanCurrent] Running Loop=1
2021-06-25T21:56:20.858Z,1624658180.858 [tank_ballast_and_trim_backseat:Science:OceanCurrent](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Science:OceanCurrent
2021-06-25T21:56:20.858Z,1624658180.858 [tank_ballast_and_trim_backseat:Science:OceanCurrent:A.] Running Loop=1
2021-06-25T21:56:20.858Z,1624658180.858 [tank_ballast_and_trim_backseat:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-06-25T21:56:20.858Z,1624658180.858 [tank_ballast_and_trim_backseat:RunBallastAndTrim] Running Loop=1
2021-06-25T21:56:20.859Z,1624658180.859 [tank_ballast_and_trim_backseat:RunBallastAndTrim](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim
2021-06-25T21:56:20.859Z,1624658180.859 [tank_ballast_and_trim_backseat:RunBallastAndTrim:B.BackseatDriver] Running Loop=1
2021-06-25T21:56:20.859Z,1624658180.859 [tank_ballast_and_trim_backseat:RunBallastAndTrim:B.BackseatDriver](DEBUG): Initialize.
2021-06-25T21:56:20.958Z,1624658180.958 [BackseatComponent](INFO): Powering up
2021-06-25T21:56:20.959Z,1624658180.959 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-06-25T21:56:20.977Z,1624658180.977 [tank_ballast_and_trim_backseat:RunBallastAndTrim:A] Running Loop=1
2021-06-25T21:56:20.977Z,1624658180.977 [tank_ballast_and_trim_backseat:RunBallastAndTrim:B.BackseatDriver] Running Loop=1
2021-06-25T21:56:21.004Z,1624658181.004 [tank_ballast_and_trim_backseat:RunBallastAndTrim:A](INFO): Powering up backseat...
2021-06-25T21:56:21.005Z,1624658181.005 [tank_ballast_and_trim_backseat:RunBallastAndTrim:A] Stopped
2021-06-25T21:56:21.005Z,1624658181.005 [tank_ballast_and_trim_backseat:RunBallastAndTrim:C] Running Loop=1
2021-06-25T21:56:21.005Z,1624658181.005 [tank_ballast_and_trim_backseat:Science] Running Loop=1
2021-06-25T21:56:21.006Z,1624658181.006 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport] Stopped
2021-06-25T21:56:21.006Z,1624658181.006 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport
2021-06-25T21:56:21.006Z,1624658181.006 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-06-25T21:56:21.006Z,1624658181.006 [tank_ballast_and_trim_backseat:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-06-25T21:56:21.006Z,1624658181.006 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity] Stopped
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:PeakDetectSalinity
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM] Stopped
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:PeakDetectFDOM
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport] Stopped
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:HighestOilPeakReport
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-06-25T21:56:21.007Z,1624658181.007 [tank_ballast_and_trim_backseat:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectOil] Stopped
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:PeakDetectOil
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3] Stopped
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:PeakDetectNO3
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-06-25T21:56:21.008Z,1624658181.008 [tank_ballast_and_trim_backseat:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport] Stopped
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:HighestChlPeakReport
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:PeakDetectChl] Stopped
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:PeakDetectChl
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-06-25T21:56:21.009Z,1624658181.009 [tank_ballast_and_trim_backseat:Science:OceanCurrent:A.] Running Loop=1
2021-06-25T21:56:21.010Z,1624658181.010 [tank_ballast_and_trim_backseat:Science:Read_Oil] Stopped
2021-06-25T21:56:21.010Z,1624658181.010 [tank_ballast_and_trim_backseat:Science:F] Stopped
2021-06-25T21:56:21.010Z,1624658181.010 [tank_ballast_and_trim_backseat:Science:E] Running Loop=1
2021-06-25T21:56:21.011Z,1624658181.011 [tank_ballast_and_trim_backseat:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-06-25T21:56:21.011Z,1624658181.011 [tank_ballast_and_trim_backseat:Science:D] Stopped
2021-06-25T21:56:21.011Z,1624658181.011 [tank_ballast_and_trim_backseat:Science:C] Running Loop=1
2021-06-25T21:56:21.011Z,1624658181.011 [tank_ballast_and_trim_backseat:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-06-25T21:56:21.016Z,1624658181.016 [tank_ballast_and_trim_backseat:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-06-25T21:56:21.016Z,1624658181.016 [tank_ballast_and_trim_backseat:Science:B] Running Loop=1
2021-06-25T21:56:21.017Z,1624658181.017 [tank_ballast_and_trim_backseat:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-06-25T21:56:21.018Z,1624658181.018 [tank_ballast_and_trim_backseat:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-06-25T21:56:21.018Z,1624658181.018 [tank_ballast_and_trim_backseat:Science:A] Stopped
2021-06-25T21:56:21.206Z,1624658181.206 [tank_ballast_and_trim_backseat:RunBallastAndTrim:C](INFO): Backseat powered.
2021-06-25T21:56:21.207Z,1624658181.207 [tank_ballast_and_trim_backseat:RunBallastAndTrim:C] Stopped
2021-06-25T21:56:21.207Z,1624658181.207 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1] Running Loop=1
2021-06-25T21:56:21.207Z,1624658181.207 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1
2021-06-25T21:56:21.207Z,1624658181.207 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:A] Running Loop=1
2021-06-25T21:56:21.485Z,1624658181.485 [WetLabsBB2FL](INFO): Powering up
2021-06-25T21:56:21.614Z,1624658181.614 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:A] Stopped
2021-06-25T21:56:21.614Z,1624658181.614 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B] Running Loop=1
2021-06-25T21:56:21.614Z,1624658181.614 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B
2021-06-25T21:56:22.012Z,1624658182.012 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2021-06-25T21:56:22.012Z,1624658182.012 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim
2021-06-25T21:56:22.012Z,1624658182.012 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-06-25T21:56:22.012Z,1624658182.012 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-06-25T21:56:22.013Z,1624658182.013 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-06-25T21:56:22.013Z,1624658182.013 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-06-25T21:56:22.013Z,1624658182.013 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-06-25T21:56:22.013Z,1624658182.013 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-06-25T21:56:22.013Z,1624658182.013 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:G] Running Loop=1
2021-06-25T21:56:22.015Z,1624658182.015 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:G](INFO): Going to target depth. Speed set to 0.000000 m/s 
2021-06-25T21:56:22.015Z,1624658182.015 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:G] Stopped
2021-06-25T21:56:22.016Z,1624658182.016 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2021-06-25T21:56:22.016Z,1624658182.016 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-06-25T21:56:22.016Z,1624658182.016 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2021-06-25T21:56:22.017Z,1624658182.017 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2021-06-25T21:56:22.018Z,1624658182.018 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2021-06-25T21:56:22.018Z,1624658182.018 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-06-25T21:56:22.018Z,1624658182.018 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2021-06-25T21:56:22.018Z,1624658182.018 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-06-25T21:56:22.018Z,1624658182.018 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-06-25T21:56:22.019Z,1624658182.019 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-06-25T21:56:22.019Z,1624658182.019 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-06-25T21:56:22.019Z,1624658182.019 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-06-25T21:56:22.019Z,1624658182.019 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-06-25T21:56:22.430Z,1624658182.430 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2021-06-25T21:56:22.430Z,1624658182.430 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-06-25T21:56:22.430Z,1624658182.430 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-06-25T21:56:22.431Z,1624658182.431 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2021-06-25T21:56:22.432Z,1624658182.432 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2021-06-25T21:56:22.432Z,1624658182.432 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-06-25T21:56:22.432Z,1624658182.432 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2021-06-25T21:56:22.432Z,1624658182.432 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-06-25T21:56:22.835Z,1624658182.835 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-06-25T21:56:22.836Z,1624658182.836 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2021-06-25T21:56:22.836Z,1624658182.836 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2021-06-25T21:56:22.836Z,1624658182.836 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2021-06-25T21:56:22.836Z,1624658182.836 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2021-06-25T21:56:24.386Z,1624658184.386 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-06-25T21:56:26.395Z,1624658186.395 [CTD_Seabird](ERROR): Salinity reading out of range: 0.331370 psu
2021-06-25T21:56:27.398Z,1624658187.398 [CTD_Seabird](ERROR): Salinity reading out of range: 0.330298 psu
2021-06-25T21:56:28.402Z,1624658188.402 [CTD_Seabird](ERROR): Salinity reading out of range: 0.329450 psu
2021-06-25T21:56:29.449Z,1624658189.449 [CTD_Seabird](ERROR): Salinity reading out of range: 0.328713 psu
2021-06-25T21:56:30.446Z,1624658190.446 [CTD_Seabird](ERROR): Salinity reading out of range: 0.328881 psu
2021-06-25T21:56:31.449Z,1624658191.449 [CTD_Seabird](ERROR): Salinity reading out of range: 0.329331 psu
2021-06-25T21:56:32.446Z,1624658192.446 [CTD_Seabird](ERROR): Salinity reading out of range: 0.330065 psu
2021-06-25T21:56:33.446Z,1624658193.446 [CTD_Seabird](ERROR): Salinity reading out of range: 0.330852 psu
2021-06-25T21:56:34.449Z,1624658194.449 [CTD_Seabird](ERROR): Salinity reading out of range: 0.331418 psu
2021-06-25T21:56:35.453Z,1624658195.453 [CTD_Seabird](ERROR): Salinity reading out of range: 0.331759 psu
2021-06-25T21:56:36.453Z,1624658196.453 [CTD_Seabird](ERROR): Salinity reading out of range: 0.331935 psu
2021-06-25T21:56:37.446Z,1624658197.446 [CTD_Seabird](ERROR): Salinity reading out of range: 0.332391 psu
2021-06-25T21:56:38.455Z,1624658198.455 [CTD_Seabird](ERROR): Salinity reading out of range: 0.333126 psu
2021-06-25T21:56:39.462Z,1624658199.462 [CTD_Seabird](ERROR): Salinity reading out of range: 0.334364 psu
2021-06-25T21:56:40.471Z,1624658200.471 [CTD_Seabird](ERROR): Salinity reading out of range: 0.335995 psu
2021-06-25T21:56:41.474Z,1624658201.474 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338022 psu
2021-06-25T21:56:42.479Z,1624658202.479 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340220 psu
2021-06-25T21:56:43.482Z,1624658203.482 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342249 psu
2021-06-25T21:56:44.487Z,1624658204.487 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344401 psu
2021-06-25T21:56:45.491Z,1624658205.491 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346603 psu
2021-06-25T21:56:46.496Z,1624658206.496 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348817 psu
2021-06-25T21:56:47.498Z,1624658207.498 [CTD_Seabird](ERROR): Salinity reading out of range: 0.350801 psu
2021-06-25T21:56:48.503Z,1624658208.503 [CTD_Seabird](ERROR): Salinity reading out of range: 0.352675 psu
2021-06-25T21:56:49.507Z,1624658209.507 [CTD_Seabird](ERROR): Salinity reading out of range: 0.354996 psu
2021-06-25T21:56:50.518Z,1624658210.518 [CTD_Seabird](ERROR): Salinity reading out of range: 0.357436 psu
2021-06-25T21:56:51.518Z,1624658211.518 [CTD_Seabird](ERROR): Salinity reading out of range: 0.359817 psu
2021-06-25T21:56:52.523Z,1624658212.523 [CTD_Seabird](ERROR): Salinity reading out of range: 0.362415 psu
2021-06-25T21:56:53.526Z,1624658213.526 [CTD_Seabird](ERROR): Salinity reading out of range: 0.365290 psu
2021-06-25T21:56:54.531Z,1624658214.531 [CTD_Seabird](ERROR): Salinity reading out of range: 0.368687 psu
2021-06-25T21:56:55.534Z,1624658215.534 [CTD_Seabird](ERROR): Salinity reading out of range: 0.371783 psu
2021-06-25T21:56:56.539Z,1624658216.539 [CTD_Seabird](ERROR): Salinity reading out of range: 0.374361 psu
2021-06-25T21:56:57.542Z,1624658217.542 [CTD_Seabird](ERROR): Salinity reading out of range: 0.376451 psu
2021-06-25T21:56:58.546Z,1624658218.546 [CTD_Seabird](ERROR): Salinity reading out of range: 0.378264 psu
2021-06-25T21:56:59.550Z,1624658219.550 [CTD_Seabird](ERROR): Salinity reading out of range: 0.379907 psu
2021-06-25T21:57:00.554Z,1624658220.554 [CTD_Seabird](ERROR): Salinity reading out of range: 0.380915 psu
2021-06-25T21:57:01.558Z,1624658221.558 [CTD_Seabird](ERROR): Salinity reading out of range: 0.382201 psu
2021-06-25T21:57:02.562Z,1624658222.562 [CTD_Seabird](ERROR): Salinity reading out of range: 0.383606 psu
2021-06-25T21:57:03.567Z,1624658223.567 [CTD_Seabird](ERROR): Salinity reading out of range: 0.385874 psu
2021-06-25T21:57:04.570Z,1624658224.570 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388260 psu
2021-06-25T21:57:05.574Z,1624658225.574 [CTD_Seabird](ERROR): Salinity reading out of range: 0.391333 psu
2021-06-25T21:57:06.578Z,1624658226.578 [CTD_Seabird](ERROR): Salinity reading out of range: 0.394233 psu
2021-06-25T21:57:07.582Z,1624658227.582 [CTD_Seabird](ERROR): Salinity reading out of range: 0.398735 psu
2021-06-25T21:57:08.165Z,1624658228.165 [BackseatComponent](IMPORTANT): IBASE917-01: running backseat application.
2021-06-25T21:57:08.587Z,1624658228.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.401814 psu
2021-06-25T21:57:09.590Z,1624658229.590 [CTD_Seabird](ERROR): Salinity reading out of range: 0.403765 psu
2021-06-25T21:57:10.594Z,1624658230.594 [CTD_Seabird](ERROR): Salinity reading out of range: 0.405070 psu
2021-06-25T21:57:10.871Z,1624658230.871 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2021-06-25T21:57:10.872Z,1624658230.872 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2021-06-25T21:57:10.876Z,1624658230.876 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2021-06-25T21:57:10.876Z,1624658230.876 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2021-06-25T21:57:10.879Z,1624658230.879 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2021-06-25T21:57:10.879Z,1624658230.879 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2021-06-25T21:57:10.882Z,1624658230.882 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2021-06-25T21:57:10.883Z,1624658230.883 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2021-06-25T21:57:10.886Z,1624658230.886 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2021-06-25T21:57:10.886Z,1624658230.886 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2021-06-25T21:57:10.893Z,1624658230.893 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2021-06-25T21:57:10.894Z,1624658230.894 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2021-06-25T21:57:10.900Z,1624658230.900 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2021-06-25T21:57:10.901Z,1624658230.901 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2021-06-25T21:57:11.599Z,1624658231.599 [CTD_Seabird](ERROR): Salinity reading out of range: 0.413360 psu
2021-06-25T21:57:12.603Z,1624658232.603 [CTD_Seabird](ERROR): Salinity reading out of range: 0.416850 psu
2021-06-25T21:57:13.606Z,1624658233.606 [CTD_Seabird](ERROR): Salinity reading out of range: 0.418889 psu
2021-06-25T21:57:14.610Z,1624658234.610 [CTD_Seabird](ERROR): Salinity reading out of range: 0.421285 psu
2021-06-25T21:57:15.614Z,1624658235.614 [CTD_Seabird](ERROR): Salinity reading out of range: 0.425276 psu
2021-06-25T21:57:16.619Z,1624658236.619 [CTD_Seabird](ERROR): Salinity reading out of range: 0.426854 psu
2021-06-25T21:57:17.622Z,1624658237.622 [CTD_Seabird](ERROR): Salinity reading out of range: 0.426261 psu
2021-06-25T21:57:18.626Z,1624658238.626 [CTD_Seabird](ERROR): Salinity reading out of range: 0.425215 psu
2021-06-25T21:57:19.630Z,1624658239.630 [CTD_Seabird](ERROR): Salinity reading out of range: 0.424289 psu
2021-06-25T21:57:20.634Z,1624658240.634 [CTD_Seabird](ERROR): Salinity reading out of range: 0.423426 psu
2021-06-25T21:57:21.639Z,1624658241.639 [CTD_Seabird](ERROR): Salinity reading out of range: 0.420452 psu
2021-06-25T21:57:22.642Z,1624658242.642 [CTD_Seabird](ERROR): Salinity reading out of range: 0.416332 psu
2021-06-25T21:57:23.646Z,1624658243.646 [CTD_Seabird](ERROR): Salinity reading out of range: 0.410443 psu
2021-06-25T21:57:24.650Z,1624658244.650 [CTD_Seabird](ERROR): Salinity reading out of range: 0.401932 psu
2021-06-25T21:57:25.654Z,1624658245.654 [CTD_Seabird](ERROR): Salinity reading out of range: 0.393190 psu
2021-06-25T21:57:26.658Z,1624658246.658 [CTD_Seabird](ERROR): Salinity reading out of range: 0.386846 psu
2021-06-25T21:57:27.662Z,1624658247.662 [CTD_Seabird](ERROR): Salinity reading out of range: 0.445398 psu
2021-06-25T21:57:28.666Z,1624658248.666 [CTD_Seabird](ERROR): Salinity reading out of range: 0.541314 psu
2021-06-25T21:57:29.670Z,1624658249.670 [CTD_Seabird](ERROR): Salinity reading out of range: 2.189102 psu
2021-06-25T21:57:30.678Z,1624658250.678 [CTD_Seabird](ERROR): Salinity reading out of range: 15.405575 psu
2021-06-25T21:57:30.683Z,1624658250.683 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2021-06-25T21:57:30.683Z,1624658250.683 [CommandLine](IMPORTANT): platform_buoyancy_position 438.059326 cc
2021-06-25T21:57:36.302Z,1624658256.302 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2021-06-25T21:57:36.303Z,1624658256.303 [CommandLine](IMPORTANT): platform_buoyancy_position 388.636963 cc
2021-06-25T21:57:53.423Z,1624658273.423 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:58:06.746Z,1624658286.746 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:58:20.079Z,1624658300.079 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:58:33.806Z,1624658313.806 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:58:36.644Z,1624658316.644 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-06-25T21:58:36.644Z,1624658316.644 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T21:58:36.690Z,1624658316.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T21:58:37.102Z,1624658317.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T21:58:37.102Z,1624658317.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-06-25T21:58:45.932Z,1624658325.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T21:58:47.147Z,1624658327.147 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:58:48.945Z,1624658328.945 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.254395
2021-06-25T21:59:00.471Z,1624658340.471 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:59:14.204Z,1624658354.204 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:59:21.080Z,1624658361.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T21:59:27.943Z,1624658367.943 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:59:41.676Z,1624658381.676 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:59:55.010Z,1624658395.010 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T21:59:56.241Z,1624658396.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:00:08.340Z,1624658408.340 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:00:16.973Z,1624658416.973 [Radio_Surface](INFO): Powering down
2021-06-25T22:00:31.384Z,1624658431.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:00:32.388Z,1624658432.388 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-06-25T22:01:37.661Z,1624658497.661 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-06-25T22:01:37.661Z,1624658497.661 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:01:37.715Z,1624658497.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:01:38.091Z,1624658498.091 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:01:38.091Z,1624658498.091 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-06-25T22:02:45.576Z,1624658565.576 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2021-06-25T22:02:45.577Z,1624658565.577 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-06-25T22:02:45.577Z,1624658565.577 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2021-06-25T22:02:45.577Z,1624658565.577 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-06-25T22:02:45.577Z,1624658565.577 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2021-06-25T22:02:45.577Z,1624658565.577 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2021-06-25T22:02:45.578Z,1624658565.578 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-06-25T22:02:45.578Z,1624658565.578 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2021-06-25T22:02:45.578Z,1624658565.578 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-06-25T22:02:45.578Z,1624658565.578 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2021-06-25T22:02:45.578Z,1624658565.578 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2021-06-25T22:02:45.578Z,1624658565.578 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-06-25T22:02:45.579Z,1624658565.579 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-06-25T22:02:45.579Z,1624658565.579 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2021-06-25T22:02:45.579Z,1624658565.579 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-06-25T22:02:45.579Z,1624658565.579 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2021-06-25T22:02:45.579Z,1624658565.579 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2021-06-25T22:02:45.914Z,1624658565.914 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2021-06-25T22:02:45.917Z,1624658565.917 [BPC1](INFO): Received data from all battery sticks.
2021-06-25T22:02:45.963Z,1624658565.963 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.005216 m 
2021-06-25T22:02:45.963Z,1624658565.963 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2021-06-25T22:02:45.964Z,1624658565.964 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2021-06-25T22:02:45.964Z,1624658565.964 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2021-06-25T22:02:45.964Z,1624658565.964 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-06-25T22:02:45.969Z,1624658565.969 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-06-25T22:04:38.640Z,1624658678.640 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-06-25T22:04:38.640Z,1624658678.640 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:04:38.792Z,1624658678.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:04:39.161Z,1624658679.161 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:04:39.161Z,1624658679.161 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-06-25T22:04:39.457Z,1624658679.457 [CTD_Seabird](ERROR): Failed to parse device response: 8.33,19.2639,4.586.15
2021-06-25T22:07:39.644Z,1624658859.644 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-06-25T22:07:39.644Z,1624658859.644 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:07:39.753Z,1624658859.753 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:07:40.160Z,1624658860.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:07:40.160Z,1624658860.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-06-25T22:07:46.514Z,1624658866.514 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2021-06-25T22:07:46.514Z,1624658866.514 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2021-06-25T22:07:46.514Z,1624658866.514 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2021-06-25T22:07:46.514Z,1624658866.514 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2021-06-25T22:07:46.920Z,1624658866.920 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 7.690732 m 
2021-06-25T22:07:46.920Z,1624658866.920 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2021-06-25T22:07:46.920Z,1624658866.920 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2021-06-25T22:07:46.920Z,1624658866.920 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2021-06-25T22:10:40.649Z,1624659040.649 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-06-25T22:10:40.649Z,1624659040.649 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:10:40.800Z,1624659040.800 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:10:41.252Z,1624659041.252 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:10:41.252Z,1624659041.252 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-06-25T22:13:41.633Z,1624659221.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-06-25T22:13:41.633Z,1624659221.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:13:41.745Z,1624659221.745 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:13:42.156Z,1624659222.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:13:42.157Z,1624659222.157 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-06-25T22:15:16.976Z,1624659316.976 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:15:29.904Z,1624659329.904 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:15:43.642Z,1624659343.642 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:15:56.976Z,1624659356.976 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:16:10.312Z,1624659370.312 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:16:23.640Z,1624659383.640 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:16:37.377Z,1624659397.377 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:16:42.645Z,1624659402.645 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-06-25T22:16:42.645Z,1624659402.645 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:16:42.753Z,1624659402.753 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:16:43.136Z,1624659403.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:16:43.136Z,1624659403.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-06-25T22:16:50.708Z,1624659410.708 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:17:04.044Z,1624659424.044 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:17:17.385Z,1624659437.385 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:17:31.116Z,1624659451.116 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-06-25T22:17:47.702Z,1624659467.702 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 10.01 minutes.
2021-06-25T22:17:47.712Z,1624659467.712 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 184.552860 +/- 13.040304 cc (conf. level 99.90%, sigma: 15.901585 cc).
2021-06-25T22:17:47.713Z,1624659467.713 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: 11.629403 +/- 3.049704 mm (conf. level 99.90%, sigma: 3.718865 mm).
2021-06-25T22:17:47.714Z,1624659467.714 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 184.552860 cc buoyancy neutral and 11.629403 mm mass default
2021-06-25T22:17:47.718Z,1624659467.718 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-06-25T22:17:47.719Z,1624659467.719 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;

2021-06-25T22:17:47.719Z,1624659467.719 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2021-06-25T22:17:47.719Z,1624659467.719 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryStickCommsTimeout=10 second;

2021-06-25T22:17:47.719Z,1624659467.719 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.abortDepth=115 meter;

2021-06-25T22:17:47.719Z,1624659467.719 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.gf24Offset=-27 microampere;

2021-06-25T22:17:47.720Z,1624659467.720 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.stopDepth=110 meter;

2021-06-25T22:17:47.720Z,1624659467.720 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-06-25T22:17:47.720Z,1624659467.720 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-06-25T22:17:47.720Z,1624659467.720 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2021-06-25T22:17:47.720Z,1624659467.720 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express linearApproximation platform_roll_angle 2.000000 degree;

2021-06-25T22:17:47.720Z,1624659467.720 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2021-06-25T22:17:47.721Z,1624659467.721 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=184.552860 cubic_centimeter;

2021-06-25T22:17:47.721Z,1624659467.721 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=11.629403 millimeter;

2021-06-25T22:17:47.721Z,1624659467.721 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2021-06-25T22:17:47.721Z,1624659467.721 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2021-06-25T22:17:47.722Z,1624659467.722 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-06-25T22:17:47.722Z,1624659467.722 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2021-06-25T22:17:47.722Z,1624659467.722 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-06-25T22:17:47.722Z,1624659467.722 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2021-06-25T22:17:47.723Z,1624659467.723 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2021-06-25T22:17:47.723Z,1624659467.723 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:A] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:B] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:C] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:D] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:E] Stopped
2021-06-25T22:17:47.762Z,1624659467.762 [tank_ballast_and_trim_backseat:RunBallastAndTrim:BallastAndTrim:F] Stopped
2021-06-25T22:17:48.111Z,1624659468.111 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B
2021-06-25T22:17:48.111Z,1624659468.111 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B] Stopped
2021-06-25T22:17:48.111Z,1624659468.111 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1:B
2021-06-25T22:17:48.111Z,1624659468.111 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1
2021-06-25T22:17:48.111Z,1624659468.111 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1] Stopped
2021-06-25T22:17:48.111Z,1624659468.111 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth1
2021-06-25T22:17:48.112Z,1624659468.112 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2] Running Loop=1
2021-06-25T22:17:48.112Z,1624659468.112 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2
2021-06-25T22:17:48.112Z,1624659468.112 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2:A] Running Loop=1
2021-06-25T22:17:48.506Z,1624659468.506 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2] Stopped
2021-06-25T22:17:48.506Z,1624659468.506 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2
2021-06-25T22:17:48.506Z,1624659468.506 [tank_ballast_and_trim_backseat:RunBallastAndTrim:Depth2:A] Stopped
2021-06-25T22:17:48.540Z,1624659468.540 [tank_ballast_and_trim_backseat:RunBallastAndTrim](INFO): Completed tank_ballast_and_trim_backseat:RunBallastAndTrim
2021-06-25T22:17:48.540Z,1624659468.540 [tank_ballast_and_trim_backseat:RunBallastAndTrim] Stopped
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:RunBallastAndTrim](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:RunBallastAndTrim
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:RunBallastAndTrim:B.BackseatDriver] Stopped
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:RunBallastAndTrim:B.BackseatDriver](DEBUG): Uninitialize.
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:Float_Up] Running Loop=1
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:Float_Up](DEBUG): Aggregate::initialize tank_ballast_and_trim_backseat:Float_Up
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:Float_Up:A.Buoyancy] Running Loop=1
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-06-25T22:17:48.541Z,1624659468.541 [tank_ballast_and_trim_backseat:Float_Up:B.Wait] Running Loop=1
2021-06-25T22:17:48.542Z,1624659468.542 [tank_ballast_and_trim_backseat:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2021-06-25T22:17:48.914Z,1624659468.914 [tank_ballast_and_trim_backseat:Float_Up:A.Buoyancy] Running Loop=1
2021-06-25T22:17:48.920Z,1624659468.920 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-06-25T22:17:55.363Z,1624659475.363 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2021-06-25T22:18:04.045Z,1624659484.045 [BackseatComponent](INFO): Powering down
2021-06-25T22:18:04.248Z,1624659484.248 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-06-25T22:18:04.249Z,1624659484.249 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-06-25T22:19:32.337Z,1624659572.337 [tank_ballast_and_trim_backseat:Float_Up] Stopped
2021-06-25T22:19:32.337Z,1624659572.337 [tank_ballast_and_trim_backseat:Float_Up](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Float_Up
2021-06-25T22:19:32.337Z,1624659572.337 [tank_ballast_and_trim_backseat:Float_Up:A.Buoyancy] Stopped
2021-06-25T22:19:32.337Z,1624659572.337 [tank_ballast_and_trim_backseat:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-06-25T22:19:32.337Z,1624659572.337 [tank_ballast_and_trim_backseat:Float_Up:B.Wait] Stopped
2021-06-25T22:19:32.338Z,1624659572.338 [tank_ballast_and_trim_backseat:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2021-06-25T22:19:32.339Z,1624659572.339 [tank_ballast_and_trim_backseat](INFO): Completed tank_ballast_and_trim_backseat
2021-06-25T22:19:32.339Z,1624659572.339 [MissionManager](INFO): tank_ballast_and_trim_backseat is completed.
2021-06-25T22:19:32.340Z,1624659572.340 [MissionManager](INFO): Uninitializing Mission tank_ballast_and_trim_backseat
2021-06-25T22:19:32.340Z,1624659572.340 [tank_ballast_and_trim_backseat] Stopped
2021-06-25T22:19:32.340Z,1624659572.340 [tank_ballast_and_trim_backseat](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat
2021-06-25T22:19:32.340Z,1624659572.340 [tank_ballast_and_trim_backseat:Science] Stopped
2021-06-25T22:19:32.340Z,1624659572.340 [tank_ballast_and_trim_backseat:Science](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science
2021-06-25T22:19:32.340Z,1624659572.340 [tank_ballast_and_trim_backseat:Science:B] Stopped
2021-06-25T22:19:32.340Z,1624659572.340 [tank_ballast_and_trim_backseat:Science:C] Stopped
2021-06-25T22:19:32.341Z,1624659572.341 [tank_ballast_and_trim_backseat:Science:E] Stopped
2021-06-25T22:19:32.341Z,1624659572.341 [tank_ballast_and_trim_backseat:Science:OceanCurrent] Stopped
2021-06-25T22:19:32.341Z,1624659572.341 [tank_ballast_and_trim_backseat:Science:OceanCurrent](DEBUG): Aggregate::uninitialize tank_ballast_and_trim_backseat:Science:OceanCurrent
2021-06-25T22:19:32.341Z,1624659572.341 [tank_ballast_and_trim_backseat:Science:OceanCurrent:A.] Stopped
2021-06-25T22:19:32.749Z,1624659572.749 [MissionManager](IMPORTANT): Started mission Default
2021-06-25T22:19:32.749Z,1624659572.749 [Default] Running Loop=1
2021-06-25T22:19:32.749Z,1624659572.749 [Default](DEBUG): Aggregate::initialize Default
2021-06-25T22:19:32.749Z,1624659572.749 [Default:B.GoToSurface] Running Loop=1
2021-06-25T22:19:32.749Z,1624659572.749 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-25T22:19:32.749Z,1624659572.749 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-25T22:19:32.750Z,1624659572.750 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-25T22:19:32.750Z,1624659572.750 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-25T22:19:32.750Z,1624659572.750 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-25T22:19:32.751Z,1624659572.751 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-25T22:19:32.751Z,1624659572.751 [Default:A.Wait] Running Loop=1
2021-06-25T22:19:32.751Z,1624659572.751 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-06-25T22:19:32.792Z,1624659572.792 [WetLabsBB2FL](INFO): Powering down
2021-06-25T22:19:32.920Z,1624659572.920 [Radio_Surface](INFO): Powering up
2021-06-25T22:19:38.204Z,1624659578.204 [DataOverHttps](INFO): Radio surface powered ON.
2021-06-25T22:19:43.655Z,1624659583.655 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-06-25T22:19:43.655Z,1624659583.655 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:19:43.705Z,1624659583.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:19:44.112Z,1624659584.112 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:19:44.113Z,1624659584.113 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-06-25T22:19:46.087Z,1624659586.087 [Default:A.Wait](INFO): Done Waiting.
2021-06-25T22:19:46.087Z,1624659586.087 [Default:A.Wait] Stopped
2021-06-25T22:19:46.087Z,1624659586.087 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-06-25T22:19:46.473Z,1624659586.473 [Default:CheckIn] Running Loop=1
2021-06-25T22:19:46.473Z,1624659586.473 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-25T22:19:46.473Z,1624659586.473 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-25T22:19:48.487Z,1624659588.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221946.00,A,3648.16405,N,12147.28307,W,0.194,0.00,250621,,,A*78
2021-06-25T22:19:48.490Z,1624659588.490 [NAL9602](INFO): GPS fix at 20210625T221946: (36.802734, -121.788051)
2021-06-25T22:19:48.500Z,1624659588.500 [Default:CheckIn:Read_GPS] Stopped
2021-06-25T22:19:48.500Z,1624659588.500 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-06-25T22:20:08.325Z,1624659608.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:20:10.957Z,1624659610.957 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2021-06-25T22:20:10.960Z,1624659610.960 [BPC1](INFO): Received data from all battery sticks.
2021-06-25T22:20:36.588Z,1624659636.588 [NAL9602](INFO): SBD MO Status=2, MOMSN=4872, MT Status=2, MTMSN=0
2021-06-25T22:20:36.588Z,1624659636.588 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-25T22:20:43.464Z,1624659643.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:21:18.612Z,1624659678.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:21:31.187Z,1624659691.187 [NAL9602](INFO): SBD MO Status=2, MOMSN=4872, MT Status=2, MTMSN=0
2021-06-25T22:21:31.188Z,1624659691.188 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-25T22:21:53.752Z,1624659713.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:21:53.999Z,1624659713.999 [NAL9602](INFO): SBD MO Status=2, MOMSN=4872, MT Status=2, MTMSN=0
2021-06-25T22:21:53.000Z,1624659714.000 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-25T22:22:17.139Z,1624659737.139 [NAL9602](INFO): SBD MO Status=2, MOMSN=4872, MT Status=2, MTMSN=0
2021-06-25T22:22:17.140Z,1624659737.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-25T22:22:28.900Z,1624659748.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-06-25T22:22:44.656Z,1624659764.656 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-25T22:22:44.656Z,1624659764.656 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:22:44.667Z,1624659764.667 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:22:45.094Z,1624659765.094 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:22:45.095Z,1624659765.095 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-25T22:23:01.177Z,1624659781.177 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003165
2021-06-25T22:23:08.616Z,1624659788.616 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20210625T213718/Courier0010.lzma
2021-06-25T22:23:09.618Z,1624659789.618 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Courier0010.lzma.bak
2021-06-25T22:23:09.618Z,1624659789.618 [DataOverHttps](INFO): SBD MOMSN=15733565
2021-06-25T22:23:19.579Z,1624659799.579 [NAL9602](INFO): SBD MO Status=2, MOMSN=4872, MT Status=2, MTMSN=0
2021-06-25T22:23:19.579Z,1624659799.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-06-25T22:23:25.160Z,1624659805.160 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20210625T213718/Courier0013.lzma
2021-06-25T22:23:26.227Z,1624659806.227 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Courier0013.lzma.bak
2021-06-25T22:23:26.227Z,1624659806.227 [DataOverHttps](INFO): SBD MOMSN=15733568
2021-06-25T22:23:42.312Z,1624659822.312 [DataOverHttps](INFO): Sending 2275 bytes from file Logs/20210625T213718/Express0011.lzma
2021-06-25T22:23:43.306Z,1624659823.306 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Express0011.lzma.bak
2021-06-25T22:23:43.306Z,1624659823.306 [DataOverHttps](INFO): SBD MOMSN=15733570
2021-06-25T22:23:50.286Z,1624659830.286 [NAL9602](INFO): Not Powering down - fast GPS
2021-06-25T22:23:58.580Z,1624659838.580 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210625T213718/Express0014.lzma
2021-06-25T22:23:59.582Z,1624659839.582 [DataOverHttps](INFO): Moved sent file to Logs/20210625T213718/Express0014.lzma.bak
2021-06-25T22:23:59.582Z,1624659839.582 [DataOverHttps](INFO): SBD MOMSN=15733617
2021-06-25T22:24:01.645Z,1624659841.645 [Default:CheckIn:Read_Iridium] Stopped
2021-06-25T22:24:01.645Z,1624659841.645 [Default:CheckIn:C.Wait] Running Loop=1
2021-06-25T22:24:01.646Z,1624659841.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-06-25T22:25:45.825Z,1624659945.825 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-25T22:25:45.825Z,1624659945.825 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-25T22:25:45.836Z,1624659945.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-25T22:25:46.239Z,1624659946.239 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-25T22:25:46.239Z,1624659946.239 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-25T22:27:12.025Z,1624660032.025 [DataOverHttps](IMPORTANT): SBD MTMSN=20210625T222711