2019-05-03T17:20:02.950Z,1556904002.950 [Supervisor](DEBUG): Initializing supervisor.
2019-05-03T17:20:02.953Z,1556904002.953 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-03T17:20:02.953Z,1556904002.953 [SyncHandler](INFO): Protected caller Thread ID is 2637
2019-05-03T17:20:02.954Z,1556904002.954 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-03T17:20:02.955Z,1556904002.955 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-03T17:20:02.955Z,1556904002.955 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2638
2019-05-03T17:20:02.958Z,1556904002.958 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-03T17:20:02.970Z,1556904002.970 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-03T17:20:02.971Z,1556904002.971 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-03T17:20:02.971Z,1556904002.971 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2639
2019-05-03T17:20:02.972Z,1556904002.972 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-03T17:20:02.973Z,1556904002.973 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-03T17:20:02.974Z,1556904002.974 [logger ThreadHandler](INFO): Protected caller Thread ID is 2640
2019-05-03T17:20:02.976Z,1556904002.976 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-03T17:20:02.976Z,1556904002.976 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-03T17:20:02.982Z,1556904002.982 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-03T17:20:03.517Z,1556904003.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-03T17:20:03.518Z,1556904003.518 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-03T17:20:03.740Z,1556904003.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-03T17:20:03.740Z,1556904003.740 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-03T17:20:03.889Z,1556904003.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-03T17:20:03.890Z,1556904003.890 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-03T17:20:04.223Z,1556904004.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-03T17:20:04.224Z,1556904004.224 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-03T17:20:04.596Z,1556904004.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-03T17:20:04.596Z,1556904004.596 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-03T17:20:04.696Z,1556904004.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-03T17:20:04.697Z,1556904004.697 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-03T17:20:04.855Z,1556904004.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-03T17:20:04.856Z,1556904004.856 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-03T17:20:05.364Z,1556904005.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-03T17:20:05.365Z,1556904005.365 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-03T17:20:05.787Z,1556904005.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-03T17:20:05.788Z,1556904005.788 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-03T17:20:05.871Z,1556904005.871 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-03T17:20:06.094Z,1556904006.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-03T17:20:06.095Z,1556904006.095 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-03T17:20:06.204Z,1556904006.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-03T17:20:06.205Z,1556904006.205 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-03T17:20:06.765Z,1556904006.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-03T17:20:06.765Z,1556904006.765 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-03T17:20:06.871Z,1556904006.871 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-03T17:20:06.872Z,1556904006.872 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-03T17:20:07.224Z,1556904007.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-03T17:20:07.226Z,1556904007.226 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2019-05-03T17:20:07.950Z,1556904007.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2019-05-03T17:20:08.150Z,1556904008.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2019-05-03T17:20:08.255Z,1556904008.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2019-05-03T17:20:08.371Z,1556904008.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2019-05-03T17:20:08.478Z,1556904008.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2019-05-03T17:20:08.563Z,1556904008.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2019-05-03T17:20:08.652Z,1556904008.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2019-05-03T17:20:08.775Z,1556904008.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2019-05-03T17:20:08.880Z,1556904008.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2019-05-03T17:20:09.048Z,1556904009.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2019-05-03T17:20:09.286Z,1556904009.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-03T17:20:09.287Z,1556904009.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2019-05-03T17:20:09.386Z,1556904009.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2019-05-03T17:20:09.387Z,1556904009.387 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-03T17:20:09.395Z,1556904009.395 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-03T17:20:09.672Z,1556904009.672 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-03T17:20:09.672Z,1556904009.672 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-03T17:20:09.791Z,1556904009.791 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-03T17:20:09.792Z,1556904009.792 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-03T17:20:09.851Z,1556904009.851 [DeadReckonUsingSpeedCalculator] Loaded
2019-05-03T17:20:09.851Z,1556904009.851 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-05-03T17:20:09.872Z,1556904009.872 [NavChart] Loaded
2019-05-03T17:20:09.873Z,1556904009.873 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-03T17:20:09.877Z,1556904009.877 [UniversalFixResidualReporter] Loaded
2019-05-03T17:20:09.877Z,1556904009.877 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-03T17:20:09.878Z,1556904009.878 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-03T17:20:09.878Z,1556904009.878 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-03T17:20:09.897Z,1556904009.897 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-03T17:20:09.898Z,1556904009.898 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-03T17:20:10.222Z,1556904010.222 [CTD_NeilBrown] Loaded
2019-05-03T17:20:10.223Z,1556904010.223 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-03T17:20:10.224Z,1556904010.224 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4065D4E0
2019-05-03T17:20:10.224Z,1556904010.224 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2719
2019-05-03T17:20:10.226Z,1556904010.226 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-03T17:20:10.226Z,1556904010.226 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-03T17:20:10.310Z,1556904010.310 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-03T17:20:10.427Z,1556904010.427 [VerticalControl] Loaded
2019-05-03T17:20:10.427Z,1556904010.427 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-03T17:20:10.428Z,1556904010.428 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-03T17:20:10.498Z,1556904010.498 [HorizontalControl] Loaded
2019-05-03T17:20:10.498Z,1556904010.498 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-03T17:20:10.499Z,1556904010.499 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-03T17:20:10.504Z,1556904010.504 [SpeedControl] Loaded
2019-05-03T17:20:10.505Z,1556904010.505 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-03T17:20:10.505Z,1556904010.505 [LoopControl](DEBUG): Construct LoopControl.
2019-05-03T17:20:10.506Z,1556904010.506 [LoopControl] Loaded
2019-05-03T17:20:10.506Z,1556904010.506 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-03T17:20:10.507Z,1556904010.507 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-03T17:20:10.507Z,1556904010.507 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-03T17:20:10.557Z,1556904010.557 [DepthRateCalculator] Loaded
2019-05-03T17:20:10.557Z,1556904010.557 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-03T17:20:10.563Z,1556904010.563 [PitchRateCalculator] Loaded
2019-05-03T17:20:10.563Z,1556904010.563 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-03T17:20:10.576Z,1556904010.576 [SpeedCalculator] Loaded
2019-05-03T17:20:10.576Z,1556904010.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-03T17:20:10.597Z,1556904010.597 [TempGradientCalculator] Loaded
2019-05-03T17:20:10.598Z,1556904010.598 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-03T17:20:10.603Z,1556904010.603 [YawRateCalculator] Loaded
2019-05-03T17:20:10.603Z,1556904010.603 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-03T17:20:10.633Z,1556904010.633 [ElevatorOffsetCalculator] Loaded
2019-05-03T17:20:10.633Z,1556904010.633 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-03T17:20:10.634Z,1556904010.634 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-03T17:20:10.634Z,1556904010.634 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-03T17:20:10.730Z,1556904010.730 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-03T17:20:10.731Z,1556904010.731 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-03T17:20:10.847Z,1556904010.847 [BuoyancyServo] Loaded
2019-05-03T17:20:10.847Z,1556904010.847 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-03T17:20:10.859Z,1556904010.859 [ElevatorServo] Loaded
2019-05-03T17:20:10.859Z,1556904010.859 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-03T17:20:10.871Z,1556904010.871 [MassServo](DEBUG): LcmSlateWriter::add(): fdi_state
2019-05-03T17:20:10.876Z,1556904010.876 [MassServo](INFO): created writer for : fdi_state
2019-05-03T17:20:10.877Z,1556904010.877 [MassServo](DEBUG): LcmSlateWriter::add(): platform_mass_position_delta
2019-05-03T17:20:10.882Z,1556904010.882 [MassServo](INFO): created writer for : platform_mass_position_delta
2019-05-03T17:20:10.883Z,1556904010.883 [MassServo] Loaded
2019-05-03T17:20:10.884Z,1556904010.884 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-03T17:20:10.895Z,1556904010.895 [RudderServo] Loaded
2019-05-03T17:20:10.896Z,1556904010.896 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-03T17:20:10.907Z,1556904010.907 [ThrusterServo] Loaded
2019-05-03T17:20:10.907Z,1556904010.907 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-03T17:20:10.907Z,1556904010.907 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-03T17:20:10.908Z,1556904010.908 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-03T17:20:11.029Z,1556904011.029 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-03T17:20:11.030Z,1556904011.030 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-03T17:20:11.460Z,1556904011.460 [DataOverHttps] Loaded
2019-05-03T17:20:11.460Z,1556904011.460 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-03T17:20:11.461Z,1556904011.461 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0
2019-05-03T17:20:11.461Z,1556904011.461 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2720
2019-05-03T17:20:11.475Z,1556904011.475 [Depth_Keller] Loaded
2019-05-03T17:20:11.475Z,1556904011.475 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-03T17:20:11.492Z,1556904011.492 [Onboard] Loaded
2019-05-03T17:20:11.492Z,1556904011.492 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-03T17:20:11.495Z,1556904011.495 [Radio_Surface] Loaded
2019-05-03T17:20:11.496Z,1556904011.496 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-03T17:20:11.497Z,1556904011.497 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0
2019-05-03T17:20:11.497Z,1556904011.497 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2721
2019-05-03T17:20:11.507Z,1556904011.507 [FDI](DEBUG): LcmSlateWriter::add(): fdi_control_run
2019-05-03T17:20:11.512Z,1556904011.512 [FDI](INFO): created writer for : fdi_control_run
2019-05-03T17:20:11.513Z,1556904011.513 [FDI] Loaded
2019-05-03T17:20:11.513Z,1556904011.513 [ComponentRegistry](DEBUG): SyncComponent "FDI" handled in the control thread.
2019-05-03T17:20:13.537Z,1556904013.537 [BPC1] Loaded
2019-05-03T17:20:13.537Z,1556904013.537 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-03T17:20:13.538Z,1556904013.538 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-03T17:20:13.539Z,1556904013.539 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-03T17:20:13.693Z,1556904013.693 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-03T17:20:13.704Z,1556904013.704 [SBIT] Loaded
2019-05-03T17:20:13.705Z,1556904013.705 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-03T17:20:13.705Z,1556904013.705 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-03T17:20:13.725Z,1556904013.725 [IBIT] Loaded
2019-05-03T17:20:13.725Z,1556904013.725 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-03T17:20:13.728Z,1556904013.728 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-03T17:20:13.873Z,1556904013.873 [CBIT] Loaded
2019-05-03T17:20:13.873Z,1556904013.873 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-03T17:20:13.873Z,1556904013.873 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-03T17:20:13.874Z,1556904013.874 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-03T17:20:13.910Z,1556904013.910 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-03T17:20:13.914Z,1556904013.914 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-03T17:20:13.915Z,1556904013.915 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-03T17:20:13.922Z,1556904013.922 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-03T17:20:13.923Z,1556904013.923 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2019-05-03T17:20:13.923Z,1556904013.923 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2722
2019-05-03T17:20:13.928Z,1556904013.928 [Supervisor](INFO): Main Thread ID is 803
2019-05-03T17:20:13.928Z,1556904013.928 [Supervisor](DEBUG): Running supervisor.
2019-05-03T17:20:13.929Z,1556904013.929 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2723
2019-05-03T17:20:13.932Z,1556904013.932 [controlThread ThreadHandler](INFO): Handler Thread ID is 2724
2019-05-03T17:20:13.932Z,1556904013.932 [controlThread](DEBUG): Initializing ControlThread
2019-05-03T17:20:13.934Z,1556904013.934 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:20:13.934Z,1556904013.934 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-03T17:20:13.935Z,1556904013.935 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-03T17:20:13.935Z,1556904013.935 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-03T17:20:13.937Z,1556904013.937 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-03T17:20:13.937Z,1556904013.937 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-03T17:20:13.938Z,1556904013.938 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-03T17:20:13.938Z,1556904013.938 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-03T17:20:13.938Z,1556904013.938 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-03T17:20:13.939Z,1556904013.939 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-03T17:20:13.939Z,1556904013.939 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-03T17:20:13.940Z,1556904013.940 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-03T17:20:13.940Z,1556904013.940 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-03T17:20:13.946Z,1556904013.946 [SBIT](INFO): Initialize SBIT Component.
2019-05-03T17:20:13.946Z,1556904013.946 [SBIT](IMPORTANT): git: 2019-03-04-52-g572e224
2019-05-03T17:20:13.946Z,1556904013.946 [SBIT](INFO): git hash: 572e224061c240733d2929a5692b542b84a19f9b
2019-05-03T17:20:13.947Z,1556904013.947 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-03T17:20:13.948Z,1556904013.948 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-03T17:20:13.949Z,1556904013.949 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-03T17:20:13.950Z,1556904013.950 [IBIT](INFO): Initialize IBIT Component.
2019-05-03T17:20:13.950Z,1556904013.950 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-03T17:20:13.951Z,1556904013.951 [logger ThreadHandler](INFO): Handler Thread ID is 2725
2019-05-03T17:20:13.965Z,1556904013.965 [CBIT](DEBUG): Initialized mux pins.
2019-05-03T17:20:13.965Z,1556904013.965 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-03T17:20:13.969Z,1556904013.969 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2726
2019-05-03T17:20:13.977Z,1556904013.977 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2727
2019-05-03T17:20:13.978Z,1556904013.978 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-03T17:20:13.985Z,1556904013.985 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2728
2019-05-03T17:20:13.989Z,1556904013.989 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-03T17:20:13.989Z,1556904013.989 [CBIT](DEBUG): Initializing heartbeat.
2019-05-03T17:20:13.997Z,1556904013.997 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2729
2019-05-03T17:20:14.005Z,1556904014.005 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-03T17:20:14.005Z,1556904014.005 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-03T17:20:14.005Z,1556904014.005 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-03T17:20:14.006Z,1556904014.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-03T17:20:14.006Z,1556904014.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-03T17:20:14.006Z,1556904014.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-03T17:20:14.006Z,1556904014.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-03T17:20:14.006Z,1556904014.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-03T17:20:14.007Z,1556904014.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-03T17:20:14.007Z,1556904014.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-03T17:20:14.007Z,1556904014.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-03T17:20:14.007Z,1556904014.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-03T17:20:14.007Z,1556904014.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-03T17:20:14.007Z,1556904014.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-03T17:20:14.008Z,1556904014.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-03T17:20:14.008Z,1556904014.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-03T17:20:14.061Z,1556904014.061 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-03T17:20:14.061Z,1556904014.061 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-03T17:20:14.098Z,1556904014.098 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-03T17:20:14.111Z,1556904014.111 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-03T17:20:14.122Z,1556904014.122 [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>


2019-05-03T17:20:14.122Z,1556904014.122 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-03T17:20:14.179Z,1556904014.179 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-03T17:20:14.180Z,1556904014.180 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-03T17:20:14.194Z,1556904014.194 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-03T17:20:14.230Z,1556904014.230 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-03T17:20:14.233Z,1556904014.233 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-03T17:20:14.247Z,1556904014.247 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-03T17:20:14.251Z,1556904014.251 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-05-03T17:20:14.268Z,1556904014.268 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,Onboard,FDI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-03T17:20:14.290Z,1556904014.290 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2019-05-03T17:20:14.349Z,1556904014.349 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-03T17:20:14.366Z,1556904014.366 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-03T17:20:14.373Z,1556904014.373 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-03T17:20:14.374Z,1556904014.374 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-03T17:20:14.382Z,1556904014.382 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-03T17:20:14.383Z,1556904014.383 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-03T17:20:14.389Z,1556904014.389 [Radio_Surface](INFO): Powering up
2019-05-03T17:20:14.390Z,1556904014.390 [MassServo](DEBUG): Initializing MassServo.
2019-05-03T17:20:14.390Z,1556904014.390 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-03T17:20:14.397Z,1556904014.397 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-03T17:20:14.398Z,1556904014.398 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-03T17:20:14.405Z,1556904014.405 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-03T17:20:15.417Z,1556904015.417 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-03T17:20:15.417Z,1556904015.417 [RudderServo](FAULT): Rudder failed to initialize
2019-05-03T17:20:15.417Z,1556904015.417 [RudderServo] Communications Fault, FailCount= 1
2019-05-03T17:20:15.417Z,1556904015.417 [RudderServo](ERROR): Communications Fault
2019-05-03T17:20:15.528Z,1556904015.528 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-03T17:20:15.692Z,1556904015.692 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-03T17:20:15.692Z,1556904015.692 [RudderServo](INFO): Powering down
2019-05-03T17:20:16.369Z,1556904016.369 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-03T17:20:16.490Z,1556904016.490 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-03T17:20:16.494Z,1556904016.494 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-03T17:20:16.494Z,1556904016.494 [RudderServo] No Fault, FailCount= 1
2019-05-03T17:20:42.631Z,1556904042.631 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-03T17:20:42.635Z,1556904042.635 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-03T17:20:53.390Z,1556904053.390 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.018346
CHAN A1 (24V): 0.001685
CHAN A2 (12V): -0.005900
CHAN A3 (5V): -0.002240
CHAN B0 (3.3V): -0.001176
CHAN B1 (3.15aV): -0.001403
CHAN B2 (3.15bV): -0.001302
CHAN B3 (GND): -0.000548
OPEN: 0.003299
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-03T17:20:55.796Z,1556904055.796 [SBIT](FAULT): Mass: EXPECTED:0.011000 ACTUAL:0.010048
2019-05-03T17:21:36.255Z,1556904096.255 [SBIT](CRITICAL): SBIT FAILED
2019-05-03T17:21:36.270Z,1556904096.270 [CommandLine](FAULT): Scheduling is paused
2019-05-03T17:21:36.270Z,1556904096.270 [CBIT](INFO): Critical error at 20190503T172136
2019-05-03T17:21:36.271Z,1556904096.271 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-03T17:21:36.312Z,1556904096.312 [CommandLine](IMPORTANT): got command configSet list
2019-05-03T17:21:36.312Z,1556904096.312 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-03T17:21:36.345Z,1556904096.345 [CommandLine](IMPORTANT): MassServo.limitHi=490582 count;

2019-05-03T17:21:36.345Z,1556904096.345 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-30 millimeter;

2019-05-03T17:21:36.346Z,1556904096.346 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=45 millimeter;

2019-05-03T17:21:36.657Z,1556904096.657 [MissionManager](IMPORTANT): Started mission Startup
2019-05-03T17:21:36.658Z,1556904096.658 [Startup] Running Loop=1
2019-05-03T17:21:36.658Z,1556904096.658 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-03T17:21:36.658Z,1556904096.658 [Startup:A.GoToSurface] Running Loop=1
2019-05-03T17:21:36.658Z,1556904096.658 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-03T17:21:36.659Z,1556904096.659 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-03T17:21:36.659Z,1556904096.659 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-03T17:21:36.659Z,1556904096.659 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-03T17:21:36.660Z,1556904096.660 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-03T17:21:36.660Z,1556904096.660 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-03T17:21:36.662Z,1556904096.662 [Startup:StartupSatComms] Running Loop=1
2019-05-03T17:21:36.662Z,1556904096.662 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-03T17:21:36.662Z,1556904096.662 [Startup:StartupSatComms:A] Running Loop=1
2019-05-03T17:21:37.049Z,1556904097.049 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-03T17:22:14.208Z,1556904134.208 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:22:14.208Z,1556904134.208 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-03T17:22:14.208Z,1556904134.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:22:14.210Z,1556904134.210 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:22:14.210Z,1556904134.210 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-05-03T17:22:14.210Z,1556904134.210 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:22:14.222Z,1556904134.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:22:14.222Z,1556904134.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:22:14.630Z,1556904134.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:22:14.630Z,1556904134.630 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-03T17:22:14.630Z,1556904134.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:22:14.630Z,1556904134.630 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-05-03T17:22:15.019Z,1556904135.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:22:36.833Z,1556904156.833 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-03T17:21:36.7Z
2019-05-03T17:22:36.833Z,1556904156.833 [Startup:StartupSatComms:A] Stopped
2019-05-03T17:22:36.833Z,1556904156.833 [Startup:StartupSatComms:B] Running Loop=1
2019-05-03T17:22:37.238Z,1556904157.238 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-03T17:23:35.943Z,1556904215.943 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004902
2019-05-03T17:23:37.062Z,1556904217.062 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-03T17:22:36.8Z
2019-05-03T17:23:37.062Z,1556904217.062 [Startup:StartupSatComms:B] Stopped
2019-05-03T17:23:37.062Z,1556904217.062 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-03T17:23:37.062Z,1556904217.062 [Startup:StartupSatComms] Stopped
2019-05-03T17:23:37.062Z,1556904217.062 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-03T17:23:37.063Z,1556904217.063 [Startup](INFO): Completed Startup
2019-05-03T17:23:37.063Z,1556904217.063 [MissionManager](INFO): Startup is completed.
2019-05-03T17:23:37.063Z,1556904217.063 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-03T17:23:37.063Z,1556904217.063 [Startup] Stopped
2019-05-03T17:23:37.063Z,1556904217.063 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-03T17:23:37.063Z,1556904217.063 [Startup:A.GoToSurface] Stopped
2019-05-03T17:23:37.063Z,1556904217.063 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-03T17:23:37.445Z,1556904217.445 [MissionManager](IMPORTANT): Started mission Default
2019-05-03T17:23:37.445Z,1556904217.445 [Default] Running Loop=1
2019-05-03T17:23:37.446Z,1556904217.446 [Default](DEBUG): Aggregate::initialize Default
2019-05-03T17:23:37.446Z,1556904217.446 [Default:B.GoToSurface] Running Loop=1
2019-05-03T17:23:37.446Z,1556904217.446 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-03T17:23:37.446Z,1556904217.446 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-03T17:23:37.446Z,1556904217.446 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-03T17:23:37.447Z,1556904217.447 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-03T17:23:37.447Z,1556904217.447 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-03T17:23:37.447Z,1556904217.447 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-03T17:23:37.447Z,1556904217.447 [Default:A.Wait] Running Loop=1
2019-05-03T17:23:37.448Z,1556904217.448 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-03T17:23:40.629Z,1556904220.629 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20190430T000338/Courier1438.lzma
2019-05-03T17:23:41.435Z,1556904221.435 [DataOverHttps](INFO): Moved sent file to Logs/20190430T000338/Courier1438.lzma.bak
2019-05-03T17:23:41.435Z,1556904221.435 [DataOverHttps](INFO): SBD MOMSN=10918916
2019-05-03T17:23:50.782Z,1556904230.782 [Default:A.Wait](INFO): Done Waiting.
2019-05-03T17:23:50.782Z,1556904230.782 [Default:A.Wait] Stopped
2019-05-03T17:23:50.782Z,1556904230.782 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-03T17:23:51.183Z,1556904231.183 [Default:CheckIn] Running Loop=1
2019-05-03T17:23:51.183Z,1556904231.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-03T17:23:51.183Z,1556904231.183 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-03T17:23:51.574Z,1556904231.574 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-03T17:24:15.408Z,1556904255.408 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:24:15.408Z,1556904255.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-03T17:24:15.408Z,1556904255.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:24:15.410Z,1556904255.410 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:24:15.410Z,1556904255.410 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-05-03T17:24:15.410Z,1556904255.410 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:24:15.475Z,1556904255.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:24:15.475Z,1556904255.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:24:15.826Z,1556904255.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:24:15.826Z,1556904255.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-03T17:24:15.826Z,1556904255.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:24:15.827Z,1556904255.827 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-05-03T17:24:16.277Z,1556904256.277 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:26:16.607Z,1556904376.607 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:26:16.608Z,1556904376.608 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-03T17:26:16.608Z,1556904376.608 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:26:16.609Z,1556904376.609 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:26:16.609Z,1556904376.609 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-05-03T17:26:16.609Z,1556904376.609 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:26:16.621Z,1556904376.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:26:16.621Z,1556904376.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:26:17.026Z,1556904377.026 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:26:17.026Z,1556904377.026 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-03T17:26:17.026Z,1556904377.026 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:26:17.027Z,1556904377.027 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-05-03T17:26:17.469Z,1556904377.469 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:27:38.232Z,1556904458.232 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6.
2019-05-03T17:28:17.807Z,1556904497.807 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:28:17.807Z,1556904497.807 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-05-03T17:28:17.807Z,1556904497.807 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:28:17.809Z,1556904497.809 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:28:17.809Z,1556904497.809 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-05-03T17:28:17.809Z,1556904497.809 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:28:17.820Z,1556904497.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:28:17.820Z,1556904497.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:28:18.218Z,1556904498.218 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:28:18.218Z,1556904498.218 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-05-03T17:28:18.218Z,1556904498.218 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:28:18.218Z,1556904498.218 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-05-03T17:28:18.607Z,1556904498.607 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:28:51.334Z,1556904531.334 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-03T17:23:51.2Z
2019-05-03T17:28:51.334Z,1556904531.334 [Default:CheckIn:Read_GPS] Stopped
2019-05-03T17:28:51.334Z,1556904531.334 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-03T17:28:51.746Z,1556904531.746 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-03T17:28:56.256Z,1556904536.256 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190503T172002/Courier0000.lzma
2019-05-03T17:28:57.062Z,1556904537.062 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Courier0000.lzma.bak
2019-05-03T17:28:57.062Z,1556904537.062 [DataOverHttps](INFO): SBD MOMSN=10918949
2019-05-03T17:29:07.044Z,1556904547.044 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190503T172002/Courier0004.lzma
2019-05-03T17:29:07.850Z,1556904547.850 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Courier0004.lzma.bak
2019-05-03T17:29:07.850Z,1556904547.850 [DataOverHttps](INFO): SBD MOMSN=10918953
2019-05-03T17:29:18.645Z,1556904558.645 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20190430T000338/Express1439.lzma
2019-05-03T17:29:19.451Z,1556904559.451 [DataOverHttps](INFO): Moved sent file to Logs/20190430T000338/Express1439.lzma.bak
2019-05-03T17:29:19.451Z,1556904559.451 [DataOverHttps](INFO): SBD MOMSN=10918955
2019-05-03T17:29:30.062Z,1556904570.062 [DataOverHttps](INFO): Sending 860 bytes from file Logs/20190503T172002/Express0001.lzma
2019-05-03T17:29:30.866Z,1556904570.866 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Express0001.lzma.bak
2019-05-03T17:29:30.866Z,1556904570.866 [DataOverHttps](INFO): SBD MOMSN=10918966
2019-05-03T17:29:41.520Z,1556904581.520 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20190503T172002/Express0005.lzma
2019-05-03T17:29:42.326Z,1556904582.326 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Express0005.lzma.bak
2019-05-03T17:29:42.326Z,1556904582.326 [DataOverHttps](INFO): SBD MOMSN=10918985
2019-05-03T17:29:43.465Z,1556904583.465 [Default:CheckIn:Read_Iridium] Stopped
2019-05-03T17:29:43.465Z,1556904583.465 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-03T17:29:43.465Z,1556904583.465 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-03T17:30:19.007Z,1556904619.007 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:30:19.007Z,1556904619.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-05-03T17:30:19.007Z,1556904619.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:30:19.008Z,1556904619.008 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:30:19.009Z,1556904619.009 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-05-03T17:30:19.009Z,1556904619.009 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:30:19.020Z,1556904619.020 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:30:19.020Z,1556904619.020 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:30:19.419Z,1556904619.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:30:19.419Z,1556904619.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-05-03T17:30:19.420Z,1556904619.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:30:19.420Z,1556904619.420 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-05-03T17:30:19.820Z,1556904619.820 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:32:20.208Z,1556904740.208 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:32:20.208Z,1556904740.208 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-05-03T17:32:20.208Z,1556904740.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:32:20.210Z,1556904740.210 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:32:20.210Z,1556904740.210 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-05-03T17:32:20.210Z,1556904740.210 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:32:20.221Z,1556904740.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:32:20.221Z,1556904740.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:32:20.622Z,1556904740.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:32:20.622Z,1556904740.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-05-03T17:32:20.622Z,1556904740.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:32:20.623Z,1556904740.623 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-05-03T17:32:21.011Z,1556904741.011 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:34:21.407Z,1556904861.407 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:34:21.407Z,1556904861.407 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-05-03T17:34:21.407Z,1556904861.407 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:34:21.409Z,1556904861.409 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:34:21.409Z,1556904861.409 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-05-03T17:34:21.409Z,1556904861.409 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:34:21.476Z,1556904861.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:34:21.476Z,1556904861.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:34:21.821Z,1556904861.821 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:34:21.821Z,1556904861.821 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-05-03T17:34:21.822Z,1556904861.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:34:21.822Z,1556904861.822 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-05-03T17:34:22.215Z,1556904862.215 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:34:44.032Z,1556904884.032 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-05-03T17:34:44.032Z,1556904884.032 [Default:CheckIn:C.Wait] Stopped
2019-05-03T17:34:44.032Z,1556904884.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-03T17:34:44.032Z,1556904884.032 [Default:CheckIn:D] Running Loop=1
2019-05-03T17:34:44.437Z,1556904884.437 [Default:CheckIn:D] Stopped
2019-05-03T17:34:44.437Z,1556904884.437 [Default:CheckIn:E] Running Loop=1
2019-05-03T17:34:44.842Z,1556904884.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.116521 min 
2019-05-03T17:34:44.842Z,1556904884.842 [Default:CheckIn:E] Stopped
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn] Stopped
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn](INFO): Running loop #2
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn] Running Loop=2
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-03T17:34:44.843Z,1556904884.843 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-03T17:35:52.266Z,1556904952.266 [CommandLine](INFO): End of History
2019-05-03T17:36:22.398Z,1556904982.398 [DataOverHttps](IMPORTANT): SBD MTMSN=20190503T173525
2019-05-03T17:36:22.607Z,1556904982.607 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:36:22.607Z,1556904982.607 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-05-03T17:36:22.607Z,1556904982.607 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:36:22.608Z,1556904982.608 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:36:22.609Z,1556904982.609 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-05-03T17:36:22.609Z,1556904982.609 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:36:22.620Z,1556904982.620 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:36:22.620Z,1556904982.620 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:36:23.022Z,1556904983.022 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:36:23.022Z,1556904983.022 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-05-03T17:36:23.022Z,1556904983.022 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:36:23.022Z,1556904983.022 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-05-03T17:36:23.407Z,1556904983.407 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:36:26.997Z,1556904986.997 [DataOverHttps](INFO): Received command:run Maintenance/tank_ballast_and_trim.xml
2019-05-03T17:36:27.080Z,1556904987.080 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2019-05-03T17:36:27.081Z,1556904987.081 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim.xml
2019-05-03T17:36:27.172Z,1556904987.172 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2019-05-03T17:36:27.175Z,1556904987.175 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2019-05-03T17:36:27.177Z,1556904987.177 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2019-05-03T17:36:27.180Z,1556904987.180 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband1 = 0.100000 m
2019-05-03T17:36:27.183Z,1556904987.183 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2019-05-03T17:36:27.186Z,1556904987.186 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband2 = nan m
2019-05-03T17:36:27.189Z,1556904987.189 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2019-05-03T17:36:27.192Z,1556904987.192 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2019-05-03T17:36:27.195Z,1556904987.195 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2019-05-03T17:36:27.198Z,1556904987.198 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2019-05-03T17:36:27.201Z,1556904987.201 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2019-05-03T17:36:27.204Z,1556904987.204 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2019-05-03T17:36:27.206Z,1556904987.206 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2019-05-03T17:36:27.250Z,1556904987.250 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2019-05-03T17:36:27.252Z,1556904987.252 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2019-05-03T17:36:27.261Z,1556904987.261 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2019-05-03T17:36:27.665Z,1556904987.665 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUS = 0 bool
2019-05-03T17:36:27.668Z,1556904987.668 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-05-03T17:36:27.671Z,1556904987.671 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSminPitch = -60.000000 arcdeg
2019-05-03T17:36:27.673Z,1556904987.673 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2019-05-03T17:36:27.676Z,1556904987.676 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2019-05-03T17:36:27.679Z,1556904987.679 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2019-05-03T17:36:27.681Z,1556904987.681 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2019-05-03T17:36:27.684Z,1556904987.684 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2019-05-03T17:36:27.687Z,1556904987.687 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2019-05-03T17:36:27.690Z,1556904987.690 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2019-05-03T17:36:27.693Z,1556904987.693 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2019-05-03T17:36:27.695Z,1556904987.695 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2019-05-03T17:36:27.698Z,1556904987.698 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2019-05-03T17:36:27.701Z,1556904987.701 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-05-03T17:36:27.704Z,1556904987.704 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool
2019-05-03T17:36:27.707Z,1556904987.707 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 1.000000 bool
2019-05-03T17:36:27.711Z,1556904987.711 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 0.000000 bool
2019-05-03T17:36:27.714Z,1556904987.714 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledTurbulence_NPS = 0.000000 bool
2019-05-03T17:36:27.718Z,1556904987.718 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 0.000000 bool
2019-05-03T17:36:27.721Z,1556904987.721 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2019-05-03T17:36:27.724Z,1556904987.724 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool
2019-05-03T17:36:27.728Z,1556904987.728 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledISUS = 0.000000 bool
2019-05-03T17:36:27.864Z,1556904987.864 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2019-05-03T17:36:27.867Z,1556904987.867 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2019-05-03T17:36:27.904Z,1556904987.904 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m
2019-05-03T17:36:27.907Z,1556904987.907 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m
2019-05-03T17:36:27.910Z,1556904987.910 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2019-05-03T17:36:27.925Z,1556904987.925 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2019-05-03T17:36:27.928Z,1556904987.928 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2019-05-03T17:36:27.931Z,1556904987.931 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2019-05-03T17:36:27.934Z,1556904987.934 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2019-05-03T17:36:27.953Z,1556904987.953 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2019-05-03T17:36:27.956Z,1556904987.956 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2019-05-03T17:36:27.959Z,1556904987.959 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2019-05-03T17:36:27.962Z,1556904987.962 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2019-05-03T17:36:27.997Z,1556904987.997 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2019-05-03T17:36:28.001Z,1556904988.001 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2019-05-03T17:36:28.004Z,1556904988.004 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m
2019-05-03T17:36:28.007Z,1556904988.007 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2019-05-03T17:36:28.038Z,1556904988.038 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2019-05-03T17:36:28.044Z,1556904988.044 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2019-05-03T17:36:28.047Z,1556904988.047 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2019-05-03T17:36:28.050Z,1556904988.050 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2019-05-03T17:36:28.068Z,1556904988.068 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2019-05-03T17:36:28.072Z,1556904988.072 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2019-05-03T17:36:28.075Z,1556904988.075 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2019-05-03T17:36:28.106Z,1556904988.106 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2019-05-03T17:36:28.109Z,1556904988.109 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2019-05-03T17:36:28.114Z,1556904988.114 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2019-05-03T17:36:28.136Z,1556904988.136 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2019-05-03T17:36:28.139Z,1556904988.139 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2019-05-03T17:36:28.168Z,1556904988.168 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-03T17:36:28.222Z,1556904988.222 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-05-03T17:36:28.294Z,1556904988.294 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-03T17:36:28.316Z,1556904988.316 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-03T17:36:28.365Z,1556904988.365 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-03T17:36:28.392Z,1556904988.392 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2019-05-03T17:36:28.582Z,1556904988.582 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepth = 25.000000 m
2019-05-03T17:36:28.597Z,1556904988.597 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2019-05-03T17:36:28.600Z,1556904988.600 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2019-05-03T17:36:28.608Z,1556904988.608 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2019-05-03T17:36:28.634Z,1556904988.634 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2019-05-03T17:36:28.686Z,1556904988.686 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2019-05-03T17:36:28.689Z,1556904988.689 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSettleTime = 10.000000 min
2019-05-03T17:36:28.692Z,1556904988.692 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstTimeout = 45.000000 min
2019-05-03T17:36:28.721Z,1556904988.721 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MinEstTime = 10.000000 min
2019-05-03T17:36:28.724Z,1556904988.724 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2019-05-03T17:36:28.727Z,1556904988.727 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2019-05-03T17:36:28.742Z,1556904988.742 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstConfidence = 99.900000 %
2019-05-03T17:36:28.750Z,1556904988.750 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2019-05-03T17:36:28.771Z,1556904988.771 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2019-05-03T17:36:28.777Z,1556904988.777 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2019-05-03T17:36:28.803Z,1556904988.803 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2019-05-03T17:36:28.826Z,1556904988.826 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-03T17:36:28.828Z,1556904988.828 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:D.Pitch](DEBUG): Construct.
2019-05-03T17:36:28.835Z,1556904988.835 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:D.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-03T17:36:28.878Z,1556904988.878 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2019-05-03T17:36:28.881Z,1556904988.881 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2019-05-03T17:36:28.900Z,1556904988.900 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2019-05-03T17:36:28.904Z,1556904988.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2019-05-03T17:36:28.967Z,1556904988.967 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth1
2019-05-03T17:36:28.968Z,1556904988.968 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband1
2019-05-03T17:36:28.987Z,1556904988.987 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceThreshold = reader:ballast_and_trim.SurfaceThreshold
2019-05-03T17:36:28.988Z,1556904988.988 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2019-05-03T17:36:28.990Z,1556904988.990 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2019-05-03T17:36:28.992Z,1556904988.992 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2019-05-03T17:36:28.994Z,1556904988.994 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2019-05-03T17:36:28.996Z,1556904988.996 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2019-05-03T17:36:29.009Z,1556904989.009 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2019-05-03T17:36:29.011Z,1556904989.011 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2019-05-03T17:36:29.022Z,1556904989.022 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2019-05-03T17:36:29.254Z,1556904989.254 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepth = 25.000000 m
2019-05-03T17:36:29.257Z,1556904989.257 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2019-05-03T17:36:29.260Z,1556904989.260 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2019-05-03T17:36:29.263Z,1556904989.263 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2019-05-03T17:36:29.278Z,1556904989.278 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2019-05-03T17:36:29.281Z,1556904989.281 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2019-05-03T17:36:29.284Z,1556904989.284 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSettleTime = 10.000000 min
2019-05-03T17:36:29.287Z,1556904989.287 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstTimeout = 45.000000 min
2019-05-03T17:36:29.302Z,1556904989.302 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MinEstTime = 10.000000 min
2019-05-03T17:36:29.305Z,1556904989.305 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2019-05-03T17:36:29.308Z,1556904989.308 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2019-05-03T17:36:29.311Z,1556904989.311 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstConfidence = 99.900000 %
2019-05-03T17:36:29.331Z,1556904989.331 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2019-05-03T17:36:29.336Z,1556904989.336 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2019-05-03T17:36:29.362Z,1556904989.362 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2019-05-03T17:36:29.365Z,1556904989.365 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2019-05-03T17:36:29.384Z,1556904989.384 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-03T17:36:29.386Z,1556904989.386 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:D.Pitch](DEBUG): Construct.
2019-05-03T17:36:29.423Z,1556904989.423 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:D.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2019-05-03T17:36:29.440Z,1556904989.440 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2019-05-03T17:36:29.443Z,1556904989.443 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2019-05-03T17:36:29.491Z,1556904989.491 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2019-05-03T17:36:29.510Z,1556904989.510 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2019-05-03T17:36:29.516Z,1556904989.516 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth2
2019-05-03T17:36:29.554Z,1556904989.554 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband2
2019-05-03T17:36:29.556Z,1556904989.556 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceThreshold = reader:ballast_and_trim.SurfaceThreshold
2019-05-03T17:36:29.558Z,1556904989.558 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2019-05-03T17:36:29.560Z,1556904989.560 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2019-05-03T17:36:29.561Z,1556904989.561 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2019-05-03T17:36:29.563Z,1556904989.563 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2019-05-03T17:36:29.578Z,1556904989.578 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2019-05-03T17:36:29.580Z,1556904989.580 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2019-05-03T17:36:29.582Z,1556904989.582 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2019-05-03T17:36:29.586Z,1556904989.586 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2019-05-03T17:36:29.606Z,1556904989.606 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2019-05-03T17:36:29.688Z,1556904989.688 [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>


2019-05-03T17:36:29.710Z,1556904989.710 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2019-05-03T17:36:29.877Z,1556904989.877 [Default] Stopped
2019-05-03T17:36:29.877Z,1556904989.877 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-03T17:36:29.877Z,1556904989.877 [Default:B.GoToSurface] Stopped
2019-05-03T17:36:29.877Z,1556904989.877 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-03T17:36:29.877Z,1556904989.877 [Default:CheckIn] Stopped
2019-05-03T17:36:29.877Z,1556904989.877 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-03T17:36:29.878Z,1556904989.878 [Default:CheckIn:Read_GPS] Stopped
2019-05-03T17:36:29.878Z,1556904989.878 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2019-05-03T17:36:29.878Z,1556904989.878 [ballast_and_trim] Running Loop=1
2019-05-03T17:36:29.878Z,1556904989.878 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2019-05-03T17:36:29.878Z,1556904989.878 [ballast_and_trim:Science] Running Loop=1
2019-05-03T17:36:29.878Z,1556904989.878 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2019-05-03T17:36:29.878Z,1556904989.878 [ballast_and_trim:Science:A] Running Loop=1
2019-05-03T17:36:29.878Z,1556904989.878 [ballast_and_trim:Science:B] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:C] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:D] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:E] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:F] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:G] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:H] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-05-03T17:36:29.879Z,1556904989.879 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-03T17:36:29.882Z,1556904989.882 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2019-05-03T17:36:29.882Z,1556904989.882 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2019-05-03T17:36:29.882Z,1556904989.882 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-05-03T17:36:29.882Z,1556904989.882 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-05-03T17:36:29.882Z,1556904989.882 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2019-05-03T17:36:29.883Z,1556904989.883 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2019-05-03T17:36:29.883Z,1556904989.883 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-05-03T17:36:29.883Z,1556904989.883 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-03T17:36:29.885Z,1556904989.885 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2019-05-03T17:36:29.885Z,1556904989.885 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2019-05-03T17:36:29.885Z,1556904989.885 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-05-03T17:36:29.885Z,1556904989.885 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-03T17:36:29.887Z,1556904989.887 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2019-05-03T17:36:29.887Z,1556904989.887 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2019-05-03T17:36:29.887Z,1556904989.887 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-05-03T17:36:29.887Z,1556904989.887 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-03T17:36:29.887Z,1556904989.887 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](CRITICAL): No UniversalURI called Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](CRITICAL): Could not create Reader called Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim] Running Loop=1
2019-05-03T17:36:29.888Z,1556904989.888 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim
2019-05-03T17:36:29.901Z,1556904989.901 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Running Loop=1
2019-05-03T17:36:29.901Z,1556904989.901 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Running Loop=1
2019-05-03T17:36:29.901Z,1556904989.901 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Running Loop=1
2019-05-03T17:36:29.901Z,1556904989.901 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D] Running Loop=1
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D](INFO): Going to target depth. Speed set to 0.000000 m/s 
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D] Stopped
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth] Running Loop=1
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Initialize.
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2019-05-03T17:36:29.904Z,1556904989.904 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2019-05-03T17:36:29.905Z,1556904989.905 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2019-05-03T17:36:29.906Z,1556904989.906 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2019-05-03T17:36:29.906Z,1556904989.906 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:D.Pitch] Running Loop=1
2019-05-03T17:36:29.906Z,1556904989.906 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:D.Pitch](DEBUG): Initialize.
2019-05-03T17:36:29.906Z,1556904989.906 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Running Loop=1
2019-05-03T17:36:29.907Z,1556904989.907 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Running Loop=1
2019-05-03T17:36:29.907Z,1556904989.907 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Running Loop=1
2019-05-03T17:36:29.907Z,1556904989.907 [ballast_and_trim:Science] Running Loop=1
2019-05-03T17:36:29.908Z,1556904989.908 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2019-05-03T17:36:29.908Z,1556904989.908 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2019-05-03T17:36:29.908Z,1556904989.908 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-05-03T17:36:29.908Z,1556904989.908 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-03T17:36:29.908Z,1556904989.908 [ballast_and_trim:Science:PeakDetectOil] Stopped
2019-05-03T17:36:29.908Z,1556904989.908 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-05-03T17:36:29.909Z,1556904989.909 [ballast_and_trim:Science:PeakDetectChl] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:Read_Oil] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:H] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:G] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:F] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:E] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:D] Stopped
2019-05-03T17:36:29.910Z,1556904989.910 [ballast_and_trim:Science:C] Running Loop=1
2019-05-03T17:36:29.911Z,1556904989.911 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-05-03T17:36:29.911Z,1556904989.911 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-05-03T17:36:29.912Z,1556904989.912 [ballast_and_trim:Science:B] Stopped
2019-05-03T17:36:29.912Z,1556904989.912 [ballast_and_trim:Science:A] Stopped
2019-05-03T17:36:29.932Z,1556904989.932 [CBIT](INFO): Critical error at 20190503T173629
2019-05-03T17:36:29.932Z,1556904989.932 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-03T17:36:30.049Z,1556904990.049 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2019-05-03T17:36:30.283Z,1556904990.283 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2019-05-03T17:36:30.285Z,1556904990.285 [MissionManager](INFO): MissionManager is completed.
2019-05-03T17:36:30.285Z,1556904990.285 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2019-05-03T17:36:30.285Z,1556904990.285 [ballast_and_trim] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:Science] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:Science:C] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Stopped
2019-05-03T17:36:30.286Z,1556904990.286 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Stopped
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Stopped
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth] Stopped
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Stopped
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Stopped
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2019-05-03T17:36:30.287Z,1556904990.287 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:D.Pitch] Stopped
2019-05-03T17:36:30.695Z,1556904990.695 [MissionManager](IMPORTANT): Started mission Default
2019-05-03T17:36:30.696Z,1556904990.696 [Default] Running Loop=1
2019-05-03T17:36:30.696Z,1556904990.696 [Default](DEBUG): Aggregate::initialize Default
2019-05-03T17:36:30.696Z,1556904990.696 [Default:B.GoToSurface] Running Loop=1
2019-05-03T17:36:30.696Z,1556904990.696 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-03T17:36:30.696Z,1556904990.696 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-03T17:36:30.697Z,1556904990.697 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-03T17:36:30.697Z,1556904990.697 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-03T17:36:30.697Z,1556904990.697 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-03T17:36:30.698Z,1556904990.698 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-03T17:36:30.698Z,1556904990.698 [Default:A.Wait] Running Loop=1
2019-05-03T17:36:30.698Z,1556904990.698 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-03T17:36:44.023Z,1556905004.023 [Default:A.Wait](INFO): Done Waiting.
2019-05-03T17:36:44.023Z,1556905004.023 [Default:A.Wait] Stopped
2019-05-03T17:36:44.023Z,1556905004.023 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-03T17:36:44.425Z,1556905004.425 [Default:CheckIn] Running Loop=1
2019-05-03T17:36:44.425Z,1556905004.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-03T17:36:44.425Z,1556905004.425 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-03T17:38:23.803Z,1556905103.803 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:38:23.803Z,1556905103.803 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-05-03T17:38:23.803Z,1556905103.803 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:38:23.805Z,1556905103.805 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:38:23.805Z,1556905103.805 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-05-03T17:38:23.805Z,1556905103.805 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:38:23.816Z,1556905103.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:38:23.817Z,1556905103.817 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:38:24.226Z,1556905104.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:38:24.226Z,1556905104.226 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-05-03T17:38:24.226Z,1556905104.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:38:24.226Z,1556905104.226 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-05-03T17:38:24.608Z,1556905104.608 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:40:25.012Z,1556905225.012 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:40:25.012Z,1556905225.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-05-03T17:40:25.012Z,1556905225.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:40:25.014Z,1556905225.014 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:40:25.014Z,1556905225.014 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-05-03T17:40:25.014Z,1556905225.014 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:40:25.025Z,1556905225.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:40:25.026Z,1556905225.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:40:25.431Z,1556905225.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:40:25.431Z,1556905225.431 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-05-03T17:40:25.431Z,1556905225.431 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:40:25.431Z,1556905225.431 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-05-03T17:40:25.815Z,1556905225.815 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:41:44.601Z,1556905304.601 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-03T17:36:44.4Z
2019-05-03T17:41:44.601Z,1556905304.601 [Default:CheckIn:Read_GPS] Stopped
2019-05-03T17:41:44.601Z,1556905304.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-03T17:41:50.501Z,1556905310.501 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20190503T172002/Courier0007.lzma
2019-05-03T17:41:51.306Z,1556905311.306 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Courier0007.lzma.bak
2019-05-03T17:41:51.306Z,1556905311.306 [DataOverHttps](INFO): SBD MOMSN=10919096
2019-05-03T17:42:01.368Z,1556905321.368 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190503T172002/Courier0010.lzma
2019-05-03T17:42:02.174Z,1556905322.174 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Courier0010.lzma.bak
2019-05-03T17:42:02.174Z,1556905322.174 [DataOverHttps](INFO): SBD MOMSN=10919100
2019-05-03T17:42:12.806Z,1556905332.806 [DataOverHttps](INFO): Sending 403 bytes from file Logs/20190503T172002/Express0008.lzma
2019-05-03T17:42:13.610Z,1556905333.610 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Express0008.lzma.bak
2019-05-03T17:42:13.610Z,1556905333.610 [DataOverHttps](INFO): SBD MOMSN=10919102
2019-05-03T17:42:24.268Z,1556905344.268 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190503T172002/Express0011.lzma
2019-05-03T17:42:25.074Z,1556905345.074 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Express0011.lzma.bak
2019-05-03T17:42:25.074Z,1556905345.074 [DataOverHttps](INFO): SBD MOMSN=10919122
2019-05-03T17:42:26.208Z,1556905346.208 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:42:26.208Z,1556905346.208 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-05-03T17:42:26.208Z,1556905346.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:42:26.209Z,1556905346.209 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:42:26.209Z,1556905346.209 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2019-05-03T17:42:26.209Z,1556905346.209 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:42:26.211Z,1556905346.211 [Default:CheckIn:Read_Iridium] Stopped
2019-05-03T17:42:26.211Z,1556905346.211 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-03T17:42:26.211Z,1556905346.211 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-03T17:42:26.232Z,1556905346.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:42:26.232Z,1556905346.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:42:26.622Z,1556905346.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:42:26.622Z,1556905346.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-05-03T17:42:26.622Z,1556905346.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:42:26.623Z,1556905346.623 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2019-05-03T17:42:27.020Z,1556905347.020 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:44:27.411Z,1556905467.411 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:44:27.411Z,1556905467.411 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-05-03T17:44:27.411Z,1556905467.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:44:27.412Z,1556905467.412 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:44:27.412Z,1556905467.412 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2019-05-03T17:44:27.413Z,1556905467.413 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:44:27.424Z,1556905467.424 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:44:27.424Z,1556905467.424 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:44:27.822Z,1556905467.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:44:27.822Z,1556905467.822 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-05-03T17:44:27.822Z,1556905467.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:44:27.822Z,1556905467.822 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2019-05-03T17:44:28.223Z,1556905468.223 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:46:28.613Z,1556905588.613 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:46:28.613Z,1556905588.613 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-05-03T17:46:28.614Z,1556905588.614 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:46:28.615Z,1556905588.615 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:46:28.615Z,1556905588.615 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2019-05-03T17:46:28.615Z,1556905588.615 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:46:28.626Z,1556905588.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:46:28.627Z,1556905588.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:46:29.030Z,1556905589.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:46:29.030Z,1556905589.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-05-03T17:46:29.031Z,1556905589.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:46:29.031Z,1556905589.031 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2019-05-03T17:46:29.415Z,1556905589.415 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:47:26.786Z,1556905646.786 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-05-03T17:47:26.786Z,1556905646.786 [Default:CheckIn:C.Wait] Stopped
2019-05-03T17:47:26.786Z,1556905646.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-03T17:47:26.787Z,1556905646.787 [Default:CheckIn:D] Running Loop=1
2019-05-03T17:47:27.193Z,1556905647.193 [Default:CheckIn:D] Stopped
2019-05-03T17:47:27.193Z,1556905647.193 [Default:CheckIn:E] Running Loop=1
2019-05-03T17:47:27.589Z,1556905647.589 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.941619 min 
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn:E] Stopped
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn] Stopped
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn](INFO): Running loop #2
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn] Running Loop=2
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-03T17:47:27.590Z,1556905647.590 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-03T17:48:29.811Z,1556905709.811 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:48:29.811Z,1556905709.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-05-03T17:48:29.811Z,1556905709.811 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:48:29.813Z,1556905709.813 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:48:29.813Z,1556905709.813 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2019-05-03T17:48:29.813Z,1556905709.813 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:48:29.824Z,1556905709.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:48:29.824Z,1556905709.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:48:30.226Z,1556905710.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:48:30.226Z,1556905710.226 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-05-03T17:48:30.226Z,1556905710.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:48:30.226Z,1556905710.226 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2019-05-03T17:48:30.612Z,1556905710.612 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:50:31.011Z,1556905831.011 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:50:31.012Z,1556905831.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-05-03T17:50:31.012Z,1556905831.012 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:50:31.013Z,1556905831.013 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:50:31.013Z,1556905831.013 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2019-05-03T17:50:31.013Z,1556905831.013 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:50:31.024Z,1556905831.024 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:50:31.025Z,1556905831.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:50:31.422Z,1556905831.422 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:50:31.422Z,1556905831.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-05-03T17:50:31.423Z,1556905831.423 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:50:31.423Z,1556905831.423 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2019-05-03T17:50:31.815Z,1556905831.815 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:52:27.778Z,1556905947.778 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-03T17:47:27.6Z
2019-05-03T17:52:27.778Z,1556905947.778 [Default:CheckIn:Read_GPS] Stopped
2019-05-03T17:52:27.778Z,1556905947.778 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-05-03T17:52:31.824Z,1556905951.824 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:52:31.824Z,1556905951.824 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-05-03T17:52:31.824Z,1556905951.824 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-03T17:52:31.826Z,1556905951.826 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-03T17:52:31.826Z,1556905951.826 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2019-05-03T17:52:31.826Z,1556905951.826 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-03T17:52:31.848Z,1556905951.848 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-03T17:52:31.849Z,1556905951.849 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-03T17:52:32.258Z,1556905952.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-03T17:52:32.258Z,1556905952.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-05-03T17:52:32.258Z,1556905952.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-03T17:52:32.258Z,1556905952.258 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2019-05-03T17:52:32.627Z,1556905952.627 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-03T17:52:32.980Z,1556905952.980 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190503T172002/Courier0013.lzma
2019-05-03T17:52:33.786Z,1556905953.786 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Courier0013.lzma.bak
2019-05-03T17:52:33.786Z,1556905953.786 [DataOverHttps](INFO): SBD MOMSN=10919147
2019-05-03T17:52:44.457Z,1556905964.457 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20190503T172002/Express0014.lzma
2019-05-03T17:52:45.270Z,1556905965.270 [DataOverHttps](INFO): Moved sent file to Logs/20190503T172002/Express0014.lzma.bak
2019-05-03T17:52:45.270Z,1556905965.270 [DataOverHttps](INFO): SBD MOMSN=10919149
2019-05-03T17:52:46.369Z,1556905966.369 [Default:CheckIn:Read_Iridium] Stopped
2019-05-03T17:52:46.369Z,1556905966.369 [Default:CheckIn:C.Wait] Running Loop=1
2019-05-03T17:52:46.369Z,1556905966.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-05-03T17:53:34.935Z,1556906014.935 [CommandLine](IMPORTANT): got command restart application
2019-05-03T17:53:35.941Z,1556906015.941 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:35.941Z,1556906015.941 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:35.977Z,1556906015.977 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-03T17:53:35.977Z,1556906015.977 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:35.977Z,1556906015.977 [CommandLine](INFO): Join timeout helper Thread ID is 2749
2019-05-03T17:53:35.978Z,1556906015.978 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-03T17:53:35.978Z,1556906015.978 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:35.978Z,1556906015.978 [NavChartDb](INFO): Join timeout helper Thread ID is 2750
2019-05-03T17:53:36.261Z,1556906016.261 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:36.261Z,1556906016.261 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:36.269Z,1556906016.269 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-03T17:53:36.269Z,1556906016.269 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:36.269Z,1556906016.269 [Radio_Surface](INFO): Join timeout helper Thread ID is 2751
2019-05-03T17:53:36.637Z,1556906016.637 [Radio_Surface](INFO): Powering down
2019-05-03T17:53:36.638Z,1556906016.638 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:36.638Z,1556906016.638 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:36.649Z,1556906016.649 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-03T17:53:36.649Z,1556906016.649 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:36.649Z,1556906016.649 [DataOverHttps](INFO): Join timeout helper Thread ID is 2752
2019-05-03T17:53:37.037Z,1556906017.037 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:37.037Z,1556906017.037 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.049Z,1556906017.049 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-03T17:53:37.049Z,1556906017.049 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.049Z,1556906017.049 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2753
2019-05-03T17:53:37.249Z,1556906017.249 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:37.249Z,1556906017.249 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.270Z,1556906017.270 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-03T17:53:37.270Z,1556906017.270 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.270Z,1556906017.270 [logger](INFO): Join timeout helper Thread ID is 2754
2019-05-03T17:53:37.305Z,1556906017.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:37.305Z,1556906017.305 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.321Z,1556906017.321 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-03T17:53:37.322Z,1556906017.322 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.322Z,1556906017.322 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-03T17:53:37.322Z,1556906017.322 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.322Z,1556906017.322 [controlThread](INFO): Join timeout helper Thread ID is 2755
2019-05-03T17:53:37.645Z,1556906017.645 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-03T17:53:37.645Z,1556906017.645 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-03T17:53:37.646Z,1556906017.646 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-03T17:53:37.646Z,1556906017.646 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-03T17:53:37.647Z,1556906017.647 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-03T17:53:37.647Z,1556906017.647 [MissionManager](INFO): Uninitializing Mission Default
2019-05-03T17:53:37.647Z,1556906017.647 [Default] Stopped
2019-05-03T17:53:37.647Z,1556906017.647 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-03T17:53:37.648Z,1556906017.648 [Default:B.GoToSurface] Stopped
2019-05-03T17:53:37.648Z,1556906017.648 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-03T17:53:37.648Z,1556906017.648 [Default:CheckIn] Stopped
2019-05-03T17:53:37.648Z,1556906017.648 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-03T17:53:37.648Z,1556906017.648 [Default:CheckIn:C.Wait] Stopped
2019-05-03T17:53:37.648Z,1556906017.648 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-05-03T17:53:37.650Z,1556906017.650 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-03T17:53:37.651Z,1556906017.651 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-03T17:53:37.651Z,1556906017.651 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-03T17:53:37.651Z,1556906017.651 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-03T17:53:37.651Z,1556906017.651 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-03T17:53:37.651Z,1556906017.651 [BuoyancyServo](INFO): Powering down
2019-05-03T17:53:37.665Z,1556906017.665 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-03T17:53:37.665Z,1556906017.665 [ElevatorServo](INFO): Powering down
2019-05-03T17:53:37.666Z,1556906017.666 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-03T17:53:37.666Z,1556906017.666 [MassServo](INFO): Powering down
2019-05-03T17:53:37.667Z,1556906017.667 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-03T17:53:37.667Z,1556906017.667 [RudderServo](INFO): Powering down
2019-05-03T17:53:37.667Z,1556906017.667 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-03T17:53:37.667Z,1556906017.667 [ThrusterServo](INFO): Powering down
2019-05-03T17:53:37.668Z,1556906017.668 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-03T17:53:37.669Z,1556906017.669 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-03T17:53:37.669Z,1556906017.669 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-03T17:53:37.669Z,1556906017.669 [CBIT](DEBUG): Powering off loads.
2019-05-03T17:53:37.681Z,1556906017.681 [CBIT](DEBUG): Disabling WDT.
2019-05-03T17:53:37.693Z,1556906017.693 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-03T17:53:37.693Z,1556906017.693 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.725Z,1556906017.725 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.823Z,1556906017.823 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.825Z,1556906017.825 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.867Z,1556906017.867 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-03T17:53:37.924Z,1556906017.924 [logger ThreadHandler](INFO): Thread cancelled.