2018-07-13T00:10:47.594Z,1531440647.594 [Supervisor](DEBUG): Initializing supervisor.
2018-07-13T00:10:47.597Z,1531440647.597 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-13T00:10:47.597Z,1531440647.597 [SyncHandler](INFO): Protected caller Thread ID is 982
2018-07-13T00:10:47.598Z,1531440647.598 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-13T00:10:47.599Z,1531440647.599 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-13T00:10:47.599Z,1531440647.599 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 983
2018-07-13T00:10:47.602Z,1531440647.602 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-13T00:10:47.614Z,1531440647.614 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-13T00:10:47.615Z,1531440647.615 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-13T00:10:47.615Z,1531440647.615 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 984
2018-07-13T00:10:47.616Z,1531440647.616 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-13T00:10:47.617Z,1531440647.617 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-13T00:10:47.617Z,1531440647.617 [logger ThreadHandler](INFO): Protected caller Thread ID is 985
2018-07-13T00:10:47.619Z,1531440647.619 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-13T00:10:47.620Z,1531440647.620 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-13T00:10:47.621Z,1531440647.621 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-13T00:10:47.830Z,1531440647.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-13T00:10:47.831Z,1531440647.831 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-13T00:10:47.979Z,1531440647.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-13T00:10:47.980Z,1531440647.980 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-13T00:10:48.357Z,1531440648.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-13T00:10:48.358Z,1531440648.358 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-13T00:10:48.502Z,1531440648.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-13T00:10:48.502Z,1531440648.502 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-13T00:10:48.584Z,1531440648.584 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-13T00:10:48.799Z,1531440648.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-13T00:10:48.800Z,1531440648.800 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-13T00:10:48.946Z,1531440648.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-13T00:10:48.947Z,1531440648.947 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-13T00:10:49.308Z,1531440649.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-13T00:10:49.309Z,1531440649.309 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-13T00:10:49.777Z,1531440649.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-13T00:10:49.777Z,1531440649.777 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-13T00:10:50.106Z,1531440650.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-13T00:10:50.107Z,1531440650.107 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-13T00:10:50.642Z,1531440650.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-13T00:10:50.642Z,1531440650.642 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-13T00:10:50.869Z,1531440650.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-13T00:10:50.869Z,1531440650.869 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-13T00:10:50.970Z,1531440650.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-13T00:10:50.971Z,1531440650.971 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-13T00:10:51.484Z,1531440651.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-13T00:10:51.484Z,1531440651.484 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-13T00:10:51.595Z,1531440651.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-13T00:10:51.597Z,1531440651.597 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-13T00:10:51.598Z,1531440651.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-13T00:10:51.835Z,1531440651.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-13T00:10:51.835Z,1531440651.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-13T00:10:51.937Z,1531440651.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-13T00:10:52.034Z,1531440652.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-13T00:10:52.141Z,1531440652.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-13T00:10:52.228Z,1531440652.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-13T00:10:52.368Z,1531440652.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-13T00:10:52.547Z,1531440652.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-13T00:10:52.661Z,1531440652.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-13T00:10:52.747Z,1531440652.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-13T00:10:52.832Z,1531440652.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-13T00:10:53.038Z,1531440653.038 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-13T00:10:53.042Z,1531440653.042 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-13T00:10:53.169Z,1531440653.169 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-13T00:10:53.199Z,1531440653.199 [SBIT] Loaded
2018-07-13T00:10:53.200Z,1531440653.200 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-13T00:10:53.200Z,1531440653.200 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-13T00:10:53.229Z,1531440653.229 [IBIT] Loaded
2018-07-13T00:10:53.229Z,1531440653.229 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-13T00:10:53.232Z,1531440653.232 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-13T00:10:53.391Z,1531440653.391 [CBIT] Loaded
2018-07-13T00:10:53.392Z,1531440653.392 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-13T00:10:53.392Z,1531440653.392 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-13T00:10:53.393Z,1531440653.393 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-13T00:10:53.459Z,1531440653.459 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-13T00:10:53.560Z,1531440653.560 [VerticalControl] Loaded
2018-07-13T00:10:53.561Z,1531440653.561 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-13T00:10:53.561Z,1531440653.561 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-13T00:10:53.624Z,1531440653.624 [HorizontalControl] Loaded
2018-07-13T00:10:53.624Z,1531440653.624 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-13T00:10:53.625Z,1531440653.625 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-13T00:10:53.630Z,1531440653.630 [SpeedControl] Loaded
2018-07-13T00:10:53.631Z,1531440653.631 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-13T00:10:53.631Z,1531440653.631 [LoopControl](DEBUG): Construct LoopControl.
2018-07-13T00:10:53.632Z,1531440653.632 [LoopControl] Loaded
2018-07-13T00:10:53.632Z,1531440653.632 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-13T00:10:53.633Z,1531440653.633 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-13T00:10:53.633Z,1531440653.633 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-13T00:10:53.674Z,1531440653.674 [DepthRateCalculator] Loaded
2018-07-13T00:10:53.674Z,1531440653.674 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-13T00:10:53.680Z,1531440653.680 [PitchRateCalculator] Loaded
2018-07-13T00:10:53.680Z,1531440653.680 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-13T00:10:53.697Z,1531440653.697 [SpeedCalculator] Loaded
2018-07-13T00:10:53.697Z,1531440653.697 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-13T00:10:53.719Z,1531440653.719 [TempGradientCalculator] Loaded
2018-07-13T00:10:53.719Z,1531440653.719 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-13T00:10:53.735Z,1531440653.735 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-13T00:10:53.735Z,1531440653.735 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-13T00:10:53.740Z,1531440653.740 [YawRateCalculator] Loaded
2018-07-13T00:10:53.740Z,1531440653.740 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-13T00:10:53.770Z,1531440653.770 [ElevatorOffsetCalculator] Loaded
2018-07-13T00:10:53.771Z,1531440653.771 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-13T00:10:53.771Z,1531440653.771 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-13T00:10:53.772Z,1531440653.772 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-13T00:10:53.855Z,1531440653.855 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-13T00:10:53.855Z,1531440653.855 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-13T00:10:53.855Z,1531440653.855 [StratificationFrontDetector] Loaded
2018-07-13T00:10:53.856Z,1531440653.856 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-13T00:10:53.856Z,1531440653.856 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-13T00:10:53.857Z,1531440653.857 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-13T00:10:54.098Z,1531440654.098 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-13T00:10:54.099Z,1531440654.099 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-13T00:10:54.247Z,1531440654.247 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-13T00:10:54.247Z,1531440654.247 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-13T00:10:54.303Z,1531440654.303 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-13T00:10:54.303Z,1531440654.303 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-13T00:10:54.320Z,1531440654.320 [NavChart] Loaded
2018-07-13T00:10:54.321Z,1531440654.321 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-13T00:10:54.325Z,1531440654.325 [UniversalFixResidualReporter] Loaded
2018-07-13T00:10:54.325Z,1531440654.325 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-13T00:10:54.325Z,1531440654.325 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-13T00:10:54.326Z,1531440654.326 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-13T00:10:54.339Z,1531440654.339 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-13T00:10:54.339Z,1531440654.339 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-13T00:10:54.555Z,1531440654.555 [CTD_NeilBrown] Loaded
2018-07-13T00:10:54.556Z,1531440654.556 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-07-13T00:10:54.557Z,1531440654.557 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0
2018-07-13T00:10:54.557Z,1531440654.557 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1064
2018-07-13T00:10:54.572Z,1531440654.572 [PAR_Licor] Loaded
2018-07-13T00:10:54.572Z,1531440654.572 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-07-13T00:10:54.623Z,1531440654.623 [WetLabsBB2FL] Loaded
2018-07-13T00:10:54.623Z,1531440654.623 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-13T00:10:54.624Z,1531440654.624 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407024E0
2018-07-13T00:10:54.624Z,1531440654.624 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1065
2018-07-13T00:10:54.669Z,1531440654.669 [WetLabsSeaOWL_UV_A] Loaded
2018-07-13T00:10:54.670Z,1531440654.670 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-07-13T00:10:54.671Z,1531440654.671 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407324E0
2018-07-13T00:10:54.671Z,1531440654.671 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1066
2018-07-13T00:10:54.671Z,1531440654.671 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-13T00:10:54.672Z,1531440654.672 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-13T00:10:54.959Z,1531440654.959 [AHRS_M2] Loaded
2018-07-13T00:10:54.960Z,1531440654.960 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-13T00:10:55.030Z,1531440655.030 [DataOverHttps] Loaded
2018-07-13T00:10:55.030Z,1531440655.030 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-13T00:10:55.043Z,1531440655.043 [Depth_Keller] Loaded
2018-07-13T00:10:55.044Z,1531440655.044 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-13T00:10:55.049Z,1531440655.049 [DropWeight] Loaded
2018-07-13T00:10:55.049Z,1531440655.049 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-13T00:10:55.142Z,1531440655.142 [NAL9602] Loaded
2018-07-13T00:10:55.142Z,1531440655.142 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-13T00:10:55.147Z,1531440655.147 [Onboard] Loaded
2018-07-13T00:10:55.148Z,1531440655.148 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-13T00:10:55.155Z,1531440655.155 [Radio_Surface] Loaded
2018-07-13T00:10:55.155Z,1531440655.155 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-13T00:10:55.156Z,1531440655.156 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409884E0
2018-07-13T00:10:55.157Z,1531440655.157 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1067
2018-07-13T00:10:55.185Z,1531440655.185 [RDI_Pathfinder] Loaded
2018-07-13T00:10:55.186Z,1531440655.186 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-13T00:10:55.192Z,1531440655.192 [SCPI] Loaded
2018-07-13T00:10:55.192Z,1531440655.192 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-13T00:10:57.014Z,1531440657.014 [BPC1] Loaded
2018-07-13T00:10:57.014Z,1531440657.014 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-13T00:10:57.015Z,1531440657.015 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-13T00:10:57.015Z,1531440657.015 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-13T00:10:57.113Z,1531440657.113 [BuoyancyServo] Loaded
2018-07-13T00:10:57.113Z,1531440657.113 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-13T00:10:57.125Z,1531440657.125 [ElevatorServo] Loaded
2018-07-13T00:10:57.126Z,1531440657.126 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-13T00:10:57.137Z,1531440657.137 [MassServo] Loaded
2018-07-13T00:10:57.137Z,1531440657.137 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-13T00:10:57.149Z,1531440657.149 [RudderServo] Loaded
2018-07-13T00:10:57.149Z,1531440657.149 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-13T00:10:57.160Z,1531440657.160 [ThrusterServo] Loaded
2018-07-13T00:10:57.161Z,1531440657.161 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-13T00:10:57.161Z,1531440657.161 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-13T00:10:57.162Z,1531440657.162 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-13T00:10:57.261Z,1531440657.261 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-13T00:10:57.262Z,1531440657.262 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-13T00:10:57.286Z,1531440657.286 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-13T00:10:57.290Z,1531440657.290 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-13T00:10:57.291Z,1531440657.291 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-13T00:10:57.297Z,1531440657.297 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-13T00:10:57.299Z,1531440657.299 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2018-07-13T00:10:57.299Z,1531440657.299 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1068
2018-07-13T00:10:57.304Z,1531440657.304 [Supervisor](INFO): Main Thread ID is 796
2018-07-13T00:10:57.304Z,1531440657.304 [Supervisor](DEBUG): Running supervisor.
2018-07-13T00:10:57.304Z,1531440657.304 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1069
2018-07-13T00:10:57.307Z,1531440657.307 [controlThread ThreadHandler](INFO): Handler Thread ID is 1070
2018-07-13T00:10:57.307Z,1531440657.307 [controlThread](DEBUG): Initializing ControlThread
2018-07-13T00:10:57.308Z,1531440657.308 [SBIT](INFO): Initialize SBIT Component.
2018-07-13T00:10:57.308Z,1531440657.308 [SBIT](IMPORTANT): git: 2018-06-03-17-gbdf8474
2018-07-13T00:10:57.309Z,1531440657.309 [SBIT](INFO): git hash: bdf847407d9faffe74fd7e88a9e899f3511e6e11
2018-07-13T00:10:57.309Z,1531440657.309 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-13T00:10:57.311Z,1531440657.311 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-13T00:10:57.312Z,1531440657.312 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-07-13T00:10:57.312Z,1531440657.312 [IBIT](INFO): Initialize IBIT Component.
2018-07-13T00:10:57.313Z,1531440657.313 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-13T00:10:57.313Z,1531440657.313 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-07-13T00:10:57.314Z,1531440657.314 [logger ThreadHandler](INFO): Handler Thread ID is 1071
2018-07-13T00:10:57.341Z,1531440657.341 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1072
2018-07-13T00:10:57.342Z,1531440657.342 [CTD_NeilBrown](INFO): Powering down
2018-07-13T00:10:57.375Z,1531440657.375 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1073
2018-07-13T00:10:57.385Z,1531440657.385 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1074
2018-07-13T00:10:57.387Z,1531440657.387 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-07-13T00:10:57.415Z,1531440657.415 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1075
2018-07-13T00:10:57.420Z,1531440657.420 [Radio_Surface](INFO): Powering up
2018-07-13T00:10:57.421Z,1531440657.421 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-13T00:10:57.423Z,1531440657.423 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-13T00:10:57.424Z,1531440657.424 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-13T00:10:57.424Z,1531440657.424 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-13T00:10:57.425Z,1531440657.425 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-13T00:10:57.425Z,1531440657.425 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-13T00:10:57.425Z,1531440657.425 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-13T00:10:57.426Z,1531440657.426 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-13T00:10:57.426Z,1531440657.426 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-13T00:10:57.427Z,1531440657.427 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-13T00:10:57.428Z,1531440657.428 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-13T00:10:57.428Z,1531440657.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-13T00:10:57.429Z,1531440657.429 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-13T00:10:57.430Z,1531440657.430 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-13T00:10:57.430Z,1531440657.430 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-13T00:10:57.431Z,1531440657.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-13T00:10:57.431Z,1531440657.431 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-13T00:10:57.432Z,1531440657.432 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-13T00:10:57.432Z,1531440657.432 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-13T00:10:57.443Z,1531440657.443 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-13T00:10:57.457Z,1531440657.457 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1076
2018-07-13T00:10:57.460Z,1531440657.460 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-13T00:10:57.461Z,1531440657.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-13T00:10:57.461Z,1531440657.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-13T00:10:57.461Z,1531440657.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-13T00:10:57.462Z,1531440657.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-13T00:10:57.462Z,1531440657.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-13T00:10:57.462Z,1531440657.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-13T00:10:57.462Z,1531440657.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-13T00:10:57.462Z,1531440657.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-13T00:10:57.463Z,1531440657.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-13T00:10:57.463Z,1531440657.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-13T00:10:57.463Z,1531440657.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-13T00:10:57.463Z,1531440657.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-13T00:10:57.463Z,1531440657.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-13T00:10:57.464Z,1531440657.464 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-13T00:10:57.464Z,1531440657.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-13T00:10:57.495Z,1531440657.495 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-13T00:10:57.531Z,1531440657.531 [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>


2018-07-13T00:10:57.531Z,1531440657.531 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-13T00:10:57.620Z,1531440657.620 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-13T00:10:57.621Z,1531440657.621 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-13T00:10:57.623Z,1531440657.623 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-13T00:10:57.674Z,1531440657.674 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-13T00:10:57.676Z,1531440657.676 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-13T00:10:57.702Z,1531440657.702 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-13T00:10:57.706Z,1531440657.706 [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 300</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>


2018-07-13T00:10:57.712Z,1531440657.712 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-13T00:10:57.798Z,1531440657.798 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-13T00:10:57.878Z,1531440657.878 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-13T00:10:58.325Z,1531440658.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:58.353Z,1531440658.353 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:58.461Z,1531440658.461 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:10:58.469Z,1531440658.469 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-13T00:10:58.491Z,1531440658.491 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:10:58.497Z,1531440658.497 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-13T00:10:58.511Z,1531440658.511 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:10:58.517Z,1531440658.517 [MassServo](DEBUG): Initializing MassServo.
2018-07-13T00:10:58.523Z,1531440658.523 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:10:58.529Z,1531440658.529 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-13T00:10:58.543Z,1531440658.543 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:10:58.549Z,1531440658.549 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-13T00:10:58.586Z,1531440658.586 [CommandLine](FAULT): Scheduling is paused
2018-07-13T00:10:58.586Z,1531440658.586 [CBIT](INFO): Critical error at 20180713T001057
2018-07-13T00:10:58.586Z,1531440658.586 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-07-13T00:10:58.765Z,1531440658.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:58.766Z,1531440658.766 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:59.369Z,1531440659.369 [AHRS_M2](ERROR): Stop data-stream UART error: serial timeout
2018-07-13T00:10:59.414Z,1531440659.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:59.415Z,1531440659.415 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:59.820Z,1531440659.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:10:59.833Z,1531440659.833 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:00.181Z,1531440660.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:00.182Z,1531440660.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:00.585Z,1531440660.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:00.586Z,1531440660.586 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:00.989Z,1531440660.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:00.990Z,1531440660.990 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:01.418Z,1531440661.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:01.419Z,1531440661.419 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:01.809Z,1531440661.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:01.810Z,1531440661.810 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:02.210Z,1531440662.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:02.211Z,1531440662.211 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:02.633Z,1531440662.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:02.635Z,1531440662.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:03.101Z,1531440663.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:03.102Z,1531440663.102 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:03.491Z,1531440663.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:03.492Z,1531440663.492 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:03.849Z,1531440663.849 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:03.851Z,1531440663.851 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:04.258Z,1531440664.258 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:04.259Z,1531440664.259 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:04.638Z,1531440664.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:04.639Z,1531440664.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:05.074Z,1531440665.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:05.075Z,1531440665.075 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:05.462Z,1531440665.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:05.463Z,1531440665.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:05.886Z,1531440665.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:05.887Z,1531440665.887 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:06.286Z,1531440666.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:06.287Z,1531440666.287 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:06.678Z,1531440666.678 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:06.679Z,1531440666.679 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:07.074Z,1531440667.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:07.075Z,1531440667.075 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:07.478Z,1531440667.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:07.480Z,1531440667.480 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:07.838Z,1531440667.838 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:07.839Z,1531440667.839 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:08.250Z,1531440668.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:08.251Z,1531440668.251 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:08.638Z,1531440668.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:08.639Z,1531440668.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:09.038Z,1531440669.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:09.039Z,1531440669.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:09.438Z,1531440669.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:09.439Z,1531440669.439 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:09.838Z,1531440669.838 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:09.839Z,1531440669.839 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:10.258Z,1531440670.258 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:10.259Z,1531440670.259 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:10.658Z,1531440670.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:10.659Z,1531440670.659 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:11.078Z,1531440671.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:11.079Z,1531440671.079 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-13T00:11:25.419Z,1531440685.419 [NAL9602](INFO): Powering up NAL9602
2018-07-13T00:11:36.219Z,1531440696.219 [NAL9602](INFO): NAL9602 initialized
2018-07-13T00:12:00.704Z,1531440720.704 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-13T00:12:00.738Z,1531440720.738 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-13T00:12:11.695Z,1531440731.695 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013487
CHAN A1 (24V): -0.017746
CHAN A2 (12V): -0.007495
CHAN A3 (5V): -0.003722
CHAN B0 (3.3V): -0.000601
CHAN B1 (3.15aV): -0.000008
CHAN B2 (3.15bV): 0.000237
CHAN B3 (GND): 0.001117
OPEN: 0.009629
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-13T00:12:54.801Z,1531440774.801 [SBIT](IMPORTANT): SBIT PASSED
2018-07-13T00:12:54.851Z,1531440774.851 [CommandLine](IMPORTANT): got command configSet list
2018-07-13T00:12:54.851Z,1531440774.851 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-13T00:12:54.852Z,1531440774.852 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-07-13T00:12:54.852Z,1531440774.852 [CommandLine](IMPORTANT): BuoyancyServo.limitHi=9600 count;

2018-07-13T00:12:55.167Z,1531440775.167 [MissionManager](IMPORTANT): Started mission Startup
2018-07-13T00:12:55.167Z,1531440775.167 [Startup] Running Loop=1
2018-07-13T00:12:55.167Z,1531440775.167 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-13T00:12:55.168Z,1531440775.168 [Startup:A.GoToSurface] Running Loop=1
2018-07-13T00:12:55.168Z,1531440775.168 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-13T00:12:55.168Z,1531440775.168 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-13T00:12:55.169Z,1531440775.169 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-13T00:12:55.169Z,1531440775.169 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-13T00:12:55.170Z,1531440775.170 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-13T00:12:55.170Z,1531440775.170 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-13T00:12:55.200Z,1531440775.200 [Startup:StartupSatComms] Running Loop=1
2018-07-13T00:12:55.200Z,1531440775.200 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-13T00:12:55.200Z,1531440775.200 [Startup:StartupSatComms:A] Running Loop=1
2018-07-13T00:12:55.615Z,1531440775.615 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-13T00:13:55.593Z,1531440835.593 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-13T00:12:55.2Z
2018-07-13T00:13:55.593Z,1531440835.593 [Startup:StartupSatComms:A] Stopped
2018-07-13T00:13:55.593Z,1531440835.593 [Startup:StartupSatComms:B] Running Loop=1
2018-07-13T00:13:55.955Z,1531440835.955 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-13T00:13:57.632Z,1531440837.632 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-13T00:13:57.632Z,1531440837.632 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-07-13T00:13:57.632Z,1531440837.632 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-13T00:13:57.634Z,1531440837.634 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-13T00:13:57.634Z,1531440837.634 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-13T00:13:57.634Z,1531440837.634 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-13T00:13:57.667Z,1531440837.667 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-13T00:13:57.667Z,1531440837.667 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-13T00:13:57.979Z,1531440837.979 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-13T00:13:57.979Z,1531440837.979 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-07-13T00:13:57.980Z,1531440837.980 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-13T00:13:57.980Z,1531440837.980 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-13T00:13:58.365Z,1531440838.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-13T00:13:58.366Z,1531440838.366 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-13T00:13:58.366Z,1531440838.366 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-13T00:13:58.366Z,1531440838.366 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-13T00:13:58.367Z,1531440838.367 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-13T00:13:58.367Z,1531440838.367 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-13T00:14:55.595Z,1531440895.595 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-13T00:13:55.6Z
2018-07-13T00:14:55.595Z,1531440895.595 [Startup:StartupSatComms:B] Stopped
2018-07-13T00:14:55.595Z,1531440895.595 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-13T00:14:55.595Z,1531440895.595 [Startup:StartupSatComms] Stopped
2018-07-13T00:14:55.595Z,1531440895.595 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-13T00:14:55.596Z,1531440895.596 [Startup](INFO): Completed Startup
2018-07-13T00:14:55.597Z,1531440895.597 [MissionManager](INFO): Startup is completed.
2018-07-13T00:14:55.597Z,1531440895.597 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-13T00:14:55.597Z,1531440895.597 [Startup] Stopped
2018-07-13T00:14:55.597Z,1531440895.597 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-13T00:14:55.597Z,1531440895.597 [Startup:A.GoToSurface] Stopped
2018-07-13T00:14:55.597Z,1531440895.597 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-13T00:14:55.970Z,1531440895.970 [MissionManager](IMPORTANT): Started mission Default
2018-07-13T00:14:55.970Z,1531440895.970 [Default] Running Loop=1
2018-07-13T00:14:55.970Z,1531440895.970 [Default](DEBUG): Aggregate::initialize Default
2018-07-13T00:14:55.970Z,1531440895.970 [Default:B.GoToSurface] Running Loop=1
2018-07-13T00:14:55.970Z,1531440895.970 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-13T00:14:55.971Z,1531440895.971 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-13T00:14:55.971Z,1531440895.971 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-13T00:14:55.971Z,1531440895.971 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-13T00:14:55.972Z,1531440895.972 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-13T00:14:55.972Z,1531440895.972 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-13T00:14:55.972Z,1531440895.972 [Default:A.Wait] Running Loop=1
2018-07-13T00:14:55.972Z,1531440895.972 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-13T00:15:02.883Z,1531440902.883 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005447
2018-07-13T00:15:09.315Z,1531440909.315 [Default:A.Wait](INFO): Done Waiting.
2018-07-13T00:15:09.316Z,1531440909.316 [Default:A.Wait] Stopped
2018-07-13T00:15:09.316Z,1531440909.316 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-13T00:15:09.723Z,1531440909.723 [Default:CheckIn] Running Loop=1
2018-07-13T00:15:09.723Z,1531440909.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-13T00:15:09.723Z,1531440909.723 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-13T00:15:10.120Z,1531440910.120 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-13T00:16:33.136Z,1531440993.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:16:33.517Z,1531440993.517 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:16:33.932Z,1531440993.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:16:34.327Z,1531440994.327 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:16:34.732Z,1531440994.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:16:35.124Z,1531440995.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:16:35.556Z,1531440995.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:16:35.925Z,1531440995.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:16:36.312Z,1531440996.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:16:36.729Z,1531440996.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:16:37.112Z,1531440997.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:16:37.534Z,1531440997.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:16:37.928Z,1531440997.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:16:38.324Z,1531440998.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:16:38.732Z,1531440998.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:16:39.132Z,1531440999.132 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:16:39.483Z,1531440999.483 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-13T00:16:39.577Z,1531440999.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:16:39.935Z,1531440999.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:16:40.344Z,1531441000.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:16:40.684Z,1531441000.684 [NAL9602](INFO): GPS fix at 20180713T001622: (36.802689, -121.788076)
2018-07-13T00:16:40.758Z,1531441000.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:16:40.773Z,1531441000.773 [Default:CheckIn:Read_GPS] Stopped
2018-07-13T00:16:40.774Z,1531441000.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-13T00:16:41.150Z,1531441001.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:16:41.173Z,1531441001.173 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-13T00:16:41.612Z,1531441001.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-07-13T00:16:41.952Z,1531441001.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-13T00:16:47.096Z,1531441007.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-13T00:16:47.216Z,1531441007.216 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20180712T235703/Courier0007.lzma
2018-07-13T00:16:47.358Z,1531441007.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2018-07-13T00:16:47.703Z,1531441007.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2018-07-13T00:16:47.997Z,1531441007.997 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235703/Courier0007.lzma.bak
2018-07-13T00:16:47.998Z,1531441007.998 [DataOverHttps](INFO): SBD MOMSN=8366962
2018-07-13T00:16:48.114Z,1531441008.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2018-07-13T00:16:48.481Z,1531441008.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-07-13T00:16:48.869Z,1531441008.869 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-07-13T00:16:52.394Z,1531441012.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2018-07-13T00:16:52.570Z,1531441012.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2018-07-13T00:16:58.738Z,1531441018.738 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20180713T001047/Courier0000.lzma
2018-07-13T00:16:59.488Z,1531441019.488 [DataOverHttps](INFO): Moved sent file to Logs/20180713T001047/Courier0000.lzma.bak
2018-07-13T00:16:59.489Z,1531441019.489 [DataOverHttps](INFO): SBD MOMSN=8366966
2018-07-13T00:17:09.812Z,1531441029.812 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180713T001047/Courier0004.lzma
2018-07-13T00:17:10.560Z,1531441030.560 [DataOverHttps](INFO): Moved sent file to Logs/20180713T001047/Courier0004.lzma.bak
2018-07-13T00:17:10.561Z,1531441030.561 [DataOverHttps](INFO): SBD MOMSN=8366970
2018-07-13T00:17:19.734Z,1531441039.734 [NAL9602](INFO): Powering down
2018-07-13T00:17:19.995Z,1531441039.995 [DataOverHttps](INFO): Sending 837 bytes from file Logs/20180712T235703/Express0008.lzma
2018-07-13T00:17:21.151Z,1531441041.151 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235703/Express0008.lzma.bak
2018-07-13T00:17:21.152Z,1531441041.152 [DataOverHttps](INFO): SBD MOMSN=8366973
2018-07-13T00:17:30.689Z,1531441050.689 [DataOverHttps](INFO): Sending 660 bytes from file Logs/20180713T001047/Express0001.lzma
2018-07-13T00:17:31.455Z,1531441051.455 [DataOverHttps](INFO): Moved sent file to Logs/20180713T001047/Express0001.lzma.bak
2018-07-13T00:17:31.456Z,1531441051.456 [DataOverHttps](INFO): SBD MOMSN=8367013
2018-07-13T00:17:41.195Z,1531441061.195 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20180713T001047/Express0005.lzma
2018-07-13T00:17:41.991Z,1531441061.991 [DataOverHttps](INFO): Moved sent file to Logs/20180713T001047/Express0005.lzma.bak
2018-07-13T00:17:41.991Z,1531441061.991 [DataOverHttps](INFO): SBD MOMSN=8367028
2018-07-13T00:17:42.466Z,1531441062.466 [Default:CheckIn:Read_Iridium] Stopped
2018-07-13T00:17:42.466Z,1531441062.466 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-13T00:17:42.467Z,1531441062.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-13T00:18:18.573Z,1531441098.573 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2018-07-13T00:18:18.573Z,1531441098.573 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-13T00:18:18.573Z,1531441098.573 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-13T00:18:18.574Z,1531441098.574 [RDI_Pathfinder](ERROR): Failed to parse:
2018-07-13T00:18:18.612Z,1531441098.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:18:18.647Z,1531441098.647 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-13T00:18:18.993Z,1531441098.993 [RDI_Pathfinder](INFO): Powering down
2018-07-13T00:18:19.008Z,1531441099.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:18:19.353Z,1531441099.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:18:19.736Z,1531441099.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:18:19.793Z,1531441099.793 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-13T00:18:19.793Z,1531441099.793 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-13T00:18:20.140Z,1531441100.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:18:20.540Z,1531441100.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:18:20.936Z,1531441100.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:18:21.345Z,1531441101.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:18:21.743Z,1531441101.743 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:18:22.147Z,1531441102.147 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:18:22.556Z,1531441102.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:18:22.950Z,1531441102.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:18:23.347Z,1531441103.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:18:23.755Z,1531441103.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:18:24.153Z,1531441104.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:18:24.552Z,1531441104.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:18:24.978Z,1531441104.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:18:25.355Z,1531441105.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:18:25.736Z,1531441105.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:18:26.157Z,1531441106.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:18:26.552Z,1531441106.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:18:26.954Z,1531441106.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-13T00:18:27.351Z,1531441107.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-13T00:18:27.752Z,1531441107.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-13T00:18:28.152Z,1531441108.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-13T00:18:28.555Z,1531441108.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-13T00:18:28.950Z,1531441108.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-13T00:18:29.352Z,1531441109.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-13T00:18:29.754Z,1531441109.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-13T00:18:30.149Z,1531441110.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-13T00:18:30.552Z,1531441110.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-13T00:18:30.953Z,1531441110.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-13T00:18:31.335Z,1531441111.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-13T00:18:31.737Z,1531441111.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-13T00:18:32.138Z,1531441112.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-13T00:18:32.546Z,1531441112.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-13T00:18:32.945Z,1531441112.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-13T00:18:33.372Z,1531441113.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-13T00:19:56.912Z,1531441196.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:19:57.281Z,1531441197.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:19:57.720Z,1531441197.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:19:58.084Z,1531441198.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:19:58.494Z,1531441198.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:19:58.886Z,1531441198.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:19:59.300Z,1531441199.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:19:59.705Z,1531441199.705 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:20:00.104Z,1531441200.104 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:20:00.509Z,1531441200.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:20:00.899Z,1531441200.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:20:01.304Z,1531441201.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:20:01.715Z,1531441201.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:20:02.103Z,1531441202.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:20:02.512Z,1531441202.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:20:02.904Z,1531441202.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:20:03.287Z,1531441203.287 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:20:03.717Z,1531441203.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:20:10.920Z,1531441210.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:20:11.301Z,1531441211.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:20:11.709Z,1531441211.709 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:20:12.108Z,1531441212.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:20:12.488Z,1531441212.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:20:12.926Z,1531441212.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:20:13.292Z,1531441213.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:20:13.704Z,1531441213.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:20:14.093Z,1531441214.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:20:14.506Z,1531441214.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:20:14.900Z,1531441214.900 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:20:15.347Z,1531441215.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:20:15.707Z,1531441215.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:20:16.104Z,1531441216.104 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:20:16.514Z,1531441216.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:20:16.912Z,1531441216.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:20:17.326Z,1531441217.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:20:17.714Z,1531441217.714 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:20:18.107Z,1531441218.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:20:18.509Z,1531441218.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:20:18.893Z,1531441218.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:20:19.296Z,1531441219.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-13T00:20:19.706Z,1531441219.706 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-13T00:20:20.101Z,1531441220.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-13T00:20:20.507Z,1531441220.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-13T00:20:20.898Z,1531441220.898 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-13T00:20:21.299Z,1531441221.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-13T00:20:21.715Z,1531441221.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-13T00:20:22.099Z,1531441222.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-13T00:20:22.522Z,1531441222.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-13T00:20:22.906Z,1531441222.906 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-13T00:20:23.302Z,1531441223.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-13T00:20:23.721Z,1531441223.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-13T00:20:24.086Z,1531441224.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-13T00:20:24.519Z,1531441224.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-13T00:20:49.420Z,1531441249.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:20:49.814Z,1531441249.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:20:50.207Z,1531441250.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:20:50.623Z,1531441250.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:20:50.976Z,1531441250.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:20:51.423Z,1531441251.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:20:51.845Z,1531441251.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:20:52.154Z,1531441252.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.1 s old, using for 20.0 s.
2018-07-13T00:20:52.582Z,1531441252.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:20:52.965Z,1531441252.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:20:53.370Z,1531441253.370 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:20:53.760Z,1531441253.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s.
2018-07-13T00:20:54.159Z,1531441254.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2018-07-13T00:20:54.559Z,1531441254.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2018-07-13T00:20:54.964Z,1531441254.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:20:55.411Z,1531441255.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:20:55.750Z,1531441255.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2018-07-13T00:20:56.158Z,1531441256.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2018-07-13T00:20:56.569Z,1531441256.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:20:56.964Z,1531441256.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:20:57.398Z,1531441257.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:20:57.749Z,1531441257.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.7 s old, using for 20.0 s.
2018-07-13T00:20:58.156Z,1531441258.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.1 s old, using for 20.0 s.
2018-07-13T00:20:58.556Z,1531441258.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s.
2018-07-13T00:20:58.949Z,1531441258.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2018-07-13T00:20:59.383Z,1531441259.383 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-13T00:21:06.974Z,1531441266.974 [CommandLine](IMPORTANT): got command failComponent
2018-07-13T00:21:06.974Z,1531441266.974 [CommandLine](IMPORTANT): Failed components:
2018-07-13T00:21:06.975Z,1531441266.975 [CommandLine](IMPORTANT): No failed Components.
2018-07-13T00:21:27.780Z,1531441287.780 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2018-07-13T00:21:27.780Z,1531441287.780 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim.xml
2018-07-13T00:21:27.950Z,1531441287.950 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2018-07-13T00:21:27.953Z,1531441287.953 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2018-07-13T00:21:27.956Z,1531441287.956 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2018-07-13T00:21:27.959Z,1531441287.959 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband1 = 0.100000 m
2018-07-13T00:21:27.962Z,1531441287.962 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2018-07-13T00:21:27.965Z,1531441287.965 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband2 = nan m
2018-07-13T00:21:27.968Z,1531441287.968 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2018-07-13T00:21:27.971Z,1531441287.971 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2018-07-13T00:21:27.975Z,1531441287.975 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2018-07-13T00:21:27.978Z,1531441287.978 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2018-07-13T00:21:27.982Z,1531441287.982 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2018-07-13T00:21:27.986Z,1531441287.986 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2018-07-13T00:21:27.990Z,1531441287.990 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2018-07-13T00:21:28.025Z,1531441288.025 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2018-07-13T00:21:28.028Z,1531441288.028 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2018-07-13T00:21:28.029Z,1531441288.029 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-07-13T00:21:28.571Z,1531441288.571 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUS = 0 bool
2018-07-13T00:21:28.579Z,1531441288.579 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-07-13T00:21:28.603Z,1531441288.603 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-07-13T00:21:28.613Z,1531441288.613 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2018-07-13T00:21:28.616Z,1531441288.616 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2018-07-13T00:21:28.626Z,1531441288.626 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2018-07-13T00:21:28.630Z,1531441288.630 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2018-07-13T00:21:28.637Z,1531441288.637 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2018-07-13T00:21:28.640Z,1531441288.640 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2018-07-13T00:21:28.647Z,1531441288.647 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2018-07-13T00:21:28.651Z,1531441288.651 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-07-13T00:21:28.662Z,1531441288.662 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool
2018-07-13T00:21:28.666Z,1531441288.666 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 1.000000 bool
2018-07-13T00:21:28.678Z,1531441288.678 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 0.000000 bool
2018-07-13T00:21:28.688Z,1531441288.688 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-07-13T00:21:28.696Z,1531441288.696 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-07-13T00:21:28.700Z,1531441288.700 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-07-13T00:21:28.715Z,1531441288.715 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledISUS = 0.000000 bool
2018-07-13T00:21:28.719Z,1531441288.719 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2018-07-13T00:21:28.722Z,1531441288.722 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m
2018-07-13T00:21:28.737Z,1531441288.737 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m
2018-07-13T00:21:28.740Z,1531441288.740 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2018-07-13T00:21:28.744Z,1531441288.744 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2018-07-13T00:21:28.747Z,1531441288.747 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2018-07-13T00:21:28.760Z,1531441288.760 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2018-07-13T00:21:28.763Z,1531441288.763 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2018-07-13T00:21:28.766Z,1531441288.766 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2018-07-13T00:21:28.782Z,1531441288.782 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2018-07-13T00:21:28.785Z,1531441288.785 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2018-07-13T00:21:28.789Z,1531441288.789 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2018-07-13T00:21:28.792Z,1531441288.792 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m
2018-07-13T00:21:28.816Z,1531441288.816 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2018-07-13T00:21:28.819Z,1531441288.819 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2018-07-13T00:21:28.822Z,1531441288.822 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2018-07-13T00:21:28.837Z,1531441288.837 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2018-07-13T00:21:28.841Z,1531441288.841 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2018-07-13T00:21:28.844Z,1531441288.844 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2018-07-13T00:21:28.847Z,1531441288.847 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2018-07-13T00:21:28.881Z,1531441288.881 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2018-07-13T00:21:28.963Z,1531441288.963 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-13T00:21:29.002Z,1531441289.002 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-07-13T00:21:29.057Z,1531441289.057 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-13T00:21:29.083Z,1531441289.083 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-13T00:21:29.100Z,1531441289.100 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2018-07-13T00:21:29.279Z,1531441289.279 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepth = 25.000000 m
2018-07-13T00:21:29.335Z,1531441289.335 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2018-07-13T00:21:29.352Z,1531441289.352 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2018-07-13T00:21:29.397Z,1531441289.397 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2018-07-13T00:21:29.400Z,1531441289.400 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2018-07-13T00:21:29.429Z,1531441289.429 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2018-07-13T00:21:29.432Z,1531441289.432 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSettleTime = 10.000000 min
2018-07-13T00:21:29.444Z,1531441289.444 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstTimeout = 45.000000 min
2018-07-13T00:21:29.455Z,1531441289.455 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MinEstTime = 10.000000 min
2018-07-13T00:21:29.471Z,1531441289.471 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2018-07-13T00:21:29.487Z,1531441289.487 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2018-07-13T00:21:29.503Z,1531441289.503 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstConfidence = 99.900000 %
2018-07-13T00:21:29.540Z,1531441289.540 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2018-07-13T00:21:29.565Z,1531441289.565 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-07-13T00:21:29.567Z,1531441289.567 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct.
2018-07-13T00:21:29.587Z,1531441289.587 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-07-13T00:21:29.602Z,1531441289.602 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2018-07-13T00:21:29.605Z,1531441289.605 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2018-07-13T00:21:29.659Z,1531441289.659 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2018-07-13T00:21:29.663Z,1531441289.663 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2018-07-13T00:21:29.790Z,1531441289.790 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth1
2018-07-13T00:21:29.792Z,1531441289.792 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband1
2018-07-13T00:21:29.810Z,1531441289.810 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceThreshold = reader:ballast_and_trim.SurfaceThreshold
2018-07-13T00:21:29.812Z,1531441289.812 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2018-07-13T00:21:29.831Z,1531441289.831 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2018-07-13T00:21:29.833Z,1531441289.833 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2018-07-13T00:21:29.835Z,1531441289.835 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2018-07-13T00:21:29.836Z,1531441289.836 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2018-07-13T00:21:29.844Z,1531441289.844 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2018-07-13T00:21:29.862Z,1531441289.862 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2018-07-13T00:21:29.864Z,1531441289.864 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2018-07-13T00:21:30.092Z,1531441290.092 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepth = 25.000000 m
2018-07-13T00:21:30.148Z,1531441290.148 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2018-07-13T00:21:30.156Z,1531441290.156 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2018-07-13T00:21:30.164Z,1531441290.164 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2018-07-13T00:21:30.188Z,1531441290.188 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2018-07-13T00:21:30.202Z,1531441290.202 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2018-07-13T00:21:30.207Z,1531441290.207 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSettleTime = 10.000000 min
2018-07-13T00:21:30.210Z,1531441290.210 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstTimeout = 45.000000 min
2018-07-13T00:21:30.221Z,1531441290.221 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MinEstTime = 10.000000 min
2018-07-13T00:21:30.229Z,1531441290.229 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2018-07-13T00:21:30.232Z,1531441290.232 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2018-07-13T00:21:30.239Z,1531441290.239 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstConfidence = 99.900000 %
2018-07-13T00:21:30.256Z,1531441290.256 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2018-07-13T00:21:30.272Z,1531441290.272 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-07-13T00:21:30.278Z,1531441290.278 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct.
2018-07-13T00:21:30.294Z,1531441290.294 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-07-13T00:21:30.306Z,1531441290.306 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2018-07-13T00:21:30.310Z,1531441290.310 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2018-07-13T00:21:30.328Z,1531441290.328 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2018-07-13T00:21:30.350Z,1531441290.350 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2018-07-13T00:21:30.374Z,1531441290.374 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth2
2018-07-13T00:21:30.375Z,1531441290.375 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband2
2018-07-13T00:21:30.377Z,1531441290.377 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceThreshold = reader:ballast_and_trim.SurfaceThreshold
2018-07-13T00:21:30.379Z,1531441290.379 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2018-07-13T00:21:30.393Z,1531441290.393 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2018-07-13T00:21:30.395Z,1531441290.395 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2018-07-13T00:21:30.398Z,1531441290.398 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2018-07-13T00:21:30.400Z,1531441290.400 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2018-07-13T00:21:30.402Z,1531441290.402 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2018-07-13T00:21:30.404Z,1531441290.404 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2018-07-13T00:21:30.407Z,1531441290.407 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2018-07-13T00:21:30.439Z,1531441290.439 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2018-07-13T00:21:30.490Z,1531441290.490 [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="DepthDeadband1"><Units:meter/><Value>0.1</Value></DefineArg>

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

    <DefineArg Name="DepthDeadband2"><Units:meter/><Value>NaN</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">

        <Aggregate Id="Depth1">

            <Sequence/>

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

            <Insert Filename="Insert/BallastAndTrim.xml">
                <RedefineArg Name="InsertDepth"><Arg Name="Depth1"/></RedefineArg>
                <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband1"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceThreshold"><Arg Name="SurfaceThreshold"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceTimeout"><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>

        <Aggregate Id="Depth2">

            <Sequence/>

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

            <Insert Filename="Insert/BallastAndTrim.xml">
                <RedefineArg Name="InsertDepth"><Arg Name="Depth2"/></RedefineArg>
                <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband2"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceThreshold"><Arg Name="SurfaceThreshold"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceTimeout"><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>

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


2018-07-13T00:21:30.542Z,1531441290.542 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2018-07-13T00:21:30.579Z,1531441290.579 [Default] Stopped
2018-07-13T00:21:30.579Z,1531441290.579 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-13T00:21:30.579Z,1531441290.579 [Default:B.GoToSurface] Stopped
2018-07-13T00:21:30.580Z,1531441290.580 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-13T00:21:30.580Z,1531441290.580 [Default:CheckIn] Stopped
2018-07-13T00:21:30.580Z,1531441290.580 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-13T00:21:30.580Z,1531441290.580 [Default:CheckIn:C.Wait] Stopped
2018-07-13T00:21:30.580Z,1531441290.580 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-13T00:21:30.580Z,1531441290.580 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2018-07-13T00:21:30.580Z,1531441290.580 [ballast_and_trim] Running Loop=1
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim:Science] Running Loop=1
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim:Science:A] Running Loop=1
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim:Science:B] Running Loop=1
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim:Science:C] Running Loop=1
2018-07-13T00:21:30.585Z,1531441290.585 [ballast_and_trim:Science:D] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:E] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:F] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:G] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-07-13T00:21:30.586Z,1531441290.586 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-13T00:21:30.588Z,1531441290.588 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2018-07-13T00:21:30.588Z,1531441290.588 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2018-07-13T00:21:30.593Z,1531441290.593 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-07-13T00:21:30.593Z,1531441290.593 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-07-13T00:21:30.593Z,1531441290.593 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2018-07-13T00:21:30.594Z,1531441290.594 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2018-07-13T00:21:30.594Z,1531441290.594 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-07-13T00:21:30.594Z,1531441290.594 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-13T00:21:30.596Z,1531441290.596 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2018-07-13T00:21:30.596Z,1531441290.596 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2018-07-13T00:21:30.596Z,1531441290.596 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-07-13T00:21:30.596Z,1531441290.596 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-13T00:21:30.598Z,1531441290.598 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2018-07-13T00:21:30.598Z,1531441290.598 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2018-07-13T00:21:30.598Z,1531441290.598 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2018-07-13T00:21:30.598Z,1531441290.598 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2018-07-13T00:21:30.599Z,1531441290.599 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim] Running Loop=1
2018-07-13T00:21:30.599Z,1531441290.599 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim
2018-07-13T00:21:30.599Z,1531441290.599 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Running Loop=1
2018-07-13T00:21:30.599Z,1531441290.599 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Running Loop=1
2018-07-13T00:21:30.599Z,1531441290.599 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Running Loop=1
2018-07-13T00:21:30.599Z,1531441290.599 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D] Running Loop=1
2018-07-13T00:21:30.652Z,1531441290.652 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D](INFO): Going to target depth. Speed set to 0.000000 m/s 
2018-07-13T00:21:30.654Z,1531441290.654 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D] Stopped
2018-07-13T00:21:30.654Z,1531441290.654 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth] Running Loop=1
2018-07-13T00:21:30.654Z,1531441290.654 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth
2018-07-13T00:21:30.654Z,1531441290.654 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2018-07-13T00:21:30.654Z,1531441290.654 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Initialize.
2018-07-13T00:21:30.654Z,1531441290.654 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2018-07-13T00:21:30.655Z,1531441290.655 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2018-07-13T00:21:30.655Z,1531441290.655 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Running Loop=1
2018-07-13T00:21:30.655Z,1531441290.655 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Initialize.
2018-07-13T00:21:30.655Z,1531441290.655 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Running Loop=1
2018-07-13T00:21:30.673Z,1531441290.673 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Running Loop=1
2018-07-13T00:21:30.675Z,1531441290.675 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Running Loop=1
2018-07-13T00:21:30.676Z,1531441290.676 [ballast_and_trim:Science] Running Loop=1
2018-07-13T00:21:30.681Z,1531441290.681 [ballast_and_trim:Science:PeakDetectOil] Stopped
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2018-07-13T00:21:30.682Z,1531441290.682 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-07-13T00:21:30.683Z,1531441290.683 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-07-13T00:21:30.683Z,1531441290.683 [ballast_and_trim:Science:PeakDetectChl] Stopped
2018-07-13T00:21:30.683Z,1531441290.683 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2018-07-13T00:21:30.683Z,1531441290.683 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-07-13T00:21:30.683Z,1531441290.683 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-13T00:21:30.683Z,1531441290.683 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2018-07-13T00:21:30.684Z,1531441290.684 [ballast_and_trim:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2018-07-13T00:21:30.688Z,1531441290.688 [ballast_and_trim:Science:G] Running Loop=1
2018-07-13T00:21:30.688Z,1531441290.688 [ballast_and_trim:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-07-13T00:21:30.714Z,1531441290.714 [ballast_and_trim:Science:F] Stopped
2018-07-13T00:21:30.714Z,1531441290.714 [ballast_and_trim:Science:E] Stopped
2018-07-13T00:21:30.714Z,1531441290.714 [ballast_and_trim:Science:D] Stopped
2018-07-13T00:21:30.714Z,1531441290.714 [ballast_and_trim:Science:C] Running Loop=1
2018-07-13T00:21:30.715Z,1531441290.715 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-07-13T00:21:30.716Z,1531441290.716 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-07-13T00:21:30.718Z,1531441290.718 [ballast_and_trim:Science:B] Stopped
2018-07-13T00:21:30.718Z,1531441290.718 [ballast_and_trim:Science:A] Stopped
2018-07-13T00:21:30.801Z,1531441290.801 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-07-13T00:21:30.809Z,1531441290.809 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-07-13T00:21:30.938Z,1531441290.938 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:30.990Z,1531441290.990 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2018-07-13T00:21:30.990Z,1531441290.990 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2018-07-13T00:21:31.349Z,1531441291.349 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:31.761Z,1531441291.761 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:32.177Z,1531441292.177 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:32.369Z,1531441292.369 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:32.542Z,1531441292.542 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:32.725Z,1531441292.725 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:32.893Z,1531441292.893 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:33.062Z,1531441293.062 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:33.233Z,1531441293.233 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:33.401Z,1531441293.401 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:33.561Z,1531441293.561 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:33.737Z,1531441293.737 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:33.905Z,1531441293.905 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:34.073Z,1531441294.073 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:34.241Z,1531441294.241 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:34.409Z,1531441294.409 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:34.577Z,1531441294.577 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:34.749Z,1531441294.749 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:34.921Z,1531441294.921 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:35.085Z,1531441295.085 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:35.261Z,1531441295.261 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:35.437Z,1531441295.437 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:35.597Z,1531441295.597 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:35.765Z,1531441295.765 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:35.929Z,1531441295.929 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:36.097Z,1531441296.097 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:36.257Z,1531441296.257 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:36.437Z,1531441296.437 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:36.609Z,1531441296.609 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:36.781Z,1531441296.781 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:36.949Z,1531441296.949 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:37.117Z,1531441297.117 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:37.273Z,1531441297.273 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:37.449Z,1531441297.449 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:37.614Z,1531441297.614 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:37.817Z,1531441297.817 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:37.817Z,1531441297.817 [CTD_NeilBrown](FAULT): No CTD communication! Re-initializing
2018-07-13T00:21:37.817Z,1531441297.817 [CTD_NeilBrown] Communications Fault, FailCount= 1
2018-07-13T00:21:37.817Z,1531441297.817 [CTD_NeilBrown](ERROR): Communications Fault
2018-07-13T00:21:38.110Z,1531441298.110 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2018-07-13T00:21:38.342Z,1531441298.342 [CTD_NeilBrown](INFO): Powering down
2018-07-13T00:21:39.670Z,1531441299.670 [CBIT](INFO): Clearing failed state for component CTD_NeilBrown
2018-07-13T00:21:39.670Z,1531441299.670 [CTD_NeilBrown] No Fault, FailCount= 1
2018-07-13T00:21:39.957Z,1531441299.957 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-07-13T00:21:39.962Z,1531441299.962 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-07-13T00:21:40.073Z,1531441300.073 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:40.485Z,1531441300.485 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:40.897Z,1531441300.897 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:41.007Z,1531441301.007 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:41.177Z,1531441301.177 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:41.341Z,1531441301.341 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:41.529Z,1531441301.529 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:41.685Z,1531441301.685 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:41.861Z,1531441301.861 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:42.010Z,1531441302.010 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:42.197Z,1531441302.197 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:42.353Z,1531441302.353 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:42.525Z,1531441302.525 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:42.693Z,1531441302.693 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:42.873Z,1531441302.873 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:43.041Z,1531441303.041 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:43.201Z,1531441303.201 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:43.369Z,1531441303.369 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:43.542Z,1531441303.542 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:43.713Z,1531441303.713 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:43.881Z,1531441303.881 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:44.041Z,1531441304.041 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:44.221Z,1531441304.221 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:44.389Z,1531441304.389 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:44.557Z,1531441304.557 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:44.738Z,1531441304.738 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:44.897Z,1531441304.897 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:45.065Z,1531441305.065 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:45.221Z,1531441305.221 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:45.401Z,1531441305.401 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:45.569Z,1531441305.569 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:45.745Z,1531441305.745 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:45.913Z,1531441305.913 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:46.081Z,1531441306.081 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:46.241Z,1531441306.241 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:46.334Z,1531441306.334 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2018-07-13T00:21:46.421Z,1531441306.421 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:46.589Z,1531441306.589 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:46.757Z,1531441306.757 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:46.917Z,1531441306.917 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:47.097Z,1531441307.097 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:47.097Z,1531441307.097 [CTD_NeilBrown](FAULT): No CTD communication! Re-initializing
2018-07-13T00:21:47.097Z,1531441307.097 [CTD_NeilBrown] Communications Fault, FailCount= 2
2018-07-13T00:21:47.097Z,1531441307.097 [CTD_NeilBrown](ERROR): Communications Fault
2018-07-13T00:21:47.498Z,1531441307.498 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2018-07-13T00:21:47.509Z,1531441307.509 [Reporter](INFO): platform_buoyancy_position 926.391338 cc
2018-07-13T00:21:47.629Z,1531441307.629 [CTD_NeilBrown](INFO): Powering down
2018-07-13T00:21:47.808Z,1531441307.808 [Reporter](INFO): platform_buoyancy_position 915.781653 cc
2018-07-13T00:21:48.102Z,1531441308.102 [Reporter](INFO): platform_buoyancy_position 907.052134 cc
2018-07-13T00:21:48.485Z,1531441308.485 [BuoyancyServo](FAULT): Overload Error
2018-07-13T00:21:48.485Z,1531441308.485 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-07-13T00:21:48.485Z,1531441308.485 [BuoyancyServo](ERROR): Hardware Fault
2018-07-13T00:21:48.566Z,1531441308.566 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-13T00:21:48.826Z,1531441308.826 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-13T00:21:48.827Z,1531441308.827 [BuoyancyServo](INFO): Powering down
2018-07-13T00:21:48.890Z,1531441308.890 [CBIT](INFO): Clearing failed state for component CTD_NeilBrown
2018-07-13T00:21:48.890Z,1531441308.890 [CTD_NeilBrown] No Fault, FailCount= 2
2018-07-13T00:21:49.181Z,1531441309.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:21:49.245Z,1531441309.245 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-07-13T00:21:49.253Z,1531441309.253 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-07-13T00:21:49.591Z,1531441309.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:21:49.665Z,1531441309.665 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:49.980Z,1531441309.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:21:50.069Z,1531441310.069 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:50.413Z,1531441310.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:21:50.473Z,1531441310.473 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:50.760Z,1531441310.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:21:50.846Z,1531441310.846 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-13T00:21:50.846Z,1531441310.846 [BuoyancyServo] No Fault, FailCount= 1
2018-07-13T00:21:50.881Z,1531441310.881 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:51.167Z,1531441311.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:21:51.210Z,1531441311.210 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:21:51.293Z,1531441311.293 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:51.330Z,1531441311.330 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-13T00:21:51.576Z,1531441311.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:21:51.701Z,1531441311.701 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:51.980Z,1531441311.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:21:52.113Z,1531441312.113 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:52.428Z,1531441312.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:21:52.525Z,1531441312.525 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:52.832Z,1531441312.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:21:52.929Z,1531441312.929 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:53.174Z,1531441313.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:21:53.341Z,1531441313.341 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:53.575Z,1531441313.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:21:53.745Z,1531441313.745 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:53.994Z,1531441313.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:21:54.042Z,1531441314.042 [Reporter](INFO): platform_buoyancy_position 896.039535 cc
2018-07-13T00:21:54.149Z,1531441314.149 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:54.415Z,1531441314.415 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:21:54.559Z,1531441314.559 [Reporter](INFO): platform_buoyancy_position 901.008665 cc
2018-07-13T00:21:54.561Z,1531441314.561 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:54.771Z,1531441314.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:21:54.965Z,1531441314.965 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:55.235Z,1531441315.235 [Reporter](INFO): platform_buoyancy_position 900.605752 cc
2018-07-13T00:21:55.373Z,1531441315.373 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:55.378Z,1531441315.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.6 s old, using for 20.0 s.
2018-07-13T00:21:55.499Z,1531441315.499 [Reporter](INFO): platform_buoyancy_position 896.308164 cc
2018-07-13T00:21:55.702Z,1531441315.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-07-13T00:21:55.777Z,1531441315.777 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:55.847Z,1531441315.847 [Reporter](INFO): platform_buoyancy_position 889.996008 cc
2018-07-13T00:21:56.096Z,1531441316.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-07-13T00:21:56.181Z,1531441316.181 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:56.207Z,1531441316.207 [Reporter](INFO): platform_buoyancy_position 889.190240 cc
2018-07-13T00:21:56.510Z,1531441316.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2018-07-13T00:21:56.585Z,1531441316.585 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-13T00:21:56.585Z,1531441316.585 [CTD_NeilBrown](FAULT): No CTD communication! Re-initializing
2018-07-13T00:21:56.585Z,1531441316.585 [CTD_NeilBrown] Communications Fault, FailCount= 3
2018-07-13T00:21:56.585Z,1531441316.585 [CTD_NeilBrown](ERROR): Communications Fault
2018-07-13T00:21:56.706Z,1531441316.706 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2018-07-13T00:21:56.707Z,1531441316.707 [CBIT](FAULT): Communications Fault in component: CTD_NeilBrown
2018-07-13T00:21:56.708Z,1531441316.708 [Reporter](INFO): platform_buoyancy_position 885.698420 cc
2018-07-13T00:21:56.907Z,1531441316.907 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2018-07-13T00:21:57.051Z,1531441317.051 [Reporter](INFO): platform_buoyancy_position 883.415341 cc
2018-07-13T00:21:57.113Z,1531441317.113 [CTD_NeilBrown](INFO): Powering down
2018-07-13T00:21:57.352Z,1531441317.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.6 s old, using for 20.0 s.
2018-07-13T00:21:57.488Z,1531441317.488 [Reporter](INFO): platform_buoyancy_position 879.252097 cc
2018-07-13T00:21:57.709Z,1531441317.709 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-07-13T00:21:57.831Z,1531441317.831 [Reporter](INFO): platform_buoyancy_position 877.103244 cc
2018-07-13T00:21:58.108Z,1531441318.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-07-13T00:21:58.236Z,1531441318.236 [Reporter](INFO): platform_buoyancy_position 874.820107 cc
2018-07-13T00:21:58.520Z,1531441318.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-07-13T00:21:58.526Z,1531441318.526 [CommandLine](IMPORTANT): got command stop
2018-07-13T00:21:58.526Z,1531441318.526 [CommandLine](IMPORTANT): Scheduling is paused
2018-07-13T00:21:58.526Z,1531441318.526 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-07-13T00:21:58.554Z,1531441318.554 [MissionManager](INFO): MissionManager is completed.
2018-07-13T00:21:58.554Z,1531441318.554 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2018-07-13T00:21:58.554Z,1531441318.554 [ballast_and_trim] Stopped
2018-07-13T00:21:58.554Z,1531441318.554 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:Science] Stopped
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:Science:C] Stopped
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:Science:G] Stopped
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:Science:Read_Oil] Stopped
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:RunBallastAndTrim] Stopped
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2018-07-13T00:21:58.555Z,1531441318.555 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Stopped
2018-07-13T00:21:58.556Z,1531441318.556 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2018-07-13T00:21:58.561Z,1531441318.561 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Stopped
2018-07-13T00:21:58.880Z,1531441318.880 [Reporter](INFO): platform_buoyancy_position 870.522519 cc
2018-07-13T00:21:58.964Z,1531441318.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2018-07-13T00:21:58.970Z,1531441318.970 [MissionManager](IMPORTANT): Started mission Default
2018-07-13T00:21:58.970Z,1531441318.970 [Default] Running Loop=1
2018-07-13T00:21:58.970Z,1531441318.970 [Default](DEBUG): Aggregate::initialize Default
2018-07-13T00:21:58.971Z,1531441318.971 [Default:B.GoToSurface] Running Loop=1
2018-07-13T00:21:58.971Z,1531441318.971 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-13T00:21:58.971Z,1531441318.971 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-13T00:21:58.971Z,1531441318.971 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-13T00:21:58.972Z,1531441318.972 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-13T00:21:58.972Z,1531441318.972 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-13T00:21:58.972Z,1531441318.972 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-13T00:21:58.985Z,1531441318.985 [Default:A.Wait] Running Loop=1
2018-07-13T00:21:58.985Z,1531441318.985 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-13T00:21:59.072Z,1531441319.072 [Reporter](INFO): platform_buoyancy_position 869.179552 cc
2018-07-13T00:21:59.400Z,1531441319.400 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-07-13T00:21:59.759Z,1531441319.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2018-07-13T00:22:00.157Z,1531441320.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-07-13T00:22:00.567Z,1531441320.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-07-13T00:22:01.036Z,1531441321.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.3 s old, using for 20.0 s.
2018-07-13T00:22:01.460Z,1531441321.460 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.7 s old, using for 20.0 s.
2018-07-13T00:22:01.832Z,1531441321.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2018-07-13T00:22:02.231Z,1531441322.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2018-07-13T00:22:02.661Z,1531441322.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.9 s old, using for 20.0 s.
2018-07-13T00:22:02.791Z,1531441322.791 [Reporter](INFO): platform_buoyancy_position 864.210422 cc
2018-07-13T00:22:03.049Z,1531441323.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.3 s old, using for 20.0 s.
2018-07-13T00:22:03.147Z,1531441323.147 [Reporter](INFO): platform_buoyancy_position 864.076195 cc
2018-07-13T00:22:03.453Z,1531441323.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.7 s old, using for 20.0 s.
2018-07-13T00:22:03.555Z,1531441323.555 [Reporter](INFO): platform_buoyancy_position 864.210422 cc
2018-07-13T00:22:03.840Z,1531441323.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2018-07-13T00:22:04.240Z,1531441324.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2018-07-13T00:22:04.327Z,1531441324.327 [Reporter](INFO): platform_buoyancy_position 863.941852 cc
2018-07-13T00:22:04.652Z,1531441324.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2018-07-13T00:22:04.756Z,1531441324.756 [Reporter](INFO): platform_buoyancy_position 866.224931 cc
2018-07-13T00:22:05.048Z,1531441325.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2018-07-13T00:22:05.136Z,1531441325.136 [Reporter](INFO): platform_buoyancy_position 866.359274 cc
2018-07-13T00:22:05.451Z,1531441325.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2018-07-13T00:22:05.536Z,1531441325.536 [Reporter](INFO): platform_buoyancy_position 868.373725 cc
2018-07-13T00:22:05.851Z,1531441325.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2018-07-13T00:22:05.936Z,1531441325.936 [Reporter](INFO): platform_buoyancy_position 869.851094 cc
2018-07-13T00:22:06.244Z,1531441326.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2018-07-13T00:22:06.335Z,1531441326.335 [Reporter](INFO): platform_buoyancy_position 870.656862 cc
2018-07-13T00:22:06.654Z,1531441326.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2018-07-13T00:22:06.740Z,1531441326.740 [Reporter](INFO): platform_buoyancy_position 872.671313 cc
2018-07-13T00:22:07.036Z,1531441327.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.3 s old, using for 20.0 s.
2018-07-13T00:22:07.137Z,1531441327.137 [Reporter](INFO): platform_buoyancy_position 872.805656 cc
2018-07-13T00:22:07.459Z,1531441327.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2018-07-13T00:22:07.559Z,1531441327.559 [Reporter](INFO): platform_buoyancy_position 874.820107 cc
2018-07-13T00:22:07.826Z,1531441327.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2018-07-13T00:22:07.934Z,1531441327.934 [Reporter](INFO): platform_buoyancy_position 876.968959 cc
2018-07-13T00:22:08.235Z,1531441328.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2018-07-13T00:22:08.366Z,1531441328.366 [Reporter](INFO): platform_buoyancy_position 877.103244 cc
2018-07-13T00:22:08.674Z,1531441328.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2018-07-13T00:22:08.780Z,1531441328.780 [Reporter](INFO): platform_buoyancy_position 879.252097 cc
2018-07-13T00:22:09.143Z,1531441329.143 [Reporter](INFO): platform_buoyancy_position 880.326435 cc
2018-07-13T00:22:09.562Z,1531441329.562 [Reporter](INFO): platform_buoyancy_position 881.400832 cc
2018-07-13T00:22:09.968Z,1531441329.968 [Reporter](INFO): platform_buoyancy_position 883.415341 cc
2018-07-13T00:22:10.333Z,1531441330.333 [Reporter](INFO): platform_buoyancy_position 883.549626 cc
2018-07-13T00:22:10.743Z,1531441330.743 [Reporter](INFO): platform_buoyancy_position 885.564135 cc
2018-07-13T00:22:11.127Z,1531441331.127 [Reporter](INFO): platform_buoyancy_position 887.712929 cc
2018-07-13T00:22:11.538Z,1531441331.538 [Reporter](INFO): platform_buoyancy_position 887.847214 cc
2018-07-13T00:22:11.940Z,1531441331.940 [Reporter](INFO): platform_buoyancy_position 889.996008 cc
2018-07-13T00:22:12.250Z,1531441332.250 [Default:A.Wait](INFO): Done Waiting.
2018-07-13T00:22:12.250Z,1531441332.250 [Default:A.Wait] Stopped
2018-07-13T00:22:12.250Z,1531441332.250 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-13T00:22:12.332Z,1531441332.332 [Reporter](INFO): platform_buoyancy_position 890.264637 cc
2018-07-13T00:22:12.662Z,1531441332.662 [Default:CheckIn] Running Loop=1
2018-07-13T00:22:12.662Z,1531441332.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-13T00:22:12.662Z,1531441332.662 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-13T00:22:12.740Z,1531441332.740 [Reporter](INFO): platform_buoyancy_position 892.010576 cc
2018-07-13T00:22:13.144Z,1531441333.144 [Reporter](INFO): platform_buoyancy_position 894.159370 cc
2018-07-13T00:22:13.411Z,1531441333.411 [NAL9602](INFO): Powering up
2018-07-13T00:22:13.940Z,1531441333.940 [Reporter](INFO): platform_buoyancy_position 896.308164 cc
2018-07-13T00:22:14.348Z,1531441334.348 [Reporter](INFO): platform_buoyancy_position 898.322614 cc
2018-07-13T00:22:14.771Z,1531441334.771 [Reporter](INFO): platform_buoyancy_position 898.591243 cc
2018-07-13T00:22:15.126Z,1531441335.126 [Reporter](INFO): platform_buoyancy_position 900.605752 cc
2018-07-13T00:22:15.560Z,1531441335.560 [Reporter](INFO): platform_buoyancy_position 900.740037 cc
2018-07-13T00:22:15.964Z,1531441335.964 [Reporter](INFO): platform_buoyancy_position 902.888831 cc
2018-07-13T00:22:16.361Z,1531441336.361 [Reporter](INFO): platform_buoyancy_position 904.769055 cc
2018-07-13T00:22:16.744Z,1531441336.744 [Reporter](INFO): platform_buoyancy_position 905.037683 cc
2018-07-13T00:22:17.259Z,1531441337.259 [Reporter](INFO): platform_buoyancy_position 907.186477 cc
2018-07-13T00:22:17.937Z,1531441337.937 [Reporter](INFO): platform_buoyancy_position 909.200928 cc
2018-07-13T00:22:18.304Z,1531441338.304 [Reporter](INFO): platform_buoyancy_position 911.484065 cc
2018-07-13T00:22:18.700Z,1531441338.700 [Reporter](INFO): platform_buoyancy_position 911.349722 cc
2018-07-13T00:22:19.096Z,1531441339.096 [Reporter](INFO): platform_buoyancy_position 913.498516 cc
2018-07-13T00:22:19.516Z,1531441339.516 [Reporter](INFO): platform_buoyancy_position 914.170058 cc
2018-07-13T00:22:19.904Z,1531441339.904 [Reporter](INFO): platform_buoyancy_position 915.781653 cc
2018-07-13T00:22:20.324Z,1531441340.324 [Reporter](INFO): platform_buoyancy_position 917.661877 cc
2018-07-13T00:22:20.716Z,1531441340.716 [Reporter](INFO): platform_buoyancy_position 917.930447 cc
2018-07-13T00:22:21.094Z,1531441341.094 [Reporter](INFO): platform_buoyancy_position 920.079241 cc
2018-07-13T00:22:21.512Z,1531441341.512 [Reporter](INFO): platform_buoyancy_position 921.690837 cc
2018-07-13T00:22:21.897Z,1531441341.897 [Reporter](INFO): platform_buoyancy_position 922.228035 cc
2018-07-13T00:22:22.312Z,1531441342.312 [Reporter](INFO): platform_buoyancy_position 924.242544 cc
2018-07-13T00:22:22.726Z,1531441342.726 [Reporter](INFO): platform_buoyancy_position 924.376829 cc
2018-07-13T00:22:23.088Z,1531441343.088 [Reporter](INFO): platform_buoyancy_position 926.391338 cc
2018-07-13T00:22:23.512Z,1531441343.512 [Reporter](INFO): platform_buoyancy_position 928.540190 cc
2018-07-13T00:22:23.896Z,1531441343.896 [Reporter](INFO): platform_buoyancy_position 928.674417 cc
2018-07-13T00:22:24.300Z,1531441344.300 [Reporter](INFO): platform_buoyancy_position 930.688984 cc
2018-07-13T00:22:24.609Z,1531441344.609 [NAL9602](INFO): NAL9602 initialized
2018-07-13T00:22:24.742Z,1531441344.742 [Reporter](INFO): platform_buoyancy_position 930.823269 cc
2018-07-13T00:22:25.112Z,1531441345.112 [Reporter](INFO): platform_buoyancy_position 932.837778 cc
2018-07-13T00:22:25.528Z,1531441345.528 [Reporter](INFO): platform_buoyancy_position 935.120857 cc
2018-07-13T00:22:25.912Z,1531441345.912 [Reporter](INFO): platform_buoyancy_position 934.986572 cc
2018-07-13T00:22:26.338Z,1531441346.338 [Reporter](INFO): platform_buoyancy_position 937.135366 cc
2018-07-13T00:22:26.749Z,1531441346.749 [Reporter](INFO): platform_buoyancy_position 938.612677 cc
2018-07-13T00:22:27.084Z,1531441347.084 [Reporter](INFO): platform_buoyancy_position 939.284160 cc
2018-07-13T00:22:27.516Z,1531441347.516 [Reporter](INFO): platform_buoyancy_position 941.433012 cc
2018-07-13T00:22:28.292Z,1531441348.292 [Reporter](INFO): platform_buoyancy_position 943.716092 cc
2018-07-13T00:22:28.712Z,1531441348.712 [Reporter](INFO): platform_buoyancy_position 945.730601 cc
2018-07-13T00:22:34.972Z,1531441354.972 [CommandLine](IMPORTANT): got command get CTD_NeilBrown.loadAtStartup 
2018-07-13T00:22:34.973Z,1531441354.973 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup 1 bool
2018-07-13T00:22:47.800Z,1531441367.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.2 s old, using for 20.0 s.
2018-07-13T00:22:48.151Z,1531441368.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2018-07-13T00:22:48.562Z,1531441368.562 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-13T00:22:48.946Z,1531441368.946 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-07-13T00:22:49.339Z,1531441369.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-07-13T00:22:49.732Z,1531441369.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2018-07-13T00:22:50.108Z,1531441370.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2018-07-13T00:22:50.526Z,1531441370.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2018-07-13T00:22:50.916Z,1531441370.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-07-13T00:22:51.316Z,1531441371.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2018-07-13T00:22:51.733Z,1531441371.733 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2018-07-13T00:22:55.772Z,1531441375.772 [CommandLine](IMPORTANT): got command configSet CTD_NeilBrown.loadAtStartup 0.000000 bool persist
2018-07-13T00:22:55.773Z,1531441375.773 [CommandLine](IMPORTANT): configSet CTD_NeilBrown.loadAtStartup requires a restart to take effect.
2018-07-13T00:23:05.720Z,1531441385.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:23:06.115Z,1531441386.115 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.7 s old, using for 20.0 s.
2018-07-13T00:23:06.525Z,1531441386.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:23:06.911Z,1531441386.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.5 s old, using for 20.0 s.
2018-07-13T00:23:07.320Z,1531441387.320 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:23:07.729Z,1531441387.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:23:08.117Z,1531441388.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.7 s old, using for 20.0 s.
2018-07-13T00:23:08.532Z,1531441388.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:23:08.947Z,1531441388.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:23:09.299Z,1531441389.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.9 s old, using for 20.0 s.
2018-07-13T00:23:09.714Z,1531441389.714 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2018-07-13T00:23:10.107Z,1531441390.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s.
2018-07-13T00:23:10.520Z,1531441390.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:23:10.909Z,1531441390.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2018-07-13T00:23:11.308Z,1531441391.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2018-07-13T00:23:11.723Z,1531441391.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:23:12.111Z,1531441392.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2018-07-13T00:23:12.511Z,1531441392.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2018-07-13T00:23:12.927Z,1531441392.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:23:13.358Z,1531441393.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:23:13.756Z,1531441393.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:23:14.116Z,1531441394.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.7 s old, using for 20.0 s.
2018-07-13T00:23:14.516Z,1531441394.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.1 s old, using for 20.0 s.
2018-07-13T00:23:14.909Z,1531441394.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s.
2018-07-13T00:23:15.348Z,1531441395.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-13T00:23:15.723Z,1531441395.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-13T00:23:16.113Z,1531441396.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.7 s old, using for 20.0 s.
2018-07-13T00:23:16.522Z,1531441396.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-13T00:23:16.919Z,1531441396.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-13T00:23:17.360Z,1531441397.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-13T00:23:17.703Z,1531441397.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.3 s old, using for 20.0 s.
2018-07-13T00:23:18.118Z,1531441398.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-13T00:23:18.525Z,1531441398.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-13T00:23:18.919Z,1531441398.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-13T00:23:19.318Z,1531441399.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.9 s old, using for 20.0 s.
2018-07-13T00:23:19.736Z,1531441399.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-13T00:23:20.131Z,1531441400.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-13T00:23:20.526Z,1531441400.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-13T00:23:20.904Z,1531441400.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2018-07-13T00:23:21.299Z,1531441401.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2018-07-13T00:23:21.718Z,1531441401.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2018-07-13T00:23:22.084Z,1531441402.084 [NAL9602](INFO): SBD MO Status=2, MOMSN=32, MT Status=2, MTMSN=0
2018-07-13T00:23:22.084Z,1531441402.084 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-13T00:23:22.109Z,1531441402.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2018-07-13T00:23:22.528Z,1531441402.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-13T00:23:22.906Z,1531441402.906 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2018-07-13T00:23:23.312Z,1531441403.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2018-07-13T00:23:23.722Z,1531441403.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-13T00:23:24.164Z,1531441404.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-13T00:23:24.525Z,1531441404.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-13T00:23:24.936Z,1531441404.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-13T00:23:25.322Z,1531441405.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-07-13T00:24:06.224Z,1531441446.224 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 2.000000 millimeter persist
2018-07-13T00:24:23.068Z,1531441463.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:24:23.461Z,1531441463.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:24:23.860Z,1531441463.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:24:24.262Z,1531441464.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:24:24.669Z,1531441464.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:24:25.059Z,1531441465.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:24:25.462Z,1531441465.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:24:25.856Z,1531441465.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:24:26.249Z,1531441466.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:24:26.661Z,1531441466.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:24:26.704Z,1531441466.704 [CBIT](INFO): Clearing failed state for component CTD_NeilBrown
2018-07-13T00:24:26.704Z,1531441466.704 [CTD_NeilBrown] No Fault, FailCount= 3
2018-07-13T00:24:27.053Z,1531441467.053 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-07-13T00:24:27.054Z,1531441467.054 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-07-13T00:24:27.064Z,1531441467.064 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:24:27.446Z,1531441467.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:24:27.856Z,1531441467.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:24:28.239Z,1531441468.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:24:28.658Z,1531441468.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:24:29.040Z,1531441469.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:24:29.444Z,1531441469.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:24:29.840Z,1531441469.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:24:30.517Z,1531441470.517 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:30.595Z,1531441470.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s.
2018-07-13T00:24:30.987Z,1531441470.987 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:31.079Z,1531441471.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:24:31.449Z,1531441471.449 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:31.531Z,1531441471.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-07-13T00:24:31.909Z,1531441471.909 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:31.986Z,1531441471.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-07-13T00:24:32.369Z,1531441472.369 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:32.448Z,1531441472.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s.
2018-07-13T00:24:32.841Z,1531441472.841 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:32.919Z,1531441472.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2018-07-13T00:24:33.313Z,1531441473.313 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:33.390Z,1531441473.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.7 s old, using for 20.0 s.
2018-07-13T00:24:33.793Z,1531441473.793 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:33.881Z,1531441473.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-13T00:24:34.257Z,1531441474.257 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:34.342Z,1531441474.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-07-13T00:24:34.728Z,1531441474.728 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:34.805Z,1531441474.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2018-07-13T00:24:35.185Z,1531441475.185 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-07-13T00:24:35.201Z,1531441475.201 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2018-07-13T00:24:35.201Z,1531441475.201 [AHRS_M2] Data Fault, FailCount= 1
2018-07-13T00:24:35.201Z,1531441475.201 [AHRS_M2](ERROR): Data Fault
2018-07-13T00:24:35.295Z,1531441475.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.494463 seconds old.
2018-07-13T00:24:35.296Z,1531441475.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2018-07-13T00:24:35.303Z,1531441475.303 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.495572 seconds old.
2018-07-13T00:24:35.348Z,1531441475.348 [CBIT](ERROR): Data Fault in component: AHRS_M2
2018-07-13T00:24:35.369Z,1531441475.369 [AHRS_M2](INFO): Powering down
2018-07-13T00:24:35.476Z,1531441475.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.675001 seconds old.
2018-07-13T00:24:35.476Z,1531441475.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-13T00:24:35.479Z,1531441475.479 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.670963 seconds old.
2018-07-13T00:24:35.799Z,1531441475.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.998236 seconds old.
2018-07-13T00:24:35.799Z,1531441475.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.1 s old, using for 20.0 s.
2018-07-13T00:24:35.802Z,1531441475.802 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.994360 seconds old.
2018-07-13T00:24:36.234Z,1531441476.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.433983 seconds old.
2018-07-13T00:24:36.235Z,1531441476.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-13T00:24:36.238Z,1531441476.238 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.430094 seconds old.
2018-07-13T00:24:36.613Z,1531441476.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.812098 seconds old.
2018-07-13T00:24:36.613Z,1531441476.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.9 s old, using for 20.0 s.
2018-07-13T00:24:36.616Z,1531441476.616 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.808053 seconds old.
2018-07-13T00:24:36.999Z,1531441476.999 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-13T00:24:36.999Z,1531441476.999 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-13T00:24:36.000Z,1531441477.000 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-13T00:24:37.008Z,1531441477.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.207063 seconds old.
2018-07-13T00:24:37.008Z,1531441477.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.3 s old, using for 20.0 s.
2018-07-13T00:24:37.011Z,1531441477.011 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.203153 seconds old.
2018-07-13T00:24:37.076Z,1531441477.076 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-13T00:24:37.461Z,1531441477.461 [RDI_Pathfinder](INFO): Powering down
2018-07-13T00:24:37.475Z,1531441477.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.674261 seconds old.
2018-07-13T00:24:37.475Z,1531441477.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-13T00:24:37.478Z,1531441477.478 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.670383 seconds old.
2018-07-13T00:24:37.546Z,1531441477.546 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-07-13T00:24:37.546Z,1531441477.546 [AHRS_M2] No Fault, FailCount= 1
2018-07-13T00:24:37.766Z,1531441477.766 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-13T00:24:37.799Z,1531441477.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.998792 seconds old.
2018-07-13T00:24:37.800Z,1531441477.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2018-07-13T00:24:37.825Z,1531441477.825 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.017646 seconds old.
2018-07-13T00:24:38.199Z,1531441478.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.398217 seconds old.
2018-07-13T00:24:38.199Z,1531441478.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2018-07-13T00:24:38.218Z,1531441478.218 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.410286 seconds old.
2018-07-13T00:24:38.250Z,1531441478.250 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-13T00:24:38.250Z,1531441478.250 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-13T00:24:38.603Z,1531441478.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.802409 seconds old.
2018-07-13T00:24:38.604Z,1531441478.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2018-07-13T00:24:38.622Z,1531441478.622 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.814510 seconds old.
2018-07-13T00:24:38.743Z,1531441478.743 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 100.000000 cubic_centimeter persist
2018-07-13T00:24:38.999Z,1531441478.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.198961 seconds old.
2018-07-13T00:24:39.000Z,1531441479.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2018-07-13T00:24:39.003Z,1531441479.003 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.194945 seconds old.
2018-07-13T00:24:39.432Z,1531441479.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.631062 seconds old.
2018-07-13T00:24:39.432Z,1531441479.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-13T00:24:39.435Z,1531441479.435 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.627179 seconds old.
2018-07-13T00:24:39.803Z,1531441479.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.002905 seconds old.
2018-07-13T00:24:39.804Z,1531441479.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2018-07-13T00:24:39.807Z,1531441479.807 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.999002 seconds old.
2018-07-13T00:24:40.203Z,1531441480.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.402045 seconds old.
2018-07-13T00:24:40.203Z,1531441480.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2018-07-13T00:24:40.206Z,1531441480.206 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.398129 seconds old.
2018-07-13T00:24:40.618Z,1531441480.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.817938 seconds old.
2018-07-13T00:24:40.619Z,1531441480.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2018-07-13T00:24:40.638Z,1531441480.638 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.830086 seconds old.
2018-07-13T00:24:41.051Z,1531441481.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.250536 seconds old.
2018-07-13T00:24:41.052Z,1531441481.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-13T00:24:41.054Z,1531441481.054 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.246659 seconds old.
2018-07-13T00:24:41.444Z,1531441481.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.643119 seconds old.
2018-07-13T00:24:41.444Z,1531441481.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-13T00:24:41.447Z,1531441481.447 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.639066 seconds old.
2018-07-13T00:24:41.855Z,1531441481.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.054690 seconds old.
2018-07-13T00:24:41.856Z,1531441481.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-13T00:24:41.859Z,1531441481.859 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.050893 seconds old.
2018-07-13T00:24:42.241Z,1531441482.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.440083 seconds old.
2018-07-13T00:24:42.241Z,1531441482.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-13T00:24:42.244Z,1531441482.244 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.436230 seconds old.
2018-07-13T00:24:42.622Z,1531441482.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.821928 seconds old.
2018-07-13T00:24:42.623Z,1531441482.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-07-13T00:24:42.648Z,1531441482.648 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.839910 seconds old.
2018-07-13T00:24:43.040Z,1531441483.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.239714 seconds old.
2018-07-13T00:24:43.043Z,1531441483.043 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.235311 seconds old.
2018-07-13T00:24:43.407Z,1531441483.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 8.606843 seconds old.
2018-07-13T00:24:43.410Z,1531441483.410 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.602459 seconds old.
2018-07-13T00:24:43.807Z,1531441483.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.006836 seconds old.
2018-07-13T00:24:43.810Z,1531441483.810 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.002315 seconds old.
2018-07-13T00:24:44.204Z,1531441484.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 9.403434 seconds old.
2018-07-13T00:24:44.207Z,1531441484.207 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.398891 seconds old.
2018-07-13T00:24:47.403Z,1531441487.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 12.602226 seconds old.
2018-07-13T00:24:47.406Z,1531441487.406 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.597873 seconds old.
2018-07-13T00:24:47.567Z,1531441487.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 12.765962 seconds old.
2018-07-13T00:24:47.569Z,1531441487.569 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.761392 seconds old.
2018-07-13T00:24:47.925Z,1531441487.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.124247 seconds old.
2018-07-13T00:24:47.928Z,1531441487.928 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.119876 seconds old.
2018-07-13T00:24:48.261Z,1531441488.261 [AHRS_M2](FAULT): Failed to initialize
2018-07-13T00:24:48.261Z,1531441488.261 [AHRS_M2] Communications Fault, FailCount= 2
2018-07-13T00:24:48.261Z,1531441488.261 [AHRS_M2](ERROR): Communications Fault
2018-07-13T00:24:48.334Z,1531441488.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.533858 seconds old.
2018-07-13T00:24:48.340Z,1531441488.340 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.531904 seconds old.
2018-07-13T00:24:48.372Z,1531441488.372 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-07-13T00:24:48.634Z,1531441488.634 [AHRS_M2](INFO): Powering down
2018-07-13T00:24:48.744Z,1531441488.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 13.943455 seconds old.
2018-07-13T00:24:48.766Z,1531441488.766 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 13.958267 seconds old.
2018-07-13T00:24:49.075Z,1531441489.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.274831 seconds old.
2018-07-13T00:24:49.078Z,1531441489.078 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.270489 seconds old.
2018-07-13T00:24:49.478Z,1531441489.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 14.677580 seconds old.
2018-07-13T00:24:49.481Z,1531441489.481 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 14.673152 seconds old.
2018-07-13T00:24:49.878Z,1531441489.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.077446 seconds old.
2018-07-13T00:24:49.881Z,1531441489.881 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.072833 seconds old.
2018-07-13T00:24:50.279Z,1531441490.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.478229 seconds old.
2018-07-13T00:24:50.282Z,1531441490.282 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.473979 seconds old.
2018-07-13T00:24:50.680Z,1531441490.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 15.879657 seconds old.
2018-07-13T00:24:50.683Z,1531441490.683 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 15.875295 seconds old.
2018-07-13T00:24:50.699Z,1531441490.699 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-07-13T00:24:50.699Z,1531441490.699 [AHRS_M2] No Fault, FailCount= 2
2018-07-13T00:24:51.034Z,1531441491.034 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-13T00:24:51.078Z,1531441491.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 16.277902 seconds old.
2018-07-13T00:24:51.081Z,1531441491.081 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 16.273378 seconds old.
2018-07-13T00:24:51.475Z,1531441491.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 16.674111 seconds old.
2018-07-13T00:24:51.482Z,1531441491.482 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 16.674414 seconds old.
2018-07-13T00:24:51.890Z,1531441491.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 17.089274 seconds old.
2018-07-13T00:24:51.893Z,1531441491.893 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.084660 seconds old.
2018-07-13T00:24:52.296Z,1531441492.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 17.495834 seconds old.
2018-07-13T00:24:52.299Z,1531441492.299 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 17.491294 seconds old.
2018-07-13T00:24:52.991Z,1531441492.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 18.190918 seconds old.
2018-07-13T00:24:52.994Z,1531441492.994 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 18.186566 seconds old.
2018-07-13T00:24:53.140Z,1531441493.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 18.339256 seconds old.
2018-07-13T00:24:53.147Z,1531441493.147 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 18.338873 seconds old.
2018-07-13T00:24:53.500Z,1531441493.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 18.684092 seconds old.
2018-07-13T00:24:53.503Z,1531441493.503 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 18.695392 seconds old.
2018-07-13T00:24:53.902Z,1531441493.902 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 19.101052 seconds old.
2018-07-13T00:24:53.904Z,1531441493.904 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.096476 seconds old.
2018-07-13T00:24:54.304Z,1531441494.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 19.503125 seconds old.
2018-07-13T00:24:54.307Z,1531441494.307 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.498770 seconds old.
2018-07-13T00:24:54.696Z,1531441494.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 19.895126 seconds old.
2018-07-13T00:24:54.698Z,1531441494.698 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 19.890608 seconds old.
2018-07-13T00:24:55.114Z,1531441495.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 20.313710 seconds old.
2018-07-13T00:24:55.118Z,1531441495.118 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.309997 seconds old.
2018-07-13T00:24:55.507Z,1531441495.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 20.706241 seconds old.
2018-07-13T00:24:55.510Z,1531441495.510 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 20.701875 seconds old.
2018-07-13T00:24:55.913Z,1531441495.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 21.112708 seconds old.
2018-07-13T00:24:55.916Z,1531441495.916 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.108101 seconds old.
2018-07-13T00:24:56.303Z,1531441496.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 21.502011 seconds old.
2018-07-13T00:24:56.305Z,1531441496.305 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.497614 seconds old.
2018-07-13T00:24:56.705Z,1531441496.705 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 21.900261 seconds old.
2018-07-13T00:24:56.709Z,1531441496.709 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 21.901587 seconds old.
2018-07-13T00:24:57.105Z,1531441497.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 22.304460 seconds old.
2018-07-13T00:24:57.138Z,1531441497.138 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 22.330225 seconds old.
2018-07-13T00:24:57.503Z,1531441497.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 22.702067 seconds old.
2018-07-13T00:24:57.516Z,1531441497.516 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 22.707766 seconds old.
2018-07-13T00:24:57.926Z,1531441497.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.125081 seconds old.
2018-07-13T00:24:57.928Z,1531441497.928 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.120482 seconds old.
2018-07-13T00:24:58.320Z,1531441498.320 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.519759 seconds old.
2018-07-13T00:24:58.325Z,1531441498.325 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.517304 seconds old.
2018-07-13T00:24:58.707Z,1531441498.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 23.906119 seconds old.
2018-07-13T00:24:58.710Z,1531441498.710 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 23.901764 seconds old.
2018-07-13T00:24:59.120Z,1531441499.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 24.319790 seconds old.
2018-07-13T00:24:59.125Z,1531441499.125 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 24.317586 seconds old.
2018-07-13T00:24:59.490Z,1531441499.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 24.689470 seconds old.
2018-07-13T00:24:59.515Z,1531441499.515 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 24.684873 seconds old.
2018-07-13T00:24:59.924Z,1531441499.924 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 25.123090 seconds old.
2018-07-13T00:24:59.931Z,1531441499.931 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.122908 seconds old.
2018-07-13T00:25:00.311Z,1531441500.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 25.510094 seconds old.
2018-07-13T00:25:00.314Z,1531441500.314 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.505699 seconds old.
2018-07-13T00:25:00.679Z,1531441500.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 25.878171 seconds old.
2018-07-13T00:25:00.682Z,1531441500.682 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 25.873664 seconds old.
2018-07-13T00:25:01.088Z,1531441501.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 26.287845 seconds old.
2018-07-13T00:25:01.091Z,1531441501.091 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 26.283394 seconds old.
2018-07-13T00:25:01.486Z,1531441501.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 26.685588 seconds old.
2018-07-13T00:25:01.489Z,1531441501.489 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 26.680976 seconds old.
2018-07-13T00:25:01.899Z,1531441501.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 27.098708 seconds old.
2018-07-13T00:25:01.902Z,1531441501.902 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 27.094226 seconds old.
2018-07-13T00:25:02.270Z,1531441502.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 27.469215 seconds old.
2018-07-13T00:25:02.272Z,1531441502.272 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 27.464632 seconds old.
2018-07-13T00:25:02.680Z,1531441502.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 27.879359 seconds old.
2018-07-13T00:25:02.683Z,1531441502.683 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 27.874971 seconds old.
2018-07-13T00:25:03.106Z,1531441503.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 28.305610 seconds old.
2018-07-13T00:25:03.109Z,1531441503.109 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.301086 seconds old.
2018-07-13T00:25:03.501Z,1531441503.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 28.700794 seconds old.
2018-07-13T00:25:03.504Z,1531441503.504 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 28.696198 seconds old.
2018-07-13T00:25:36.458Z,1531441536.458 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitHiCC 1075.000000 cubic_centimeter persist
2018-07-13T00:25:40.698Z,1531441540.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:25:41.103Z,1531441541.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:25:41.208Z,1531441541.208 [Reporter](INFO): platform_buoyancy_position 952.311268 cc
2018-07-13T00:25:41.487Z,1531441541.487 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:25:41.901Z,1531441541.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:25:42.293Z,1531441542.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:25:42.621Z,1531441542.621 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-07-13T00:25:42.621Z,1531441542.621 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-07-13T00:25:42.621Z,1531441542.621 [BuoyancyServo] Communications Fault, FailCount= 2
2018-07-13T00:25:42.621Z,1531441542.621 [BuoyancyServo](ERROR): Communications Fault
2018-07-13T00:25:42.627Z,1531441542.627 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-13T00:25:42.743Z,1531441542.743 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:25:42.764Z,1531441542.764 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-13T00:25:42.769Z,1531441542.769 [BuoyancyServo](INFO): Powering down
2018-07-13T00:25:43.112Z,1531441543.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:25:43.499Z,1531441543.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:25:43.909Z,1531441543.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:25:44.304Z,1531441544.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:25:44.318Z,1531441544.318 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-07-13T00:25:44.318Z,1531441544.318 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-07-13T00:25:44.318Z,1531441544.318 [BuoyancyServo](ERROR): Hardware Fault
2018-07-13T00:25:47.590Z,1531441547.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-07-13T00:25:47.633Z,1531441547.633 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-13T00:25:47.633Z,1531441547.633 [BuoyancyServo] No Fault, FailCount= 2
2018-07-13T00:25:47.770Z,1531441547.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.5 s old, using for 20.0 s.
2018-07-13T00:25:47.795Z,1531441547.795 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:25:47.914Z,1531441547.914 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-13T00:25:48.129Z,1531441548.129 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.8 s old, using for 20.0 s.
2018-07-13T00:25:48.525Z,1531441548.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.2 s old, using for 20.0 s.
2018-07-13T00:25:48.912Z,1531441548.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.6 s old, using for 20.0 s.
2018-07-13T00:25:49.310Z,1531441549.310 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.0 s old, using for 20.0 s.
2018-07-13T00:25:49.699Z,1531441549.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s.
2018-07-13T00:25:50.107Z,1531441550.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s.
2018-07-13T00:25:50.509Z,1531441550.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2018-07-13T00:25:50.916Z,1531441550.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-07-13T00:25:51.016Z,1531441551.016 [Reporter](INFO): platform_buoyancy_position 952.176983 cc
2018-07-13T00:25:51.321Z,1531441551.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2018-07-13T00:25:51.401Z,1531441551.401 [Reporter](INFO): platform_buoyancy_position 953.117094 cc
2018-07-13T00:25:51.687Z,1531441551.687 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-07-13T00:25:51.756Z,1531441551.756 [Reporter](INFO): platform_buoyancy_position 952.311268 cc
2018-07-13T00:25:52.136Z,1531441552.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-07-13T00:25:52.473Z,1531441552.473 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-07-13T00:25:52.473Z,1531441552.473 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-07-13T00:25:52.473Z,1531441552.473 [BuoyancyServo] Communications Fault, FailCount= 3
2018-07-13T00:25:52.473Z,1531441552.473 [BuoyancyServo](ERROR): Communications Fault
2018-07-13T00:25:52.479Z,1531441552.479 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-13T00:25:52.616Z,1531441552.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.3 s old, using for 20.0 s.
2018-07-13T00:25:52.650Z,1531441552.650 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-13T00:25:52.651Z,1531441552.651 [BuoyancyServo](INFO): Powering down
2018-07-13T00:25:52.961Z,1531441552.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.7 s old, using for 20.0 s.
2018-07-13T00:25:53.348Z,1531441553.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2018-07-13T00:25:53.747Z,1531441553.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2018-07-13T00:25:54.167Z,1531441554.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.9 s old, using for 20.0 s.
2018-07-13T00:25:54.191Z,1531441554.191 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-07-13T00:25:54.191Z,1531441554.191 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-07-13T00:25:54.191Z,1531441554.191 [BuoyancyServo](ERROR): Hardware Fault
2018-07-13T00:25:54.605Z,1531441554.605 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-13T00:25:54.605Z,1531441554.605 [BuoyancyServo] No Fault, FailCount= 3
2018-07-13T00:25:54.994Z,1531441554.994 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-13T00:25:55.114Z,1531441555.114 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-13T00:25:58.246Z,1531441558.246 [Reporter](INFO): platform_buoyancy_position 952.579838 cc
2018-07-13T00:25:58.612Z,1531441558.612 [Reporter](INFO): platform_buoyancy_position 952.445553 cc
2018-07-13T00:25:59.028Z,1531441559.028 [Reporter](INFO): platform_buoyancy_position 954.325835 cc
2018-07-13T00:25:59.393Z,1531441559.393 [Reporter](INFO): platform_buoyancy_position 952.176983 cc
2018-07-13T00:25:59.831Z,1531441559.831 [Reporter](INFO): platform_buoyancy_position 958.623306 cc
2018-07-13T00:26:00.208Z,1531441560.208 [Reporter](INFO): platform_buoyancy_position 965.204090 cc
2018-07-13T00:26:00.632Z,1531441560.632 [Reporter](INFO): platform_buoyancy_position 971.516129 cc
2018-07-13T00:26:01.036Z,1531441561.036 [Reporter](INFO): platform_buoyancy_position 975.813717 cc
2018-07-13T00:26:01.424Z,1531441561.424 [Reporter](INFO): platform_buoyancy_position 982.260099 cc
2018-07-13T00:26:01.817Z,1531441561.817 [Reporter](INFO): platform_buoyancy_position 986.692030 cc
2018-07-13T00:26:02.228Z,1531441562.228 [Reporter](INFO): platform_buoyancy_position 993.004185 cc
2018-07-13T00:26:02.600Z,1531441562.600 [Reporter](INFO): platform_buoyancy_position 997.301773 cc
2018-07-13T00:26:03.020Z,1531441563.020 [Reporter](INFO): platform_buoyancy_position 1003.748155 cc
2018-07-13T00:26:03.392Z,1531441563.392 [Reporter](INFO): platform_buoyancy_position 1008.180087 cc
2018-07-13T00:26:03.784Z,1531441563.784 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyDefault 1050.000000 cubic_centimeter persist
2018-07-13T00:26:03.834Z,1531441563.834 [Reporter](INFO): platform_buoyancy_position 1014.626469 cc
2018-07-13T00:26:04.236Z,1531441564.236 [Reporter](INFO): platform_buoyancy_position 1018.924057 cc
2018-07-13T00:26:04.601Z,1531441564.601 [Reporter](INFO): platform_buoyancy_position 1023.355988 cc
2018-07-13T00:26:05.012Z,1531441565.012 [Reporter](INFO): platform_buoyancy_position 1029.533800 cc
2018-07-13T00:26:05.408Z,1531441565.408 [Reporter](INFO): platform_buoyancy_position 1034.099958 cc
2018-07-13T00:26:05.808Z,1531441565.808 [Reporter](INFO): platform_buoyancy_position 1040.412113 cc
2018-07-13T00:26:06.228Z,1531441566.228 [Reporter](INFO): platform_buoyancy_position 1044.709701 cc
2018-07-13T00:26:06.608Z,1531441566.608 [Reporter](INFO): platform_buoyancy_position 1051.021740 cc
2018-07-13T00:26:07.013Z,1531441567.013 [Reporter](INFO): platform_buoyancy_position 1055.319328 cc
2018-07-13T00:26:07.406Z,1531441567.406 [Reporter](INFO): platform_buoyancy_position 1060.019946 cc
2018-07-13T00:26:07.848Z,1531441567.848 [Reporter](INFO): platform_buoyancy_position 1066.197641 cc
2018-07-13T00:26:25.414Z,1531441585.414 [CommandLine](IMPORTANT): got command configSet BuoyancyServo.offsetVolume 200.000000 cubic_centimeter persist
2018-07-13T00:26:29.362Z,1531441589.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:26:29.752Z,1531441589.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:26:30.158Z,1531441590.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:26:30.552Z,1531441590.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:26:30.960Z,1531441590.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:26:31.367Z,1531441591.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:26:31.739Z,1531441591.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:26:32.166Z,1531441592.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:26:32.538Z,1531441592.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:26:32.979Z,1531441592.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:26:33.367Z,1531441593.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:26:33.741Z,1531441593.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:26:34.167Z,1531441594.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:26:34.549Z,1531441594.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:26:34.962Z,1531441594.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:26:35.356Z,1531441595.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:26:35.752Z,1531441595.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:26:36.194Z,1531441596.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:26:36.562Z,1531441596.562 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:26:36.956Z,1531441596.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:26:37.354Z,1531441597.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:26:37.763Z,1531441597.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-13T00:26:38.168Z,1531441598.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-13T00:26:38.565Z,1531441598.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-13T00:26:38.976Z,1531441598.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-13T00:26:39.372Z,1531441599.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-13T00:26:39.758Z,1531441599.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-13T00:26:40.164Z,1531441600.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-13T00:26:40.566Z,1531441600.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-13T00:26:40.937Z,1531441600.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-13T00:26:41.367Z,1531441601.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-13T00:26:41.771Z,1531441601.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-13T00:26:42.192Z,1531441602.192 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-13T00:26:42.563Z,1531441602.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-13T00:26:42.963Z,1531441602.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-13T00:26:43.364Z,1531441603.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-13T00:26:43.765Z,1531441603.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-13T00:26:44.164Z,1531441604.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-13T00:26:44.542Z,1531441604.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-13T00:26:46.301Z,1531441606.301 [CommandLine](IMPORTANT): got command configSet BuoyancyServo.limitLo 1200.000000 count persist
2018-07-13T00:26:47.948Z,1531441607.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2018-07-13T00:26:48.122Z,1531441608.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2018-07-13T00:26:48.483Z,1531441608.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2018-07-13T00:26:48.901Z,1531441608.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2018-07-13T00:27:04.479Z,1531441624.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-13T00:27:04.868Z,1531441624.868 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-13T00:27:05.294Z,1531441625.294 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-13T00:27:05.677Z,1531441625.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-13T00:27:06.090Z,1531441626.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-13T00:27:06.456Z,1531441626.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-13T00:27:06.881Z,1531441626.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-13T00:27:07.277Z,1531441627.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-13T00:27:07.672Z,1531441627.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-13T00:27:08.088Z,1531441628.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-13T00:27:08.459Z,1531441628.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-13T00:27:08.880Z,1531441628.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-13T00:27:09.264Z,1531441629.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-13T00:27:09.671Z,1531441629.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-13T00:27:10.078Z,1531441630.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-13T00:27:10.476Z,1531441630.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-13T00:27:10.880Z,1531441630.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-13T00:27:11.276Z,1531441631.276 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-13T00:27:11.691Z,1531441631.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-13T00:27:12.084Z,1531441632.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-13T00:27:12.479Z,1531441632.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-13T00:27:12.856Z,1531441632.856 [CommandLine](IMPORTANT): got command configSet BuoyancyServo.limitHi 9600.000000 count persist
2018-07-13T00:27:12.909Z,1531441632.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-13T00:27:12.920Z,1531441632.920 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-13T00:22:12.7Z
2018-07-13T00:27:12.922Z,1531441632.922 [Default:CheckIn:Read_GPS] Stopped
2018-07-13T00:27:12.922Z,1531441632.922 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-13T00:27:13.256Z,1531441633.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-13T00:27:13.688Z,1531441633.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-13T00:27:14.097Z,1531441634.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-13T00:27:17.448Z,1531441637.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2018-07-13T00:27:17.609Z,1531441637.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.5 s old, using for 20.0 s.
2018-07-13T00:27:17.910Z,1531441637.910 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20180713T001047/Courier0007.lzma
2018-07-13T00:27:18.042Z,1531441638.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-13T00:27:18.380Z,1531441638.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.3 s old, using for 20.0 s.
2018-07-13T00:27:18.707Z,1531441638.707 [DataOverHttps](INFO): Moved sent file to Logs/20180713T001047/Courier0007.lzma.bak
2018-07-13T00:27:18.708Z,1531441638.708 [DataOverHttps](INFO): SBD MOMSN=8367032
2018-07-13T00:27:18.814Z,1531441638.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.7 s old, using for 20.0 s.
2018-07-13T00:27:19.200Z,1531441639.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2018-07-13T00:27:19.564Z,1531441639.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2018-07-13T00:27:20.775Z,1531441640.775 [CommandLine](IMPORTANT): got command restart application
2018-07-13T00:27:21.780Z,1531441641.780 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:21.780Z,1531441641.780 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:21.905Z,1531441641.905 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-07-13T00:27:21.905Z,1531441641.905 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:21.906Z,1531441641.906 [CommandLine](INFO): Join timeout helper Thread ID is 1129
2018-07-13T00:27:21.913Z,1531441641.913 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-07-13T00:27:21.913Z,1531441641.913 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:21.913Z,1531441641.913 [NavChartDb](INFO): Join timeout helper Thread ID is 1130
2018-07-13T00:27:21.921Z,1531441641.921 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:21.921Z,1531441641.921 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:21.922Z,1531441641.922 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-07-13T00:27:21.922Z,1531441641.922 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:21.922Z,1531441641.922 [Radio_Surface](INFO): Join timeout helper Thread ID is 1131
2018-07-13T00:27:22.213Z,1531441642.213 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:22.213Z,1531441642.213 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.233Z,1531441642.233 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-07-13T00:27:22.233Z,1531441642.233 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.233Z,1531441642.233 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1132
2018-07-13T00:27:22.461Z,1531441642.461 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:22.461Z,1531441642.461 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-07-13T00:27:22.462Z,1531441642.462 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.477Z,1531441642.477 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-07-13T00:27:22.477Z,1531441642.477 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.477Z,1531441642.477 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1133
2018-07-13T00:27:22.681Z,1531441642.681 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:22.681Z,1531441642.681 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.686Z,1531441642.686 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-07-13T00:27:22.686Z,1531441642.686 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.686Z,1531441642.686 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1134
2018-07-13T00:27:22.769Z,1531441642.769 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:22.769Z,1531441642.769 [CTD_NeilBrown](INFO): Powering down
2018-07-13T00:27:22.770Z,1531441642.770 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.774Z,1531441642.774 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-07-13T00:27:22.774Z,1531441642.774 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.774Z,1531441642.774 [logger](INFO): Join timeout helper Thread ID is 1135
2018-07-13T00:27:22.785Z,1531441642.785 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:22.786Z,1531441642.786 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.803Z,1531441642.803 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-07-13T00:27:22.803Z,1531441642.803 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.803Z,1531441642.803 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-07-13T00:27:22.803Z,1531441642.803 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:22.804Z,1531441642.804 [controlThread](INFO): Join timeout helper Thread ID is 1136
2018-07-13T00:27:22.896Z,1531441642.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-13T00:27:22.916Z,1531441642.916 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-13T00:27:22.916Z,1531441642.916 [controlThread](DEBUG): Uninitializing ControlThread
2018-07-13T00:27:22.917Z,1531441642.917 [AHRS_M2](INFO): Powering down
2018-07-13T00:27:22.993Z,1531441642.993 [NAL9602](INFO): Powering down
2018-07-13T00:27:23.065Z,1531441643.065 [RDI_Pathfinder](INFO): Powering down
2018-07-13T00:27:23.067Z,1531441643.067 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-13T00:27:23.068Z,1531441643.068 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-07-13T00:27:23.069Z,1531441643.069 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-07-13T00:27:23.069Z,1531441643.069 [MissionManager](INFO): Uninitializing Mission Default
2018-07-13T00:27:23.069Z,1531441643.069 [Default] Stopped
2018-07-13T00:27:23.070Z,1531441643.070 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-13T00:27:23.070Z,1531441643.070 [Default:B.GoToSurface] Stopped
2018-07-13T00:27:23.070Z,1531441643.070 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-13T00:27:23.070Z,1531441643.070 [Default:CheckIn] Stopped
2018-07-13T00:27:23.070Z,1531441643.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-13T00:27:23.070Z,1531441643.070 [Default:CheckIn:Read_Iridium] Stopped
2018-07-13T00:27:23.073Z,1531441643.073 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-07-13T00:27:23.074Z,1531441643.074 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-07-13T00:27:23.074Z,1531441643.074 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-07-13T00:27:23.074Z,1531441643.074 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-07-13T00:27:23.074Z,1531441643.074 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-13T00:27:23.075Z,1531441643.075 [BuoyancyServo](INFO): Powering down
2018-07-13T00:27:23.089Z,1531441643.089 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-07-13T00:27:23.089Z,1531441643.089 [ElevatorServo](INFO): Powering down
2018-07-13T00:27:23.090Z,1531441643.090 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-07-13T00:27:23.090Z,1531441643.090 [MassServo](INFO): Powering down
2018-07-13T00:27:23.091Z,1531441643.091 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-07-13T00:27:23.091Z,1531441643.091 [RudderServo](INFO): Powering down
2018-07-13T00:27:23.092Z,1531441643.092 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-13T00:27:23.092Z,1531441643.092 [ThrusterServo](INFO): Powering down
2018-07-13T00:27:23.093Z,1531441643.093 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-07-13T00:27:23.093Z,1531441643.093 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-07-13T00:27:23.094Z,1531441643.094 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-07-13T00:27:23.095Z,1531441643.095 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:23.160Z,1531441643.160 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:23.163Z,1531441643.163 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:23.166Z,1531441643.166 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:23.229Z,1531441643.229 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:23.271Z,1531441643.271 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-13T00:27:23.339Z,1531441643.339 [logger ThreadHandler](INFO): Thread cancelled.