2021-07-27T21:27:25.500Z,1627421245.500 [Supervisor](DEBUG): Initializing supervisor.
2021-07-27T21:27:25.505Z,1627421245.505 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-07-27T21:27:25.505Z,1627421245.505 [SyncHandler](INFO): Protected caller Thread ID is 2198
2021-07-27T21:27:25.506Z,1627421245.506 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-07-27T21:27:25.507Z,1627421245.507 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-07-27T21:27:25.507Z,1627421245.507 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2199
2021-07-27T21:27:25.511Z,1627421245.511 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-07-27T21:27:25.531Z,1627421245.531 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-07-27T21:27:25.532Z,1627421245.532 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-07-27T21:27:25.533Z,1627421245.533 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2200
2021-07-27T21:27:25.535Z,1627421245.535 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-07-27T21:27:25.536Z,1627421245.536 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-07-27T21:27:25.536Z,1627421245.536 [logger ThreadHandler](INFO): Protected caller Thread ID is 2201
2021-07-27T21:27:25.540Z,1627421245.540 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-07-27T21:27:25.540Z,1627421245.540 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-07-27T21:27:25.542Z,1627421245.542 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-07-27T21:27:25.772Z,1627421245.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-07-27T21:27:25.773Z,1627421245.773 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-07-27T21:27:25.855Z,1627421245.855 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-07-27T21:27:26.358Z,1627421246.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-07-27T21:27:26.358Z,1627421246.358 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-07-27T21:27:26.691Z,1627421246.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-07-27T21:27:26.692Z,1627421246.692 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-07-27T21:27:26.789Z,1627421246.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-07-27T21:27:26.790Z,1627421246.790 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-07-27T21:27:27.165Z,1627421247.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-07-27T21:27:27.165Z,1627421247.165 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-07-27T21:27:27.367Z,1627421247.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-07-27T21:27:27.367Z,1627421247.367 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-07-27T21:27:27.833Z,1627421247.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-07-27T21:27:27.834Z,1627421247.834 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-07-27T21:27:27.961Z,1627421247.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-07-27T21:27:27.962Z,1627421247.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-07-27T21:27:28.042Z,1627421248.042 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-07-27T21:27:28.712Z,1627421248.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-07-27T21:27:28.712Z,1627421248.712 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-07-27T21:27:29.081Z,1627421249.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-07-27T21:27:29.081Z,1627421249.081 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-07-27T21:27:29.276Z,1627421249.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-07-27T21:27:29.276Z,1627421249.276 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-07-27T21:27:29.388Z,1627421249.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-07-27T21:27:29.389Z,1627421249.389 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-07-27T21:27:29.580Z,1627421249.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-07-27T21:27:29.581Z,1627421249.581 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-07-27T21:27:29.991Z,1627421249.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-07-27T21:27:29.993Z,1627421249.993 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-07-27T21:27:30.342Z,1627421250.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-07-27T21:27:30.677Z,1627421250.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-07-27T21:27:30.765Z,1627421250.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-07-27T21:27:30.867Z,1627421250.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-07-27T21:27:30.952Z,1627421250.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-07-27T21:27:31.049Z,1627421251.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-07-27T21:27:31.153Z,1627421251.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-07-27T21:27:31.345Z,1627421251.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-07-27T21:27:31.422Z,1627421251.422 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-07-27T21:27:31.573Z,1627421251.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-07-27T21:27:31.713Z,1627421251.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-07-27T21:27:31.831Z,1627421251.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-07-27T21:27:32.095Z,1627421252.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-07-27T21:27:32.096Z,1627421252.096 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-07-27T21:27:32.106Z,1627421252.106 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-07-27T21:27:32.162Z,1627421252.162 [VerticalControl](DEBUG): Construct VerticalControl.
2021-07-27T21:27:32.224Z,1627421252.224 [VerticalControl] Loaded
2021-07-27T21:27:32.225Z,1627421252.225 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-07-27T21:27:32.227Z,1627421252.227 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-07-27T21:27:32.267Z,1627421252.267 [HorizontalControl] Loaded
2021-07-27T21:27:32.268Z,1627421252.268 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-07-27T21:27:32.270Z,1627421252.270 [SpeedControl](DEBUG): Construct SpeedControl.
2021-07-27T21:27:32.273Z,1627421252.273 [SpeedControl] Loaded
2021-07-27T21:27:32.274Z,1627421252.274 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-07-27T21:27:32.276Z,1627421252.276 [LoopControl](DEBUG): Construct LoopControl.
2021-07-27T21:27:32.277Z,1627421252.277 [LoopControl] Loaded
2021-07-27T21:27:32.277Z,1627421252.277 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-07-27T21:27:32.277Z,1627421252.277 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-07-27T21:27:32.278Z,1627421252.278 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-07-27T21:27:32.289Z,1627421252.289 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-07-27T21:27:32.290Z,1627421252.290 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-07-27T21:27:32.396Z,1627421252.396 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-07-27T21:27:32.397Z,1627421252.397 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-07-27T21:27:32.547Z,1627421252.547 [BuoyancyServo] Loaded
2021-07-27T21:27:32.547Z,1627421252.547 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-07-27T21:27:32.566Z,1627421252.566 [ElevatorServo] Loaded
2021-07-27T21:27:32.567Z,1627421252.567 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-07-27T21:27:32.585Z,1627421252.585 [MassServo] Loaded
2021-07-27T21:27:32.585Z,1627421252.585 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-07-27T21:27:32.604Z,1627421252.604 [RudderServo] Loaded
2021-07-27T21:27:32.604Z,1627421252.604 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-07-27T21:27:32.621Z,1627421252.621 [ThrusterServo] Loaded
2021-07-27T21:27:32.621Z,1627421252.621 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-07-27T21:27:32.622Z,1627421252.622 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-07-27T21:27:32.622Z,1627421252.622 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-07-27T21:27:32.704Z,1627421252.704 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-07-27T21:27:32.705Z,1627421252.705 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-07-27T21:27:32.718Z,1627421252.718 [NavChart] Loaded
2021-07-27T21:27:32.718Z,1627421252.718 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-07-27T21:27:32.724Z,1627421252.724 [UniversalFixResidualReporter] Loaded
2021-07-27T21:27:32.724Z,1627421252.724 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-07-27T21:27:32.725Z,1627421252.725 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-07-27T21:27:32.725Z,1627421252.725 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-07-27T21:27:32.785Z,1627421252.785 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-07-27T21:27:32.785Z,1627421252.785 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-07-27T21:27:33.160Z,1627421253.160 [AHRS_M2] Loaded
2021-07-27T21:27:33.160Z,1627421253.160 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-07-27T21:27:33.960Z,1627421253.960 [BPC1] Loaded
2021-07-27T21:27:33.960Z,1627421253.960 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-07-27T21:27:34.033Z,1627421254.033 [DataOverHttps] Loaded
2021-07-27T21:27:34.033Z,1627421254.033 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-07-27T21:27:34.034Z,1627421254.034 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408854E0
2021-07-27T21:27:34.035Z,1627421254.035 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2286
2021-07-27T21:27:34.055Z,1627421254.055 [Depth_Keller] Loaded
2021-07-27T21:27:34.055Z,1627421254.055 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-07-27T21:27:34.060Z,1627421254.060 [DropWeight] Loaded
2021-07-27T21:27:34.060Z,1627421254.060 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-07-27T21:27:34.119Z,1627421254.119 [NAL9602] Loaded
2021-07-27T21:27:34.119Z,1627421254.119 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-07-27T21:27:34.148Z,1627421254.148 [Onboard] Loaded
2021-07-27T21:27:34.148Z,1627421254.148 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-07-27T21:27:34.149Z,1627421254.149 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2021-07-27T21:27:34.150Z,1627421254.150 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2287
2021-07-27T21:27:34.162Z,1627421254.162 [Power24vConverter] Loaded
2021-07-27T21:27:34.162Z,1627421254.162 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-07-27T21:27:34.175Z,1627421254.175 [Radio_Surface] Loaded
2021-07-27T21:27:34.176Z,1627421254.176 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-07-27T21:27:34.177Z,1627421254.177 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0
2021-07-27T21:27:34.177Z,1627421254.177 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2288
2021-07-27T21:27:34.178Z,1627421254.178 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-07-27T21:27:34.178Z,1627421254.178 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-07-27T21:27:34.222Z,1627421254.222 [DepthRateCalculator] Loaded
2021-07-27T21:27:34.222Z,1627421254.222 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-07-27T21:27:34.227Z,1627421254.227 [PitchRateCalculator] Loaded
2021-07-27T21:27:34.227Z,1627421254.227 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-07-27T21:27:34.237Z,1627421254.237 [SpeedCalculator] Loaded
2021-07-27T21:27:34.237Z,1627421254.237 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-07-27T21:27:34.251Z,1627421254.251 [TempGradientCalculator] Loaded
2021-07-27T21:27:34.251Z,1627421254.251 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-07-27T21:27:34.255Z,1627421254.255 [YawRateCalculator] Loaded
2021-07-27T21:27:34.256Z,1627421254.256 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-07-27T21:27:34.274Z,1627421254.274 [ElevatorOffsetCalculator] Loaded
2021-07-27T21:27:34.274Z,1627421254.274 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-07-27T21:27:34.274Z,1627421254.274 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-07-27T21:27:34.275Z,1627421254.275 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-07-27T21:27:34.416Z,1627421254.416 [CANONSampler] Loaded
2021-07-27T21:27:34.416Z,1627421254.416 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-07-27T21:27:34.475Z,1627421254.475 [CTD_Seabird] Loaded
2021-07-27T21:27:34.476Z,1627421254.476 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-07-27T21:27:34.477Z,1627421254.477 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4099A4E0
2021-07-27T21:27:34.477Z,1627421254.477 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2289
2021-07-27T21:27:34.497Z,1627421254.497 [PAR_Licor] Loaded
2021-07-27T21:27:34.497Z,1627421254.497 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-07-27T21:27:34.529Z,1627421254.529 [WetLabsBB2FL] Loaded
2021-07-27T21:27:34.530Z,1627421254.530 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-07-27T21:27:34.531Z,1627421254.531 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2021-07-27T21:27:34.531Z,1627421254.531 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2290
2021-07-27T21:27:34.532Z,1627421254.532 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-07-27T21:27:34.533Z,1627421254.533 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-07-27T21:27:34.561Z,1627421254.561 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-07-27T21:27:34.562Z,1627421254.562 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-07-27T21:27:35.230Z,1627421255.230 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-07-27T21:27:35.231Z,1627421255.231 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-07-27T21:27:35.384Z,1627421255.384 [SBIT](DEBUG): Construct Startup Built In Test.
2021-07-27T21:27:35.393Z,1627421255.393 [SBIT] Loaded
2021-07-27T21:27:35.394Z,1627421255.394 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-07-27T21:27:35.397Z,1627421255.397 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-07-27T21:27:35.410Z,1627421255.410 [IBIT] Loaded
2021-07-27T21:27:35.410Z,1627421255.410 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-07-27T21:27:35.416Z,1627421255.416 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-07-27T21:27:35.518Z,1627421255.518 [CBIT] Loaded
2021-07-27T21:27:35.518Z,1627421255.518 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-07-27T21:27:35.518Z,1627421255.518 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-07-27T21:27:35.519Z,1627421255.519 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-07-27T21:27:35.619Z,1627421255.619 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-07-27T21:27:35.625Z,1627421255.625 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-07-27T21:27:35.628Z,1627421255.628 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-07-27T21:27:35.640Z,1627421255.640 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-07-27T21:27:35.641Z,1627421255.641 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFE4E0
2021-07-27T21:27:35.641Z,1627421255.641 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2291
2021-07-27T21:27:35.646Z,1627421255.646 [Supervisor](INFO): Main Thread ID is 829
2021-07-27T21:27:35.646Z,1627421255.646 [Supervisor](DEBUG): Running supervisor.
2021-07-27T21:27:35.647Z,1627421255.647 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2292
2021-07-27T21:27:35.649Z,1627421255.649 [controlThread ThreadHandler](INFO): Handler Thread ID is 2293
2021-07-27T21:27:35.650Z,1627421255.650 [controlThread](DEBUG): Initializing ControlThread
2021-07-27T21:27:35.650Z,1627421255.650 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-07-27T21:27:35.652Z,1627421255.652 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-07-27T21:27:35.653Z,1627421255.653 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-07-27T21:27:35.654Z,1627421255.654 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-07-27T21:27:35.656Z,1627421255.656 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-07-27T21:27:35.656Z,1627421255.656 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-07-27T21:27:35.661Z,1627421255.661 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-07-27T21:27:35.661Z,1627421255.661 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-07-27T21:27:35.662Z,1627421255.662 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-07-27T21:27:35.662Z,1627421255.662 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-07-27T21:27:35.663Z,1627421255.663 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-07-27T21:27:35.663Z,1627421255.663 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-07-27T21:27:35.668Z,1627421255.668 [SBIT](INFO): Initialize SBIT Component.
2021-07-27T21:27:35.668Z,1627421255.668 [SBIT](IMPORTANT): git: 2021-06-24-6-gba3a55f22
2021-07-27T21:27:35.668Z,1627421255.668 [SBIT](INFO): git hash: ba3a55f2224e7c70e8e462f655364809e4684817
2021-07-27T21:27:35.669Z,1627421255.669 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-07-27T21:27:35.670Z,1627421255.670 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-07-27T21:27:35.671Z,1627421255.671 [SBIT](INFO): Beginning SBIT in 88.000000 seconds.
2021-07-27T21:27:35.672Z,1627421255.672 [IBIT](INFO): Initialize IBIT Component.
2021-07-27T21:27:35.673Z,1627421255.673 [CBIT](DEBUG): Initialize CBIT Component.
2021-07-27T21:27:35.674Z,1627421255.674 [logger ThreadHandler](INFO): Handler Thread ID is 2294
2021-07-27T21:27:35.687Z,1627421255.687 [CBIT](DEBUG): Initialized mux pins.
2021-07-27T21:27:35.687Z,1627421255.687 [CBIT](DEBUG): Initializing the watchdog timer.
2021-07-27T21:27:35.692Z,1627421255.692 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2295
2021-07-27T21:27:35.693Z,1627421255.693 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-07-27T21:27:35.700Z,1627421255.700 [Onboard ThreadHandler](INFO): Handler Thread ID is 2296
2021-07-27T21:27:35.712Z,1627421255.712 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-07-27T21:27:35.713Z,1627421255.713 [CBIT](DEBUG): Initializing heartbeat.
2021-07-27T21:27:35.721Z,1627421255.721 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2297
2021-07-27T21:27:35.732Z,1627421255.732 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2298
2021-07-27T21:27:35.733Z,1627421255.733 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-07-27T21:27:35.736Z,1627421255.736 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2300
2021-07-27T21:27:35.740Z,1627421255.740 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2302
2021-07-27T21:27:35.743Z,1627421255.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-07-27T21:27:35.743Z,1627421255.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-07-27T21:27:35.744Z,1627421255.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-07-27T21:27:35.744Z,1627421255.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-07-27T21:27:35.744Z,1627421255.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-07-27T21:27:35.744Z,1627421255.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-07-27T21:27:35.744Z,1627421255.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-07-27T21:27:35.745Z,1627421255.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-07-27T21:27:35.745Z,1627421255.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-07-27T21:27:35.745Z,1627421255.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-07-27T21:27:35.745Z,1627421255.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-07-27T21:27:35.745Z,1627421255.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-07-27T21:27:35.745Z,1627421255.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-07-27T21:27:35.746Z,1627421255.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-07-27T21:27:35.746Z,1627421255.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-07-27T21:27:35.746Z,1627421255.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-07-27T21:27:35.760Z,1627421255.760 [WetLabsBB2FL](INFO): Powering up
2021-07-27T21:27:35.783Z,1627421255.783 [CBIT](DEBUG): Deactivating GF circuits.
2021-07-27T21:27:35.783Z,1627421255.783 [CBIT](DEBUG): Deactivating emergency mode.
2021-07-27T21:27:35.819Z,1627421255.819 [CBIT](DEBUG): Backplane powered.
2021-07-27T21:27:35.820Z,1627421255.820 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-07-27T21:27:35.833Z,1627421255.833 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-27T21:27:35.850Z,1627421255.850 [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-07-27T21:27:35.851Z,1627421255.851 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-07-27T21:27:35.934Z,1627421255.934 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-07-27T21:27:35.955Z,1627421255.955 [Default:A.Wait](DEBUG): Construct Wait.
2021-07-27T21:27:35.958Z,1627421255.958 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-27T21:27:35.978Z,1627421255.978 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-07-27T21:27:36.005Z,1627421256.005 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-07-27T21:27:36.027Z,1627421256.027 [Default:E.Execute](DEBUG): Construct Execute.
2021-07-27T21:27:36.031Z,1627421256.031 [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-07-27T21:27:36.044Z,1627421256.044 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-07-27T21:27:36.058Z,1627421256.058 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-07-27T21:27:36.139Z,1627421256.139 [Radio_Surface](INFO): Powering up
2021-07-27T21:27:36.144Z,1627421256.144 [Power24vConverter](INFO): Powering up.
2021-07-27T21:27:36.144Z,1627421256.144 [CANONSampler](INFO): Powering down
2021-07-27T21:27:36.192Z,1627421256.192 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-07-27T21:27:36.200Z,1627421256.200 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-07-27T21:27:36.201Z,1627421256.201 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-07-27T21:27:36.212Z,1627421256.212 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-07-27T21:27:36.213Z,1627421256.213 [MassServo](DEBUG): Initializing EZServoServo.
2021-07-27T21:27:36.220Z,1627421256.220 [MassServo](DEBUG): Initializing MassServo.
2021-07-27T21:27:36.220Z,1627421256.220 [RudderServo](DEBUG): Initializing EZServoServo.
2021-07-27T21:27:36.232Z,1627421256.232 [RudderServo](DEBUG): Initializing RudderServo.
2021-07-27T21:27:36.232Z,1627421256.232 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-07-27T21:27:36.240Z,1627421256.240 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-07-27T21:27:37.191Z,1627421257.191 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-07-27T21:27:37.191Z,1627421257.191 [RudderServo](FAULT): Rudder failed to initialize
2021-07-27T21:27:37.192Z,1627421257.192 [RudderServo] Communications Fault, FailCount= 1
2021-07-27T21:27:37.192Z,1627421257.192 [RudderServo](ERROR): Communications Fault
2021-07-27T21:27:37.295Z,1627421257.295 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-07-27T21:27:37.444Z,1627421257.444 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-07-27T21:27:37.444Z,1627421257.444 [RudderServo](INFO): Powering down
2021-07-27T21:27:37.848Z,1627421257.848 [WetLabsBB2FL](INFO): Powering down
2021-07-27T21:27:38.132Z,1627421258.132 [RudderServo](DEBUG): Initializing EZServoServo.
2021-07-27T21:27:38.252Z,1627421258.252 [RudderServo](DEBUG): Initializing RudderServo.
2021-07-27T21:27:38.257Z,1627421258.257 [CBIT](INFO): Clearing failed state for component RudderServo
2021-07-27T21:27:38.257Z,1627421258.257 [RudderServo] No Fault, FailCount= 1
2021-07-27T21:27:42.764Z,1627421262.764 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-07-27T21:28:04.377Z,1627421284.377 [NAL9602](INFO): Powering up NAL9602
2021-07-27T21:28:15.285Z,1627421295.285 [NAL9602](INFO): NAL9602 initialized
2021-07-27T21:29:04.202Z,1627421344.202 [SBIT](IMPORTANT): Beginning Startup BIT
2021-07-27T21:29:04.206Z,1627421344.206 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-27T21:29:04.982Z,1627421344.982 [NAL9602](INFO): SBD MO Status=2, MOMSN=28553, MT Status=2, MTMSN=0
2021-07-27T21:29:04.983Z,1627421344.983 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-27T21:29:15.172Z,1627421355.172 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002368
CHAN A1 (24V): -0.181223
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.001683
CHAN B0 (3.3V): -0.000178
CHAN B1 (3.15aV): 4.767929
CHAN B2 (3.15bV): 0.000220
CHAN B3 (GND): -0.000271
OPEN: -0.000492
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-27T21:29:58.352Z,1627421398.352 [SBIT](IMPORTANT): SBIT PASSED
2021-07-27T21:29:58.352Z,1627421398.352 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-07-27T21:29:58.353Z,1627421398.353 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-07-27T21:29:58.353Z,1627421398.353 [SBIT](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-07-27T21:29:58.353Z,1627421398.353 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=405.897205 cubic_centimeter;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): VerticalControl.kdDepthBuoy=2680 second;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): VerticalControl.kiDepthBuoy=0.15 reciprocal_second;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): VerticalControl.kiDepthOff=0.01 meter_per_second;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): VerticalControl.kpDepthBuoy=62 ratio;

2021-07-27T21:29:58.354Z,1627421398.354 [SBIT](IMPORTANT): VerticalControl.massDefault=-17.147663 millimeter;

2021-07-27T21:29:58.747Z,1627421398.747 [MissionManager](IMPORTANT): Started mission Startup
2021-07-27T21:29:58.747Z,1627421398.747 [Startup] Running Loop=1
2021-07-27T21:29:58.748Z,1627421398.748 [Startup](DEBUG): Aggregate::initialize Startup
2021-07-27T21:29:58.748Z,1627421398.748 [Startup:A.GoToSurface] Running Loop=1
2021-07-27T21:29:58.748Z,1627421398.748 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-27T21:29:58.748Z,1627421398.748 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-27T21:29:58.749Z,1627421398.749 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-27T21:29:58.749Z,1627421398.749 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-27T21:29:58.750Z,1627421398.750 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-27T21:29:58.750Z,1627421398.750 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-27T21:29:58.752Z,1627421398.752 [Startup:StartupSatComms] Running Loop=1
2021-07-27T21:29:58.752Z,1627421398.752 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-07-27T21:29:58.752Z,1627421398.752 [Startup:StartupSatComms:A] Running Loop=1
2021-07-27T21:29:59.128Z,1627421399.128 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-07-27T21:30:35.897Z,1627421435.897 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-07-27T21:30:35.897Z,1627421435.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-27T21:30:35.908Z,1627421435.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-27T21:30:36.320Z,1627421436.320 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-27T21:30:36.320Z,1627421436.320 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-07-27T21:30:58.938Z,1627421458.938 [Startup:StartupSatComms:A](INFO): Timed out from 2021-07-27T21:29:58.8Z
2021-07-27T21:30:58.939Z,1627421458.939 [Startup:StartupSatComms:A] Stopped
2021-07-27T21:30:58.939Z,1627421458.939 [Startup:StartupSatComms:B] Running Loop=1
2021-07-27T21:30:59.345Z,1627421459.345 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-27T21:31:22.782Z,1627421482.782 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004375
2021-07-27T21:31:31.291Z,1627421491.291 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210727T210334/Courier0009.lzma
2021-07-27T21:31:32.293Z,1627421492.293 [DataOverHttps](INFO): Moved sent file to Logs/20210727T210334/Courier0009.lzma.bak
2021-07-27T21:31:32.293Z,1627421492.293 [DataOverHttps](INFO): SBD MOMSN=15764777
2021-07-27T21:31:49.087Z,1627421509.087 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210727T212725/Courier0000.lzma
2021-07-27T21:31:51.093Z,1627421511.093 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Courier0000.lzma.bak
2021-07-27T21:31:51.093Z,1627421511.093 [DataOverHttps](INFO): SBD MOMSN=15764779
2021-07-27T21:31:59.126Z,1627421519.126 [Startup:StartupSatComms:B](INFO): Timed out from 2021-07-27T21:30:58.9Z
2021-07-27T21:31:59.127Z,1627421519.127 [Startup:StartupSatComms:B] Stopped
2021-07-27T21:31:59.127Z,1627421519.127 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-07-27T21:31:59.127Z,1627421519.127 [Startup:StartupSatComms] Stopped
2021-07-27T21:31:59.131Z,1627421519.131 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-07-27T21:31:59.132Z,1627421519.132 [Startup](INFO): Completed Startup
2021-07-27T21:31:59.132Z,1627421519.132 [MissionManager](INFO): Startup is completed.
2021-07-27T21:31:59.132Z,1627421519.132 [MissionManager](INFO): Uninitializing Mission Startup
2021-07-27T21:31:59.133Z,1627421519.133 [Startup] Stopped
2021-07-27T21:31:59.133Z,1627421519.133 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-07-27T21:31:59.133Z,1627421519.133 [Startup:A.GoToSurface] Stopped
2021-07-27T21:31:59.133Z,1627421519.133 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-27T21:31:59.538Z,1627421519.538 [MissionManager](IMPORTANT): Started mission Default
2021-07-27T21:31:59.538Z,1627421519.538 [Default] Running Loop=1
2021-07-27T21:31:59.538Z,1627421519.538 [Default](DEBUG): Aggregate::initialize Default
2021-07-27T21:31:59.539Z,1627421519.539 [Default:B.GoToSurface] Running Loop=1
2021-07-27T21:31:59.539Z,1627421519.539 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-27T21:31:59.543Z,1627421519.543 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-27T21:31:59.544Z,1627421519.544 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-27T21:31:59.544Z,1627421519.544 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-27T21:31:59.544Z,1627421519.544 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-27T21:31:59.545Z,1627421519.545 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-27T21:31:59.545Z,1627421519.545 [Default:A.Wait] Running Loop=1
2021-07-27T21:31:59.545Z,1627421519.545 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-27T21:32:01.618Z,1627421521.618 [DataOverHttps](IMPORTANT): SBD MTMSN=20210727T213200
2021-07-27T21:32:08.932Z,1627421528.932 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20210727T210334/Express0010.lzma
2021-07-27T21:32:08.967Z,1627421528.967 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 45 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" 1uh56 1 2
2021-07-27T21:32:09.010Z,1627421529.010 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 45 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" 1uh56 1 2.000000
2021-07-27T21:32:09.010Z,1627421529.010 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=1uh56
2021-07-27T21:32:09.012Z,1627421529.012 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='1uh56'): "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 45 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" ASAP
2021-07-27T21:32:10.669Z,1627421530.669 [DataOverHttps](INFO): Moved sent file to Logs/20210727T210334/Express0010.lzma.bak
2021-07-27T21:32:10.670Z,1627421530.670 [DataOverHttps](IMPORTANT): SBD MOMSN=15764783, MTMSN=20210727T213208
2021-07-27T21:32:12.871Z,1627421532.871 [Default:A.Wait](INFO): Done Waiting.
2021-07-27T21:32:12.871Z,1627421532.871 [Default:A.Wait] Stopped
2021-07-27T21:32:12.871Z,1627421532.871 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-27T21:32:13.280Z,1627421533.280 [Default:CheckIn] Running Loop=1
2021-07-27T21:32:13.280Z,1627421533.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-27T21:32:13.280Z,1627421533.280 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-27T21:32:13.663Z,1627421533.663 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-07-27T21:32:17.259Z,1627421537.259 [DataOverHttps](INFO): Received command:sched asap "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" 1uh56 2 2
2021-07-27T21:32:17.325Z,1627421537.325 [CommandLine](IMPORTANT): got command schedule asap "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" 1uh56 2 2.000000
2021-07-27T21:32:17.325Z,1627421537.325 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=1uh56
2021-07-27T21:32:17.326Z,1627421537.326 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='1uh56'): "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" ASAP
2021-07-27T21:32:17.491Z,1627421537.491 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-27T21:32:17.492Z,1627421537.492 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-27T21:32:17.722Z,1627421537.722 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2021-07-27T21:32:17.765Z,1627421537.765 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2021-07-27T21:32:17.784Z,1627421537.784 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2021-07-27T21:32:17.787Z,1627421537.787 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2021-07-27T21:32:17.798Z,1627421537.798 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2021-07-27T21:32:17.822Z,1627421537.822 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2021-07-27T21:32:17.845Z,1627421537.845 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2021-07-27T21:32:17.880Z,1627421537.880 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2021-07-27T21:32:17.904Z,1627421537.904 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2021-07-27T21:32:17.915Z,1627421537.915 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2021-07-27T21:32:17.918Z,1627421537.918 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2021-07-27T21:32:17.922Z,1627421537.922 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2021-07-27T21:32:17.957Z,1627421537.957 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2021-07-27T21:32:17.984Z,1627421537.984 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2021-07-27T21:32:17.984Z,1627421537.984 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-07-27T21:32:18.566Z,1627421538.566 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2021-07-27T21:32:18.607Z,1627421538.607 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2021-07-27T21:32:18.610Z,1627421538.610 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2021-07-27T21:32:18.622Z,1627421538.622 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2021-07-27T21:32:18.645Z,1627421538.645 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2021-07-27T21:32:18.660Z,1627421538.660 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2021-07-27T21:32:18.663Z,1627421538.663 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2021-07-27T21:32:18.682Z,1627421538.682 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2021-07-27T21:32:18.694Z,1627421538.694 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2021-07-27T21:32:18.721Z,1627421538.721 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2021-07-27T21:32:18.740Z,1627421538.740 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2021-07-27T21:32:18.742Z,1627421538.742 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2021-07-27T21:32:18.754Z,1627421538.754 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2021-07-27T21:32:18.781Z,1627421538.781 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-07-27T21:32:18.793Z,1627421538.793 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool
2021-07-27T21:32:18.817Z,1627421538.817 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool
2021-07-27T21:32:18.837Z,1627421538.837 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1.000000 bool
2021-07-27T21:32:18.865Z,1627421538.865 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-07-27T21:32:18.873Z,1627421538.873 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-07-27T21:32:18.897Z,1627421538.897 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-07-27T21:32:18.932Z,1627421538.932 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2021-07-27T21:32:18.935Z,1627421538.935 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2021-07-27T21:32:18.958Z,1627421538.958 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2021-07-27T21:32:18.970Z,1627421538.970 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2021-07-27T21:32:18.993Z,1627421538.993 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-07-27T21:32:19.005Z,1627421539.005 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2021-07-27T21:32:19.033Z,1627421539.033 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2021-07-27T21:32:19.045Z,1627421539.045 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2021-07-27T21:32:19.057Z,1627421539.057 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2021-07-27T21:32:19.060Z,1627421539.060 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2021-07-27T21:32:19.087Z,1627421539.087 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2021-07-27T21:32:19.090Z,1627421539.090 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2021-07-27T21:32:19.093Z,1627421539.093 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2021-07-27T21:32:19.104Z,1627421539.104 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2021-07-27T21:32:19.136Z,1627421539.136 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2021-07-27T21:32:19.139Z,1627421539.139 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2021-07-27T21:32:19.142Z,1627421539.142 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2021-07-27T21:32:19.153Z,1627421539.153 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2021-07-27T21:32:19.157Z,1627421539.157 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2021-07-27T21:32:19.184Z,1627421539.184 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2021-07-27T21:32:19.187Z,1627421539.187 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2021-07-27T21:32:19.190Z,1627421539.190 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2021-07-27T21:32:19.201Z,1627421539.201 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2021-07-27T21:32:19.244Z,1627421539.244 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2021-07-27T21:32:19.247Z,1627421539.247 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2021-07-27T21:32:19.250Z,1627421539.250 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2021-07-27T21:32:19.258Z,1627421539.258 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2021-07-27T21:32:19.261Z,1627421539.261 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2021-07-27T21:32:19.293Z,1627421539.293 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2021-07-27T21:32:19.296Z,1627421539.296 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2021-07-27T21:32:19.332Z,1627421539.332 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2021-07-27T21:32:19.336Z,1627421539.336 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2021-07-27T21:32:19.339Z,1627421539.339 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2021-07-27T21:32:19.366Z,1627421539.366 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2021-07-27T21:32:19.370Z,1627421539.370 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2021-07-27T21:32:19.401Z,1627421539.401 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2021-07-27T21:32:19.404Z,1627421539.404 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2021-07-27T21:32:19.429Z,1627421539.429 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2021-07-27T21:32:19.439Z,1627421539.439 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2021-07-27T21:32:19.454Z,1627421539.454 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2021-07-27T21:32:19.466Z,1627421539.466 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2021-07-27T21:32:19.489Z,1627421539.489 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2021-07-27T21:32:19.501Z,1627421539.501 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2021-07-27T21:32:19.512Z,1627421539.512 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2021-07-27T21:32:19.617Z,1627421539.617 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:32:19.654Z,1627421539.654 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-27T21:32:19.705Z,1627421539.705 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:32:19.796Z,1627421539.796 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:32:19.838Z,1627421539.838 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-27T21:32:19.885Z,1627421539.885 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:32:19.921Z,1627421539.921 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:32:19.958Z,1627421539.958 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-27T21:32:19.994Z,1627421539.994 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2021-07-27T21:32:20.329Z,1627421540.329 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2021-07-27T21:32:20.360Z,1627421540.360 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2021-07-27T21:32:20.363Z,1627421540.363 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2021-07-27T21:32:20.367Z,1627421540.367 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2021-07-27T21:32:20.378Z,1627421540.378 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2021-07-27T21:32:20.381Z,1627421540.381 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2021-07-27T21:32:20.401Z,1627421540.401 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2021-07-27T21:32:20.404Z,1627421540.404 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2021-07-27T21:32:20.423Z,1627421540.423 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2021-07-27T21:32:20.426Z,1627421540.426 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2021-07-27T21:32:20.430Z,1627421540.430 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2021-07-27T21:32:20.457Z,1627421540.457 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 %
2021-07-27T21:32:20.460Z,1627421540.460 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2021-07-27T21:32:20.480Z,1627421540.480 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2021-07-27T21:32:20.590Z,1627421540.590 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2021-07-27T21:32:20.629Z,1627421540.629 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-07-27T21:32:20.633Z,1627421540.633 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2021-07-27T21:32:20.685Z,1627421540.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2021-07-27T21:32:20.698Z,1627421540.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2021-07-27T21:32:20.702Z,1627421540.702 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2021-07-27T21:32:20.737Z,1627421540.737 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-07-27T21:32:20.745Z,1627421540.745 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2021-07-27T21:32:20.757Z,1627421540.757 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2021-07-27T21:32:20.785Z,1627421540.785 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2021-07-27T21:32:20.808Z,1627421540.808 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2021-07-27T21:32:20.865Z,1627421540.865 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2021-07-27T21:32:20.879Z,1627421540.879 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2021-07-27T21:32:20.881Z,1627421540.881 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2021-07-27T21:32:20.883Z,1627421540.883 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2021-07-27T21:32:20.893Z,1627421540.893 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2021-07-27T21:32:20.903Z,1627421540.903 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2021-07-27T21:32:20.912Z,1627421540.912 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2021-07-27T21:32:20.914Z,1627421540.914 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2021-07-27T21:32:21.022Z,1627421541.022 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2021-07-27T21:32:21.034Z,1627421541.034 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2021-07-27T21:32:21.153Z,1627421541.153 [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="ballast_and_trim">

    <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/>

        <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-07-27T21:32:21.153Z,1627421541.153 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-27T21:32:37.861Z,1627421557.861 [CommandLine](IMPORTANT): got command set ballast_and_trim.MissionTimeout 45.000000 minute
2021-07-27T21:32:37.862Z,1627421557.862 [CommandLine](IMPORTANT): got command set ballast_and_trim.Depth1 2.000000 meter
2021-07-27T21:32:37.862Z,1627421557.862 [CommandLine](IMPORTANT): got command set ballast_and_trim.SurfaceThreshold 1.000000 meter
2021-07-27T21:32:37.863Z,1627421557.863 [CommandLine](IMPORTANT): got command set ballast_and_trim.EstimationTimeout 15.000000 minute
2021-07-27T21:32:55.490Z,1627421575.490 [CommandLine](IMPORTANT): got command set ballast_and_trim.MassEstimationErrorBound 1.000000 millimeter
2021-07-27T21:32:55.491Z,1627421575.491 [CommandLine](IMPORTANT): got command set ballast_and_trim.BuoyEstimationErrorBound 25.000000 cubic_centimeter
2021-07-27T21:32:55.492Z,1627421575.492 [CommandLine](IMPORTANT): got command set ballast_and_trim.EstimationConfidence 98.000000 percent
2021-07-27T21:32:55.493Z,1627421575.493 [CommandLine](IMPORTANT): got command run
2021-07-27T21:32:55.496Z,1627421575.496 [CommandLine](IMPORTANT): Running 
2021-07-27T21:32:55.581Z,1627421575.581 [Default] Stopped
2021-07-27T21:32:55.581Z,1627421575.581 [Default](DEBUG): Aggregate::uninitialize Default
2021-07-27T21:32:55.581Z,1627421575.581 [Default:B.GoToSurface] Stopped
2021-07-27T21:32:55.581Z,1627421575.581 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-27T21:32:55.581Z,1627421575.581 [Default:CheckIn] Stopped
2021-07-27T21:32:55.581Z,1627421575.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-27T21:32:55.581Z,1627421575.581 [Default:CheckIn:Read_GPS] Stopped
2021-07-27T21:32:55.581Z,1627421575.581 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim] Running Loop=1
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science] Running Loop=1
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science:A] Running Loop=1
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science:B] Running Loop=1
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science:C] Running Loop=1
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science:D] Running Loop=1
2021-07-27T21:32:55.582Z,1627421575.582 [ballast_and_trim:Science:E] Running Loop=1
2021-07-27T21:32:55.583Z,1627421575.583 [ballast_and_trim:Science:F] Running Loop=1
2021-07-27T21:32:55.583Z,1627421575.583 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2021-07-27T21:32:55.583Z,1627421575.583 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2021-07-27T21:32:55.583Z,1627421575.583 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2021-07-27T21:32:55.583Z,1627421575.583 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:32:55.583Z,1627421575.583 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:32:55.585Z,1627421575.585 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2021-07-27T21:32:55.585Z,1627421575.585 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2021-07-27T21:32:55.586Z,1627421575.586 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-27T21:32:55.586Z,1627421575.586 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-27T21:32:55.586Z,1627421575.586 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2021-07-27T21:32:55.586Z,1627421575.586 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2021-07-27T21:32:55.586Z,1627421575.586 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:32:55.587Z,1627421575.587 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:32:55.589Z,1627421575.589 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2021-07-27T21:32:55.589Z,1627421575.589 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2021-07-27T21:32:55.589Z,1627421575.589 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:32:55.589Z,1627421575.589 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:32:55.596Z,1627421575.596 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2021-07-27T21:32:55.596Z,1627421575.596 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2021-07-27T21:32:55.596Z,1627421575.596 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-27T21:32:55.596Z,1627421575.596 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-27T21:32:55.596Z,1627421575.596 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2021-07-27T21:32:55.597Z,1627421575.597 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2021-07-27T21:32:55.597Z,1627421575.597 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:32:55.597Z,1627421575.597 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:32:55.599Z,1627421575.599 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2021-07-27T21:32:55.603Z,1627421575.603 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2021-07-27T21:32:55.603Z,1627421575.603 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:32:55.604Z,1627421575.604 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:32:55.605Z,1627421575.605 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2021-07-27T21:32:55.605Z,1627421575.605 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2021-07-27T21:32:55.606Z,1627421575.606 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-27T21:32:55.606Z,1627421575.606 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-27T21:32:55.606Z,1627421575.606 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2021-07-27T21:32:55.606Z,1627421575.606 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2021-07-27T21:32:55.606Z,1627421575.606 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-07-27T21:32:55.606Z,1627421575.606 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-07-27T21:32:55.607Z,1627421575.607 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2021-07-27T21:32:55.607Z,1627421575.607 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2021-07-27T21:32:55.611Z,1627421575.611 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2021-07-27T21:32:55.611Z,1627421575.611 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-27T21:32:55.612Z,1627421575.612 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2021-07-27T21:32:55.613Z,1627421575.613 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2021-07-27T21:32:55.613Z,1627421575.613 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2021-07-27T21:32:55.613Z,1627421575.613 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-27T21:32:55.613Z,1627421575.613 [ballast_and_trim:Science] Running Loop=1
2021-07-27T21:32:55.614Z,1627421575.614 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2021-07-27T21:32:55.614Z,1627421575.614 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2021-07-27T21:32:55.614Z,1627421575.614 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-27T21:32:55.614Z,1627421575.614 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-27T21:32:55.615Z,1627421575.615 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2021-07-27T21:32:55.615Z,1627421575.615 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2021-07-27T21:32:55.615Z,1627421575.615 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-07-27T21:32:55.615Z,1627421575.615 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:32:55.619Z,1627421575.619 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2021-07-27T21:32:55.619Z,1627421575.619 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2021-07-27T21:32:55.619Z,1627421575.619 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:PeakDetectOil] Stopped
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:32:55.620Z,1627421575.620 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectChl] Stopped
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-07-27T21:32:55.621Z,1627421575.621 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:32:55.622Z,1627421575.622 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-07-27T21:32:55.622Z,1627421575.622 [ballast_and_trim:Science:Read_Oil] Stopped
2021-07-27T21:32:55.622Z,1627421575.622 [ballast_and_trim:Science:F] Stopped
2021-07-27T21:32:55.622Z,1627421575.622 [ballast_and_trim:Science:E] Running Loop=1
2021-07-27T21:32:55.623Z,1627421575.623 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-07-27T21:32:55.623Z,1627421575.623 [ballast_and_trim:Science:D] Stopped
2021-07-27T21:32:55.626Z,1627421575.626 [ballast_and_trim:Science:C] Running Loop=1
2021-07-27T21:32:55.626Z,1627421575.626 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-07-27T21:32:55.629Z,1627421575.629 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-07-27T21:32:55.629Z,1627421575.629 [ballast_and_trim:Science:B] Running Loop=1
2021-07-27T21:32:55.630Z,1627421575.630 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-07-27T21:32:55.631Z,1627421575.631 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-07-27T21:32:55.631Z,1627421575.631 [ballast_and_trim:Science:A] Stopped
2021-07-27T21:32:55.985Z,1627421575.985 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2021-07-27T21:32:55.997Z,1627421575.997 [WetLabsBB2FL](INFO): Powering up
2021-07-27T21:32:55.999Z,1627421575.999 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-27T21:32:55.999Z,1627421575.999 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-07-27T21:32:55.999Z,1627421575.999 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-07-27T21:32:55.000Z,1627421576.000 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-07-27T21:32:55.000Z,1627421576.000 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-07-27T21:32:55.000Z,1627421576.000 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-07-27T21:32:55.000Z,1627421576.000 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-07-27T21:32:56.002Z,1627421576.002 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2021-07-27T21:32:56.002Z,1627421576.002 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2021-07-27T21:32:56.002Z,1627421576.002 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2021-07-27T21:32:56.003Z,1627421576.003 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-27T21:32:56.003Z,1627421576.003 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-07-27T21:32:56.003Z,1627421576.003 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2021-07-27T21:32:56.003Z,1627421576.003 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-07-27T21:32:56.007Z,1627421576.007 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2021-07-27T21:32:56.007Z,1627421576.007 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2021-07-27T21:32:56.007Z,1627421576.007 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2021-07-27T21:32:56.008Z,1627421576.008 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-07-27T21:32:56.009Z,1627421576.009 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-07-27T21:32:56.009Z,1627421576.009 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-07-27T21:32:56.009Z,1627421576.009 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-07-27T21:32:56.010Z,1627421576.010 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-07-27T21:32:56.387Z,1627421576.387 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2021-07-27T21:32:56.387Z,1627421576.387 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2021-07-27T21:32:56.388Z,1627421576.388 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-07-27T21:32:56.389Z,1627421576.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2021-07-27T21:32:56.389Z,1627421576.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2021-07-27T21:32:56.389Z,1627421576.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-07-27T21:32:56.389Z,1627421576.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2021-07-27T21:32:56.389Z,1627421576.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-07-27T21:32:56.797Z,1627421576.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-07-27T21:32:56.798Z,1627421576.798 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 2.000000 m 
2021-07-27T21:32:56.798Z,1627421576.798 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2021-07-27T21:32:56.798Z,1627421576.798 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2021-07-27T21:32:56.798Z,1627421576.798 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2021-07-27T21:32:59.212Z,1627421579.212 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-07-27T21:33:18.589Z,1627421598.589 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-27T21:33:19.685Z,1627421599.685 [DataOverHttps](IMPORTANT): SBD MTMSN=20210727T213318
2021-07-27T21:33:27.599Z,1627421607.599 [DataOverHttps](INFO): Received command:stop
2021-07-27T21:33:27.656Z,1627421607.656 [CommandLine](IMPORTANT): got command stop
2021-07-27T21:33:27.656Z,1627421607.656 [CommandLine](IMPORTANT): Scheduling is paused
2021-07-27T21:33:27.656Z,1627421607.656 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2021-07-27T21:33:27.928Z,1627421607.928 [MissionManager](INFO): MissionManager is completed.
2021-07-27T21:33:27.929Z,1627421607.929 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim] Stopped
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science] Stopped
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science:B] Stopped
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science:C] Stopped
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science:E] Stopped
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science:OceanCurrent] Stopped
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2021-07-27T21:33:27.929Z,1627421607.929 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2021-07-27T21:33:27.930Z,1627421607.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-27T21:33:27.931Z,1627421607.931 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2021-07-27T21:33:27.932Z,1627421607.932 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-27T21:33:28.298Z,1627421608.298 [MissionManager](IMPORTANT): Started mission Default
2021-07-27T21:33:28.299Z,1627421608.299 [Default] Running Loop=1
2021-07-27T21:33:28.299Z,1627421608.299 [Default](DEBUG): Aggregate::initialize Default
2021-07-27T21:33:28.299Z,1627421608.299 [Default:B.GoToSurface] Running Loop=1
2021-07-27T21:33:28.299Z,1627421608.299 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-27T21:33:28.299Z,1627421608.299 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-27T21:33:28.300Z,1627421608.300 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-27T21:33:28.300Z,1627421608.300 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-27T21:33:28.300Z,1627421608.300 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-27T21:33:28.301Z,1627421608.301 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-27T21:33:28.301Z,1627421608.301 [Default:A.Wait] Running Loop=1
2021-07-27T21:33:28.301Z,1627421608.301 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-27T21:33:28.356Z,1627421608.356 [WetLabsBB2FL](INFO): Powering down
2021-07-27T21:33:36.781Z,1627421616.781 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-07-27T21:33:36.781Z,1627421616.781 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-27T21:33:36.832Z,1627421616.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-27T21:33:37.220Z,1627421617.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-27T21:33:37.220Z,1627421617.220 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-07-27T21:33:41.626Z,1627421621.626 [Default:A.Wait](INFO): Done Waiting.
2021-07-27T21:33:41.626Z,1627421621.626 [Default:A.Wait] Stopped
2021-07-27T21:33:41.626Z,1627421621.626 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-27T21:33:42.029Z,1627421622.029 [Default:CheckIn] Running Loop=1
2021-07-27T21:33:42.030Z,1627421622.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-27T21:33:42.030Z,1627421622.030 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-27T21:35:00.444Z,1627421700.444 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-07-27T21:35:00.448Z,1627421700.448 [BPC1](INFO): Received data from all battery sticks.
2021-07-27T21:35:08.120Z,1627421708.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213507.00,A,3648.16748,N,12147.28446,W,1.147,328.01,270721,,,A*7C
2021-07-27T21:35:08.123Z,1627421708.123 [NAL9602](INFO): GPS fix at 20210727T213507: (36.802791, -121.788074)
2021-07-27T21:35:08.174Z,1627421708.174 [Default:CheckIn:Read_GPS] Stopped
2021-07-27T21:35:08.174Z,1627421708.174 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-27T21:35:08.571Z,1627421708.571 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-27T21:35:19.083Z,1627421719.083 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20210727T212725/Courier0004.lzma
2021-07-27T21:35:20.086Z,1627421720.086 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Courier0004.lzma.bak
2021-07-27T21:35:20.087Z,1627421720.087 [DataOverHttps](IMPORTANT): SBD MOMSN=15764824, MTMSN=20210727T213519
2021-07-27T21:35:29.275Z,1627421729.275 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 45 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" 1uhac 1 2
2021-07-27T21:35:29.361Z,1627421729.361 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 45 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" 1uhac 1 2.000000
2021-07-27T21:35:29.361Z,1627421729.361 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=1uhac
2021-07-27T21:35:29.362Z,1627421729.362 [CommandLine](IMPORTANT): Scheduled #3 (#1 of 2 with id='1uhac'): "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 45 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" ASAP
2021-07-27T21:35:30.996Z,1627421730.996 [DataOverHttps](IMPORTANT): SBD MTMSN=20210727T213529
2021-07-27T21:35:37.732Z,1627421737.732 [DataOverHttps](INFO): Sending 883 bytes from file Logs/20210727T212725/Express0001.lzma
2021-07-27T21:35:37.737Z,1627421737.737 [DataOverHttps](INFO): Received command:sched asap "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" 1uhac 2 2
2021-07-27T21:35:37.764Z,1627421737.764 [CommandLine](IMPORTANT): got command schedule asap "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" 1uhac 2 2.000000
2021-07-27T21:35:37.765Z,1627421737.765 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=1uhac
2021-07-27T21:35:37.766Z,1627421737.766 [CommandLine](IMPORTANT): Scheduled #4 (#2 of 2 with id='1uhac'): "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" ASAP
2021-07-27T21:35:37.845Z,1627421737.845 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-27T21:35:37.846Z,1627421737.846 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-27T21:35:37.908Z,1627421737.908 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2021-07-27T21:35:37.910Z,1627421737.910 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2021-07-27T21:35:37.912Z,1627421737.912 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2021-07-27T21:35:37.914Z,1627421737.914 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2021-07-27T21:35:37.916Z,1627421737.916 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2021-07-27T21:35:37.918Z,1627421737.918 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2021-07-27T21:35:37.921Z,1627421737.921 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2021-07-27T21:35:37.923Z,1627421737.923 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2021-07-27T21:35:37.925Z,1627421737.925 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2021-07-27T21:35:37.927Z,1627421737.927 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2021-07-27T21:35:37.929Z,1627421737.929 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2021-07-27T21:35:37.932Z,1627421737.932 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2021-07-27T21:35:37.934Z,1627421737.934 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2021-07-27T21:35:37.936Z,1627421737.936 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2021-07-27T21:35:37.937Z,1627421737.937 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-07-27T21:35:38.270Z,1627421738.270 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2021-07-27T21:35:38.272Z,1627421738.272 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2021-07-27T21:35:38.274Z,1627421738.274 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2021-07-27T21:35:38.276Z,1627421738.276 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2021-07-27T21:35:38.278Z,1627421738.278 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2021-07-27T21:35:38.280Z,1627421738.280 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2021-07-27T21:35:38.282Z,1627421738.282 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2021-07-27T21:35:38.284Z,1627421738.284 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2021-07-27T21:35:38.286Z,1627421738.286 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2021-07-27T21:35:38.288Z,1627421738.288 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2021-07-27T21:35:38.290Z,1627421738.290 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2021-07-27T21:35:38.292Z,1627421738.292 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2021-07-27T21:35:38.293Z,1627421738.293 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2021-07-27T21:35:38.295Z,1627421738.295 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-07-27T21:35:38.298Z,1627421738.298 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool
2021-07-27T21:35:38.300Z,1627421738.300 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool
2021-07-27T21:35:38.302Z,1627421738.302 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1.000000 bool
2021-07-27T21:35:38.305Z,1627421738.305 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-07-27T21:35:38.307Z,1627421738.307 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-07-27T21:35:38.310Z,1627421738.310 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-07-27T21:35:38.334Z,1627421738.334 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2021-07-27T21:35:38.337Z,1627421738.337 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2021-07-27T21:35:38.339Z,1627421738.339 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2021-07-27T21:35:38.341Z,1627421738.341 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2021-07-27T21:35:38.343Z,1627421738.343 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-07-27T21:35:38.361Z,1627421738.361 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2021-07-27T21:35:38.363Z,1627421738.363 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2021-07-27T21:35:38.366Z,1627421738.366 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2021-07-27T21:35:38.385Z,1627421738.385 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2021-07-27T21:35:38.387Z,1627421738.387 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2021-07-27T21:35:38.389Z,1627421738.389 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2021-07-27T21:35:38.391Z,1627421738.391 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2021-07-27T21:35:38.394Z,1627421738.394 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2021-07-27T21:35:38.446Z,1627421738.446 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2021-07-27T21:35:38.448Z,1627421738.448 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2021-07-27T21:35:38.450Z,1627421738.450 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2021-07-27T21:35:38.469Z,1627421738.469 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2021-07-27T21:35:38.472Z,1627421738.472 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2021-07-27T21:35:38.474Z,1627421738.474 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2021-07-27T21:35:38.504Z,1627421738.504 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2021-07-27T21:35:38.506Z,1627421738.506 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2021-07-27T21:35:38.509Z,1627421738.509 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2021-07-27T21:35:38.527Z,1627421738.527 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2021-07-27T21:35:38.530Z,1627421738.530 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2021-07-27T21:35:38.532Z,1627421738.532 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2021-07-27T21:35:38.534Z,1627421738.534 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2021-07-27T21:35:38.553Z,1627421738.553 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2021-07-27T21:35:38.556Z,1627421738.556 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2021-07-27T21:35:38.558Z,1627421738.558 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2021-07-27T21:35:38.560Z,1627421738.560 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2021-07-27T21:35:38.562Z,1627421738.562 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2021-07-27T21:35:38.580Z,1627421738.580 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2021-07-27T21:35:38.583Z,1627421738.583 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2021-07-27T21:35:38.585Z,1627421738.585 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2021-07-27T21:35:38.603Z,1627421738.603 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2021-07-27T21:35:38.606Z,1627421738.606 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2021-07-27T21:35:38.608Z,1627421738.608 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2021-07-27T21:35:38.610Z,1627421738.610 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2021-07-27T21:35:38.625Z,1627421738.625 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2021-07-27T21:35:38.632Z,1627421738.632 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2021-07-27T21:35:38.634Z,1627421738.634 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2021-07-27T21:35:38.637Z,1627421738.637 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2021-07-27T21:35:38.655Z,1627421738.655 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2021-07-27T21:35:38.658Z,1627421738.658 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2021-07-27T21:35:38.686Z,1627421738.686 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:35:38.710Z,1627421738.710 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-27T21:35:38.765Z,1627421738.765 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:35:38.799Z,1627421738.799 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:35:38.853Z,1627421738.853 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-27T21:35:38.902Z,1627421738.902 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:35:38.938Z,1627421738.938 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-27T21:35:38.989Z,1627421738.989 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-27T21:35:39.029Z,1627421739.029 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2021-07-27T21:35:39.302Z,1627421739.302 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2021-07-27T21:35:39.324Z,1627421739.324 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2021-07-27T21:35:39.327Z,1627421739.327 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2021-07-27T21:35:39.329Z,1627421739.329 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2021-07-27T21:35:39.331Z,1627421739.331 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2021-07-27T21:35:39.349Z,1627421739.349 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2021-07-27T21:35:39.352Z,1627421739.352 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2021-07-27T21:35:39.354Z,1627421739.354 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2021-07-27T21:35:39.357Z,1627421739.357 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Express0001.lzma.bak
2021-07-27T21:35:39.357Z,1627421739.357 [DataOverHttps](INFO): SBD MOMSN=15764828
2021-07-27T21:35:39.380Z,1627421739.380 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2021-07-27T21:35:39.382Z,1627421739.382 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2021-07-27T21:35:39.385Z,1627421739.385 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2021-07-27T21:35:39.388Z,1627421739.388 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 %
2021-07-27T21:35:39.390Z,1627421739.390 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2021-07-27T21:35:39.392Z,1627421739.392 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2021-07-27T21:35:39.417Z,1627421739.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2021-07-27T21:35:39.438Z,1627421739.438 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-07-27T21:35:39.441Z,1627421739.441 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2021-07-27T21:35:39.466Z,1627421739.466 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2021-07-27T21:35:39.470Z,1627421739.470 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2021-07-27T21:35:39.489Z,1627421739.489 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2021-07-27T21:35:39.495Z,1627421739.495 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-07-27T21:35:39.498Z,1627421739.498 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2021-07-27T21:35:39.515Z,1627421739.515 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2021-07-27T21:35:39.520Z,1627421739.520 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2021-07-27T21:35:39.540Z,1627421739.540 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2021-07-27T21:35:39.546Z,1627421739.546 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2021-07-27T21:35:39.547Z,1627421739.547 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2021-07-27T21:35:39.549Z,1627421739.549 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2021-07-27T21:35:39.550Z,1627421739.550 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2021-07-27T21:35:39.568Z,1627421739.568 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2021-07-27T21:35:39.569Z,1627421739.569 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2021-07-27T21:35:39.571Z,1627421739.571 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2021-07-27T21:35:39.572Z,1627421739.572 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2021-07-27T21:35:39.594Z,1627421739.594 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2021-07-27T21:35:39.597Z,1627421739.597 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2021-07-27T21:35:39.716Z,1627421739.716 [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="ballast_and_trim">

    <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/>

        <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-07-27T21:35:39.717Z,1627421739.717 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-27T21:35:40.440Z,1627421740.440 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-27T21:35:53.706Z,1627421753.706 [CommandLine](IMPORTANT): got command set ballast_and_trim.MissionTimeout 45.000000 minute
2021-07-27T21:35:53.707Z,1627421753.707 [CommandLine](IMPORTANT): got command set ballast_and_trim.Depth1 2.000000 meter
2021-07-27T21:35:53.707Z,1627421753.707 [CommandLine](IMPORTANT): got command set ballast_and_trim.SurfaceThreshold 1.000000 meter
2021-07-27T21:35:53.708Z,1627421753.708 [CommandLine](IMPORTANT): got command set ballast_and_trim.EstimationTimeout 15.000000 minute
2021-07-27T21:36:04.179Z,1627421764.179 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210727T212725/Courier0010.lzma
2021-07-27T21:36:05.738Z,1627421765.738 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Courier0010.lzma.bak
2021-07-27T21:36:05.738Z,1627421765.738 [DataOverHttps](INFO): SBD MOMSN=15764852
2021-07-27T21:36:08.408Z,1627421768.408 [CommandLine](IMPORTANT): got command set ballast_and_trim.MassEstimationErrorBound 1.000000 millimeter
2021-07-27T21:36:08.409Z,1627421768.409 [CommandLine](IMPORTANT): got command set ballast_and_trim.BuoyEstimationErrorBound 25.000000 cubic_centimeter
2021-07-27T21:36:08.410Z,1627421768.410 [CommandLine](IMPORTANT): got command set ballast_and_trim.EstimationConfidence 98.000000 percent
2021-07-27T21:36:08.410Z,1627421768.410 [CommandLine](IMPORTANT): got command run
2021-07-27T21:36:08.412Z,1627421768.412 [CommandLine](IMPORTANT): Running 
2021-07-27T21:36:08.733Z,1627421768.733 [Default] Stopped
2021-07-27T21:36:08.734Z,1627421768.734 [Default](DEBUG): Aggregate::uninitialize Default
2021-07-27T21:36:08.734Z,1627421768.734 [Default:B.GoToSurface] Stopped
2021-07-27T21:36:08.734Z,1627421768.734 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-27T21:36:08.735Z,1627421768.735 [Default:CheckIn] Stopped
2021-07-27T21:36:08.735Z,1627421768.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-27T21:36:08.735Z,1627421768.735 [Default:CheckIn:Read_Iridium] Stopped
2021-07-27T21:36:08.735Z,1627421768.735 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2021-07-27T21:36:08.735Z,1627421768.735 [ballast_and_trim] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:A] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:B] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:C] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:D] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:E] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:F] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2021-07-27T21:36:08.736Z,1627421768.736 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2021-07-27T21:36:08.737Z,1627421768.737 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2021-07-27T21:36:08.737Z,1627421768.737 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:36:08.737Z,1627421768.737 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:36:08.738Z,1627421768.738 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2021-07-27T21:36:08.738Z,1627421768.738 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2021-07-27T21:36:08.738Z,1627421768.738 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-27T21:36:08.739Z,1627421768.739 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-27T21:36:08.739Z,1627421768.739 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2021-07-27T21:36:08.739Z,1627421768.739 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2021-07-27T21:36:08.739Z,1627421768.739 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:36:08.739Z,1627421768.739 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:36:08.741Z,1627421768.741 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2021-07-27T21:36:08.741Z,1627421768.741 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2021-07-27T21:36:08.741Z,1627421768.741 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:36:08.741Z,1627421768.741 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:36:08.742Z,1627421768.742 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2021-07-27T21:36:08.742Z,1627421768.742 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2021-07-27T21:36:08.742Z,1627421768.742 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-27T21:36:08.742Z,1627421768.742 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-27T21:36:08.743Z,1627421768.743 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2021-07-27T21:36:08.743Z,1627421768.743 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2021-07-27T21:36:08.743Z,1627421768.743 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:36:08.743Z,1627421768.743 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:36:08.744Z,1627421768.744 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2021-07-27T21:36:08.745Z,1627421768.745 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2021-07-27T21:36:08.745Z,1627421768.745 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-07-27T21:36:08.745Z,1627421768.745 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-27T21:36:08.746Z,1627421768.746 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2021-07-27T21:36:08.746Z,1627421768.746 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2021-07-27T21:36:08.746Z,1627421768.746 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-27T21:36:08.747Z,1627421768.747 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-27T21:36:08.775Z,1627421768.775 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2021-07-27T21:36:08.775Z,1627421768.775 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2021-07-27T21:36:08.775Z,1627421768.775 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-07-27T21:36:08.775Z,1627421768.775 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-07-27T21:36:08.776Z,1627421768.776 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2021-07-27T21:36:08.776Z,1627421768.776 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2021-07-27T21:36:08.776Z,1627421768.776 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2021-07-27T21:36:08.776Z,1627421768.776 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-27T21:36:08.776Z,1627421768.776 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2021-07-27T21:36:08.777Z,1627421768.777 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2021-07-27T21:36:08.777Z,1627421768.777 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2021-07-27T21:36:08.777Z,1627421768.777 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-27T21:36:08.777Z,1627421768.777 [ballast_and_trim:Science] Running Loop=1
2021-07-27T21:36:08.778Z,1627421768.778 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2021-07-27T21:36:08.779Z,1627421768.779 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectOil] Stopped
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:36:08.780Z,1627421768.780 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:PeakDetectChl] Stopped
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2021-07-27T21:36:08.781Z,1627421768.781 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-07-27T21:36:08.782Z,1627421768.782 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-27T21:36:08.782Z,1627421768.782 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-07-27T21:36:08.782Z,1627421768.782 [ballast_and_trim:Science:Read_Oil] Stopped
2021-07-27T21:36:08.782Z,1627421768.782 [ballast_and_trim:Science:F] Stopped
2021-07-27T21:36:08.782Z,1627421768.782 [ballast_and_trim:Science:E] Running Loop=1
2021-07-27T21:36:08.782Z,1627421768.782 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-07-27T21:36:08.783Z,1627421768.783 [ballast_and_trim:Science:D] Stopped
2021-07-27T21:36:08.783Z,1627421768.783 [ballast_and_trim:Science:C] Running Loop=1
2021-07-27T21:36:08.783Z,1627421768.783 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-07-27T21:36:08.784Z,1627421768.784 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-07-27T21:36:08.784Z,1627421768.784 [ballast_and_trim:Science:B] Running Loop=1
2021-07-27T21:36:08.784Z,1627421768.784 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-07-27T21:36:08.785Z,1627421768.785 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-07-27T21:36:08.785Z,1627421768.785 [ballast_and_trim:Science:A] Stopped
2021-07-27T21:36:09.128Z,1627421769.128 [WetLabsBB2FL](INFO): Powering up
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-07-27T21:36:09.156Z,1627421769.156 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-07-27T21:36:09.157Z,1627421769.157 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-07-27T21:36:09.159Z,1627421769.159 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2021-07-27T21:36:09.160Z,1627421769.160 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2021-07-27T21:36:09.161Z,1627421769.161 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-07-27T21:36:09.162Z,1627421769.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-07-27T21:36:09.162Z,1627421769.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-07-27T21:36:09.162Z,1627421769.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-07-27T21:36:09.163Z,1627421769.163 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2021-07-27T21:36:09.562Z,1627421769.562 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2021-07-27T21:36:09.563Z,1627421769.563 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-07-27T21:36:09.948Z,1627421769.948 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-07-27T21:36:09.949Z,1627421769.949 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 2.000000 m 
2021-07-27T21:36:09.949Z,1627421769.949 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2021-07-27T21:36:09.949Z,1627421769.949 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2021-07-27T21:36:09.949Z,1627421769.949 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2021-07-27T21:36:12.020Z,1627421772.020 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-07-27T21:36:29.108Z,1627421789.108 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210727T212725/Courier0013.lzma
2021-07-27T21:36:30.101Z,1627421790.101 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Courier0013.lzma.bak
2021-07-27T21:36:30.101Z,1627421790.101 [DataOverHttps](INFO): SBD MOMSN=15764854
2021-07-27T21:37:37.277Z,1627421857.277 [Power24vConverter](INFO): Powering down.
2021-07-27T21:38:18.336Z,1627421898.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T21:38:49.459Z,1627421929.459 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T21:39:20.612Z,1627421960.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T21:39:33.040Z,1627421973.040 [Radio_Surface](INFO): Powering down
2021-07-27T21:39:51.736Z,1627421991.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T21:39:52.739Z,1627421992.739 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-07-27T21:40:16.491Z,1627422016.491 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2021-07-27T21:40:16.492Z,1627422016.492 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-27T21:40:16.492Z,1627422016.492 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2021-07-27T21:40:16.492Z,1627422016.492 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-27T21:40:16.492Z,1627422016.492 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2021-07-27T21:40:16.492Z,1627422016.492 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-07-27T21:40:16.493Z,1627422016.493 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2021-07-27T21:40:16.494Z,1627422016.494 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-07-27T21:40:16.494Z,1627422016.494 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2021-07-27T21:40:16.494Z,1627422016.494 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2021-07-27T21:40:16.874Z,1627422016.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 2.016085 m 
2021-07-27T21:40:16.874Z,1627422016.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2021-07-27T21:40:16.874Z,1627422016.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2021-07-27T21:40:16.874Z,1627422016.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2021-07-27T21:40:16.874Z,1627422016.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-07-27T21:40:16.875Z,1627422016.875 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-07-27T21:40:26.876Z,1627422026.876 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747147
2021-07-27T21:50:17.664Z,1627422617.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2021-07-27T21:50:17.664Z,1627422617.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2021-07-27T21:50:17.664Z,1627422617.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2021-07-27T21:50:17.665Z,1627422617.665 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2021-07-27T21:50:18.051Z,1627422618.051 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 2.467558 m 
2021-07-27T21:50:18.051Z,1627422618.051 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2021-07-27T21:50:18.051Z,1627422618.051 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2021-07-27T21:50:18.051Z,1627422618.051 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2021-07-27T22:00:18.835Z,1627423218.835 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 10.01 minutes.
2021-07-27T22:00:18.840Z,1627423218.840 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 415.840727 +/- 4.780659 cc (conf. level 98.00%, sigma: 26.071019 cc).
2021-07-27T22:00:18.841Z,1627423218.841 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: -17.079992 +/- 0.096515 mm (conf. level 98.00%, sigma: 0.526336 mm).
2021-07-27T22:00:18.842Z,1627423218.842 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 415.840727 cc buoyancy neutral and -17.079992 mm mass default
2021-07-27T22:00:18.850Z,1627423218.850 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-07-27T22:00:18.872Z,1627423218.872 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-07-27T22:00:18.872Z,1627423218.872 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-07-27T22:00:18.872Z,1627423218.872 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-07-27T22:00:18.872Z,1627423218.872 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.verbosity=3 count;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=415.840727 cubic_centimeter;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kdDepthBuoy=2680 second;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kiDepthBuoy=0.15 reciprocal_second;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kiDepthOff=0.01 meter_per_second;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kpDepthBuoy=62 ratio;

2021-07-27T22:00:18.873Z,1627423218.873 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=-17.079992 millimeter;

2021-07-27T22:00:18.874Z,1627423218.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2021-07-27T22:00:18.874Z,1627423218.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2021-07-27T22:00:18.875Z,1627423218.875 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-07-27T22:00:18.883Z,1627423218.883 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2021-07-27T22:00:18.883Z,1627423218.883 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-07-27T22:00:18.884Z,1627423218.884 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2021-07-27T22:00:18.884Z,1627423218.884 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2021-07-27T22:00:18.884Z,1627423218.884 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2021-07-27T22:00:18.885Z,1627423218.885 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-27T22:00:18.885Z,1627423218.885 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2021-07-27T22:00:18.885Z,1627423218.885 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-27T22:00:18.885Z,1627423218.885 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2021-07-27T22:00:18.885Z,1627423218.885 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2021-07-27T22:00:18.885Z,1627423218.885 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2021-07-27T22:00:18.886Z,1627423218.886 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2021-07-27T22:00:18.886Z,1627423218.886 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2021-07-27T22:00:19.229Z,1627423219.229 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-27T22:00:19.229Z,1627423219.229 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2021-07-27T22:00:19.229Z,1627423219.229 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-27T22:00:19.229Z,1627423219.229 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-27T22:00:19.229Z,1627423219.229 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2021-07-27T22:00:19.230Z,1627423219.230 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-27T22:00:19.230Z,1627423219.230 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2021-07-27T22:00:19.230Z,1627423219.230 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2021-07-27T22:00:19.230Z,1627423219.230 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2021-07-27T22:00:19.653Z,1627423219.653 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2021-07-27T22:00:19.653Z,1627423219.653 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2021-07-27T22:00:19.653Z,1627423219.653 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:RunBallastAndTrim] Stopped
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:Float_Up] Running Loop=1
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-07-27T22:00:19.654Z,1627423219.654 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2021-07-27T22:00:19.655Z,1627423219.655 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2021-07-27T22:00:20.014Z,1627423220.014 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2021-07-27T22:00:50.713Z,1627423250.713 [ballast_and_trim:Float_Up] Stopped
2021-07-27T22:00:50.713Z,1627423250.713 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2021-07-27T22:00:50.713Z,1627423250.713 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2021-07-27T22:00:50.713Z,1627423250.713 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-07-27T22:00:50.713Z,1627423250.713 [ballast_and_trim:Float_Up:B.Wait] Stopped
2021-07-27T22:00:50.713Z,1627423250.713 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2021-07-27T22:00:50.715Z,1627423250.715 [ballast_and_trim](INFO): Completed ballast_and_trim
2021-07-27T22:00:50.715Z,1627423250.715 [MissionManager](INFO): ballast_and_trim is completed.
2021-07-27T22:00:50.715Z,1627423250.715 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2021-07-27T22:00:50.715Z,1627423250.715 [ballast_and_trim] Stopped
2021-07-27T22:00:50.715Z,1627423250.716 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science] Stopped
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science:B] Stopped
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science:C] Stopped
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science:E] Stopped
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science:OceanCurrent] Stopped
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2021-07-27T22:00:50.716Z,1627423250.716 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2021-07-27T22:00:51.055Z,1627423251.055 [WetLabsBB2FL](INFO): Powering down
2021-07-27T22:00:51.144Z,1627423251.144 [MissionManager](IMPORTANT): Started mission Default
2021-07-27T22:00:51.144Z,1627423251.144 [Default] Running Loop=1
2021-07-27T22:00:51.144Z,1627423251.144 [Default](DEBUG): Aggregate::initialize Default
2021-07-27T22:00:51.144Z,1627423251.144 [Default:B.GoToSurface] Running Loop=1
2021-07-27T22:00:51.145Z,1627423251.145 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-27T22:00:51.145Z,1627423251.145 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-27T22:00:51.145Z,1627423251.145 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-27T22:00:51.146Z,1627423251.146 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-27T22:00:51.146Z,1627423251.146 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-27T22:00:51.146Z,1627423251.146 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-27T22:00:51.147Z,1627423251.147 [Default:A.Wait] Running Loop=1
2021-07-27T22:00:51.147Z,1627423251.147 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-27T22:00:51.351Z,1627423251.351 [Radio_Surface](INFO): Powering up
2021-07-27T22:00:57.080Z,1627423257.080 [DataOverHttps](INFO): Radio surface powered ON.
2021-07-27T22:01:04.464Z,1627423264.464 [Default:A.Wait](INFO): Done Waiting.
2021-07-27T22:01:04.464Z,1627423264.464 [Default:A.Wait] Stopped
2021-07-27T22:01:04.464Z,1627423264.464 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-27T22:01:04.856Z,1627423264.856 [Default:CheckIn] Running Loop=1
2021-07-27T22:01:04.856Z,1627423264.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-27T22:01:04.856Z,1627423264.856 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-27T22:01:27.216Z,1627423287.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T22:01:37.574Z,1627423297.574 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-27T22:01:37.647Z,1627423297.647 [NAL9602](ERROR): received: 
+CSQ:0

OK553, 2, 0, 0, 0

OK
2021-07-27T22:01:58.340Z,1627423318.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T22:02:29.484Z,1627423349.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T22:03:00.608Z,1627423380.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T22:03:31.732Z,1627423411.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T22:04:02.876Z,1627423442.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-27T22:04:34.264Z,1627423474.264 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003189
2021-07-27T22:06:05.087Z,1627423565.087 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-27T22:01:04.9Z
2021-07-27T22:06:05.087Z,1627423565.087 [Default:CheckIn:Read_GPS] Stopped
2021-07-27T22:06:05.087Z,1627423565.087 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-27T22:06:09.540Z,1627423569.540 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-27T22:06:13.543Z,1627423573.543 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210727T212725/Courier0016.lzma
2021-07-27T22:06:14.545Z,1627423574.545 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Courier0016.lzma.bak
2021-07-27T22:06:14.545Z,1627423574.545 [DataOverHttps](INFO): SBD MOMSN=15764866
2021-07-27T22:06:39.984Z,1627423599.984 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-27T22:06:40.158Z,1627423600.158 [DataOverHttps](INFO): Sending 1081 bytes from file Logs/20210727T212725/Express0005.lzma
2021-07-27T22:06:41.157Z,1627423601.157 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Express0005.lzma.bak
2021-07-27T22:06:41.157Z,1627423601.157 [DataOverHttps](INFO): SBD MOMSN=15764871
2021-07-27T22:07:00.627Z,1627423620.627 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210727T212725/Express0008.lzma
2021-07-27T22:07:01.629Z,1627423621.629 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Express0008.lzma.bak
2021-07-27T22:07:01.629Z,1627423621.629 [DataOverHttps](INFO): SBD MOMSN=15764939
2021-07-27T22:07:19.995Z,1627423639.995 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20210727T212725/Express0011.lzma
2021-07-27T22:07:20.997Z,1627423640.997 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Express0011.lzma.bak
2021-07-27T22:07:20.997Z,1627423640.997 [DataOverHttps](INFO): SBD MOMSN=15764941
2021-07-27T22:07:40.547Z,1627423660.547 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20210727T212725/Express0014.lzma
2021-07-27T22:07:41.549Z,1627423661.549 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Express0014.lzma.bak
2021-07-27T22:07:41.549Z,1627423661.549 [DataOverHttps](INFO): SBD MOMSN=15764947
2021-07-27T22:07:59.664Z,1627423679.664 [DataOverHttps](INFO): Sending 895 bytes from file Logs/20210727T212725/Express0017.lzma
2021-07-27T22:08:00.665Z,1627423680.665 [DataOverHttps](INFO): Moved sent file to Logs/20210727T212725/Express0017.lzma.bak
2021-07-27T22:08:00.665Z,1627423680.665 [DataOverHttps](INFO): SBD MOMSN=15764954
2021-07-27T22:08:04.059Z,1627423684.059 [Default:CheckIn:Read_Iridium] Stopped
2021-07-27T22:08:04.060Z,1627423684.060 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-27T22:08:04.060Z,1627423684.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-27T22:11:54.789Z,1627423914.789 [DataOverHttps](IMPORTANT): SBD MTMSN=20210727T221153
2021-07-27T22:12:02.195Z,1627423922.195 [DataOverHttps](INFO): Received command:restart logs