2019-03-19T00:35:21.407Z,1552955721.407 [Supervisor](DEBUG): Initializing supervisor.
2019-03-19T00:35:21.409Z,1552955721.409 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-19T00:35:21.410Z,1552955721.410 [SyncHandler](INFO): Protected caller Thread ID is 1022
2019-03-19T00:35:21.410Z,1552955721.410 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-19T00:35:21.411Z,1552955721.411 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-19T00:35:21.412Z,1552955721.412 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1023
2019-03-19T00:35:21.414Z,1552955721.414 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-19T00:35:21.426Z,1552955721.426 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-19T00:35:21.427Z,1552955721.427 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-19T00:35:21.427Z,1552955721.427 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1024
2019-03-19T00:35:21.428Z,1552955721.428 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-19T00:35:21.429Z,1552955721.429 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-19T00:35:21.429Z,1552955721.429 [logger ThreadHandler](INFO): Protected caller Thread ID is 1025
2019-03-19T00:35:21.431Z,1552955721.431 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-19T00:35:21.432Z,1552955721.432 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-19T00:35:21.433Z,1552955721.433 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-19T00:35:21.879Z,1552955721.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-19T00:35:21.879Z,1552955721.879 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-19T00:35:22.358Z,1552955722.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-19T00:35:22.358Z,1552955722.358 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-19T00:35:22.556Z,1552955722.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-19T00:35:22.556Z,1552955722.556 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-19T00:35:22.704Z,1552955722.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-19T00:35:22.704Z,1552955722.704 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-19T00:35:23.605Z,1552955723.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-19T00:35:23.606Z,1552955723.606 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-19T00:35:23.703Z,1552955723.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-19T00:35:23.704Z,1552955723.704 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-19T00:35:23.849Z,1552955723.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-19T00:35:23.849Z,1552955723.849 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-19T00:35:23.950Z,1552955723.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-19T00:35:23.951Z,1552955723.951 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-19T00:35:24.056Z,1552955724.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-19T00:35:24.057Z,1552955724.057 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-19T00:35:24.450Z,1552955724.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-19T00:35:24.451Z,1552955724.451 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-19T00:35:24.653Z,1552955724.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-19T00:35:24.654Z,1552955724.654 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-19T00:35:24.879Z,1552955724.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-19T00:35:24.880Z,1552955724.880 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-19T00:35:24.961Z,1552955724.961 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-19T00:35:25.307Z,1552955725.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-19T00:35:25.308Z,1552955725.308 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-19T00:35:25.633Z,1552955725.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-19T00:35:25.635Z,1552955725.635 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2019-03-19T00:35:25.636Z,1552955725.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2019-03-19T00:35:25.722Z,1552955725.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2019-03-19T00:35:25.877Z,1552955725.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2019-03-19T00:35:25.977Z,1552955725.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2019-03-19T00:35:26.156Z,1552955726.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2019-03-19T00:35:26.239Z,1552955726.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2019-03-19T00:35:26.469Z,1552955726.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-19T00:35:26.469Z,1552955726.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2019-03-19T00:35:26.586Z,1552955726.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2019-03-19T00:35:26.686Z,1552955726.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2019-03-19T00:35:26.781Z,1552955726.781 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2019-03-19T00:35:26.882Z,1552955726.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2019-03-19T00:35:26.994Z,1552955726.994 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2019-03-19T00:35:26.994Z,1552955726.994 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-19T00:35:26.999Z,1552955726.999 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-19T00:35:27.042Z,1552955727.042 [DepthRateCalculator] Loaded
2019-03-19T00:35:27.042Z,1552955727.042 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-19T00:35:27.049Z,1552955727.049 [PitchRateCalculator] Loaded
2019-03-19T00:35:27.049Z,1552955727.049 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-19T00:35:27.065Z,1552955727.065 [SpeedCalculator] Loaded
2019-03-19T00:35:27.065Z,1552955727.065 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-19T00:35:27.087Z,1552955727.087 [TempGradientCalculator] Loaded
2019-03-19T00:35:27.087Z,1552955727.087 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-19T00:35:27.093Z,1552955727.093 [YawRateCalculator] Loaded
2019-03-19T00:35:27.094Z,1552955727.094 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-19T00:35:27.135Z,1552955727.135 [ElevatorOffsetCalculator] Loaded
2019-03-19T00:35:27.135Z,1552955727.135 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-19T00:35:27.135Z,1552955727.135 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-19T00:35:27.136Z,1552955727.136 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-19T00:35:27.375Z,1552955727.375 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-19T00:35:27.375Z,1552955727.375 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-19T00:35:27.690Z,1552955727.690 [DataOverHttps] Loaded
2019-03-19T00:35:27.690Z,1552955727.690 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-19T00:35:27.691Z,1552955727.691 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407CE4E0
2019-03-19T00:35:27.691Z,1552955727.691 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1104
2019-03-19T00:35:27.728Z,1552955727.728 [Depth_Keller] Loaded
2019-03-19T00:35:27.729Z,1552955727.729 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-19T00:35:27.829Z,1552955727.829 [NAL9602] Loaded
2019-03-19T00:35:27.829Z,1552955727.829 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-19T00:35:27.845Z,1552955727.845 [Onboard] Loaded
2019-03-19T00:35:27.845Z,1552955727.845 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-19T00:35:27.852Z,1552955727.852 [Radio_Surface] Loaded
2019-03-19T00:35:27.852Z,1552955727.852 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-19T00:35:27.853Z,1552955727.853 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407FE4E0
2019-03-19T00:35:27.854Z,1552955727.854 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1105
2019-03-19T00:35:30.272Z,1552955730.272 [BPC1] Loaded
2019-03-19T00:35:30.272Z,1552955730.272 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-19T00:35:30.272Z,1552955730.272 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-19T00:35:30.273Z,1552955730.273 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-19T00:35:30.336Z,1552955730.336 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-19T00:35:30.337Z,1552955730.337 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-19T00:35:30.441Z,1552955730.441 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-19T00:35:30.441Z,1552955730.441 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-19T00:35:30.501Z,1552955730.501 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-19T00:35:30.502Z,1552955730.502 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-19T00:35:30.523Z,1552955730.523 [NavChart] Loaded
2019-03-19T00:35:30.523Z,1552955730.523 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-19T00:35:30.527Z,1552955730.527 [UniversalFixResidualReporter] Loaded
2019-03-19T00:35:30.527Z,1552955730.527 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-19T00:35:30.528Z,1552955730.528 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-19T00:35:30.528Z,1552955730.528 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-19T00:35:30.779Z,1552955730.779 [CTD_NeilBrown] Loaded
2019-03-19T00:35:30.779Z,1552955730.779 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-19T00:35:30.780Z,1552955730.780 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409524E0
2019-03-19T00:35:30.781Z,1552955730.781 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1106
2019-03-19T00:35:30.830Z,1552955730.830 [WetLabsBB2FL] Loaded
2019-03-19T00:35:30.831Z,1552955730.831 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-19T00:35:30.832Z,1552955730.832 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409824E0
2019-03-19T00:35:30.832Z,1552955730.832 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1107
2019-03-19T00:35:30.833Z,1552955730.833 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-19T00:35:30.833Z,1552955730.833 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-19T00:35:30.901Z,1552955730.901 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-19T00:35:31.002Z,1552955731.002 [VerticalControl] Loaded
2019-03-19T00:35:31.002Z,1552955731.002 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-19T00:35:31.003Z,1552955731.003 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-19T00:35:31.073Z,1552955731.073 [HorizontalControl] Loaded
2019-03-19T00:35:31.073Z,1552955731.073 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-19T00:35:31.074Z,1552955731.074 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-19T00:35:31.079Z,1552955731.079 [SpeedControl] Loaded
2019-03-19T00:35:31.079Z,1552955731.079 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-19T00:35:31.080Z,1552955731.080 [LoopControl](DEBUG): Construct LoopControl.
2019-03-19T00:35:31.080Z,1552955731.080 [LoopControl] Loaded
2019-03-19T00:35:31.081Z,1552955731.081 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-19T00:35:31.081Z,1552955731.081 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-19T00:35:31.082Z,1552955731.082 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-19T00:35:31.095Z,1552955731.095 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-19T00:35:31.095Z,1552955731.095 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-19T00:35:31.232Z,1552955731.232 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-19T00:35:31.244Z,1552955731.244 [SBIT] Loaded
2019-03-19T00:35:31.244Z,1552955731.244 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-19T00:35:31.245Z,1552955731.245 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-19T00:35:31.257Z,1552955731.257 [IBIT] Loaded
2019-03-19T00:35:31.257Z,1552955731.257 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-19T00:35:31.260Z,1552955731.260 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-19T00:35:31.402Z,1552955731.402 [CBIT] Loaded
2019-03-19T00:35:31.402Z,1552955731.402 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-19T00:35:31.402Z,1552955731.402 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-19T00:35:31.403Z,1552955731.403 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-19T00:35:31.504Z,1552955731.504 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-19T00:35:31.504Z,1552955731.504 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-19T00:35:31.532Z,1552955731.532 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-19T00:35:31.533Z,1552955731.533 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-19T00:35:31.634Z,1552955731.634 [BuoyancyServo] Loaded
2019-03-19T00:35:31.634Z,1552955731.634 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-19T00:35:31.646Z,1552955731.646 [ElevatorServo] Loaded
2019-03-19T00:35:31.646Z,1552955731.646 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-19T00:35:31.653Z,1552955731.653 [MassServo](DEBUG): LcmSlateWriter::add(): platform_mass_position
2019-03-19T00:35:31.657Z,1552955731.657 [MassServo](INFO): created writer for : platform_mass_position
2019-03-19T00:35:31.659Z,1552955731.659 [MassServo](DEBUG): LcmSlateWriter::add(): fdi_state
2019-03-19T00:35:31.664Z,1552955731.664 [MassServo](INFO): created writer for : fdi_state
2019-03-19T00:35:31.665Z,1552955731.665 [MassServo] Loaded
2019-03-19T00:35:31.665Z,1552955731.665 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-19T00:35:31.677Z,1552955731.677 [RudderServo] Loaded
2019-03-19T00:35:31.677Z,1552955731.677 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-19T00:35:31.688Z,1552955731.688 [ThrusterServo] Loaded
2019-03-19T00:35:31.688Z,1552955731.688 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-19T00:35:31.689Z,1552955731.689 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-19T00:35:31.692Z,1552955731.692 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-19T00:35:31.693Z,1552955731.693 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-19T00:35:31.700Z,1552955731.700 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-19T00:35:31.701Z,1552955731.701 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0
2019-03-19T00:35:31.701Z,1552955731.701 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1108
2019-03-19T00:35:31.706Z,1552955731.706 [Supervisor](INFO): Main Thread ID is 798
2019-03-19T00:35:31.706Z,1552955731.706 [Supervisor](DEBUG): Running supervisor.
2019-03-19T00:35:31.707Z,1552955731.707 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1109
2019-03-19T00:35:31.710Z,1552955731.710 [controlThread ThreadHandler](INFO): Handler Thread ID is 1110
2019-03-19T00:35:31.710Z,1552955731.710 [controlThread](DEBUG): Initializing ControlThread
2019-03-19T00:35:31.711Z,1552955731.711 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-19T00:35:31.712Z,1552955731.712 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-19T00:35:31.712Z,1552955731.712 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-19T00:35:31.712Z,1552955731.712 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-19T00:35:31.713Z,1552955731.713 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-19T00:35:31.713Z,1552955731.713 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-19T00:35:31.718Z,1552955731.718 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:35:31.719Z,1552955731.719 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-19T00:35:31.719Z,1552955731.719 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-19T00:35:31.719Z,1552955731.719 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-19T00:35:31.721Z,1552955731.721 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-19T00:35:31.722Z,1552955731.722 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-19T00:35:31.722Z,1552955731.722 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-19T00:35:31.722Z,1552955731.722 [SBIT](INFO): Initialize SBIT Component.
2019-03-19T00:35:31.723Z,1552955731.723 [SBIT](IMPORTANT): git: 2019-03-04-46-gd6610ff
2019-03-19T00:35:31.723Z,1552955731.723 [SBIT](INFO): git hash: d6610ff838e6b7a25a908fc1e9fd291f97bf1870
2019-03-19T00:35:31.723Z,1552955731.723 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-19T00:35:31.723Z,1552955731.723 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-19T00:35:31.725Z,1552955731.725 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-19T00:35:31.725Z,1552955731.725 [IBIT](INFO): Initialize IBIT Component.
2019-03-19T00:35:31.726Z,1552955731.726 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-19T00:35:31.727Z,1552955731.727 [logger ThreadHandler](INFO): Handler Thread ID is 1111
2019-03-19T00:35:31.737Z,1552955731.737 [CBIT](DEBUG): Initialized mux pins.
2019-03-19T00:35:31.737Z,1552955731.737 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-19T00:35:31.745Z,1552955731.745 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1112
2019-03-19T00:35:31.746Z,1552955731.746 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-19T00:35:31.757Z,1552955731.757 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1113
2019-03-19T00:35:31.761Z,1552955731.761 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-19T00:35:31.761Z,1552955731.761 [CBIT](DEBUG): Initializing heartbeat.
2019-03-19T00:35:31.769Z,1552955731.769 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1114
2019-03-19T00:35:31.777Z,1552955731.777 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1115
2019-03-19T00:35:31.778Z,1552955731.778 [WetLabsBB2FL](INFO): Powering down
2019-03-19T00:35:31.805Z,1552955731.805 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1116
2019-03-19T00:35:31.808Z,1552955731.808 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-19T00:35:31.808Z,1552955731.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-19T00:35:31.809Z,1552955731.809 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-19T00:35:31.809Z,1552955731.809 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-19T00:35:31.809Z,1552955731.809 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-19T00:35:31.810Z,1552955731.810 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-19T00:35:31.810Z,1552955731.810 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-19T00:35:31.810Z,1552955731.810 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-19T00:35:31.810Z,1552955731.810 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-19T00:35:31.810Z,1552955731.810 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-19T00:35:31.810Z,1552955731.810 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-19T00:35:31.811Z,1552955731.811 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-19T00:35:31.811Z,1552955731.811 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-19T00:35:31.811Z,1552955731.811 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-19T00:35:31.811Z,1552955731.811 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-19T00:35:31.811Z,1552955731.811 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-19T00:35:31.833Z,1552955731.833 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-19T00:35:31.833Z,1552955731.833 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-19T00:35:31.871Z,1552955731.871 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-19T00:35:31.881Z,1552955731.881 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-19T00:35:31.892Z,1552955731.892 [MissionManager](DEBUG):
2019-03-19T00:35:31.892Z,1552955731.892 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-19T00:35:31.977Z,1552955731.977 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-19T00:35:31.978Z,1552955731.978 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-19T00:35:31.980Z,1552955731.980 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-19T00:35:32.012Z,1552955732.012 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-19T00:35:32.014Z,1552955732.014 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-19T00:35:32.024Z,1552955732.024 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-19T00:35:32.028Z,1552955732.028 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-03-19T00:35:32.036Z,1552955732.036 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,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-03-19T00:35:32.086Z,1552955732.086 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2019-03-19T00:35:32.142Z,1552955732.142 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-19T00:35:32.161Z,1552955732.161 [Radio_Surface](INFO): Powering up
2019-03-19T00:35:32.166Z,1552955732.166 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:32.173Z,1552955732.173 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-19T00:35:32.174Z,1552955732.174 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:32.189Z,1552955732.189 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-19T00:35:32.190Z,1552955732.190 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:32.197Z,1552955732.197 [MassServo](DEBUG): Initializing MassServo.
2019-03-19T00:35:32.198Z,1552955732.198 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:32.213Z,1552955732.213 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-19T00:35:32.214Z,1552955732.214 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:32.221Z,1552955732.221 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-19T00:35:32.473Z,1552955732.473 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-19T00:35:36.998Z,1552955736.998 [RudderServo](FAULT): Overload Error
2019-03-19T00:35:36.998Z,1552955736.998 [RudderServo] Hardware Fault, FailCount= 1
2019-03-19T00:35:36.998Z,1552955736.998 [RudderServo](ERROR): Hardware Fault
2019-03-19T00:35:37.037Z,1552955737.037 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-03-19T00:35:37.354Z,1552955737.354 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-19T00:35:37.354Z,1552955737.354 [RudderServo](INFO): Powering down
2019-03-19T00:35:38.162Z,1552955738.162 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:38.282Z,1552955738.282 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-19T00:35:38.286Z,1552955738.286 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-19T00:35:38.286Z,1552955738.286 [RudderServo] No Fault, FailCount= 1
2019-03-19T00:35:42.997Z,1552955742.997 [RudderServo](FAULT): Overload Error
2019-03-19T00:35:42.997Z,1552955742.997 [RudderServo] Hardware Fault, FailCount= 2
2019-03-19T00:35:42.997Z,1552955742.997 [RudderServo](ERROR): Hardware Fault
2019-03-19T00:35:43.037Z,1552955743.037 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-03-19T00:35:43.378Z,1552955743.378 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-19T00:35:43.378Z,1552955743.378 [RudderServo](INFO): Powering down
2019-03-19T00:35:44.186Z,1552955744.186 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:44.306Z,1552955744.306 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-19T00:35:44.310Z,1552955744.310 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-19T00:35:44.310Z,1552955744.310 [RudderServo] No Fault, FailCount= 2
2019-03-19T00:35:49.057Z,1552955749.057 [RudderServo](FAULT): Overload Error
2019-03-19T00:35:49.057Z,1552955749.057 [RudderServo] Hardware Fault, FailCount= 3
2019-03-19T00:35:49.057Z,1552955749.057 [RudderServo](ERROR): Hardware Fault
2019-03-19T00:35:49.097Z,1552955749.097 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-03-19T00:35:49.097Z,1552955749.097 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2019-03-19T00:35:49.450Z,1552955749.450 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-19T00:35:49.450Z,1552955749.450 [RudderServo](INFO): Powering down
2019-03-19T00:35:49.452Z,1552955749.452 [CommandLine](FAULT): Scheduling is paused
2019-03-19T00:35:49.452Z,1552955749.452 [CBIT](INFO): Critical error at 20190319T003549
2019-03-19T00:35:49.457Z,1552955749.457 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-19T00:35:50.249Z,1552955750.249 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:35:50.370Z,1552955750.370 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-19T00:35:57.898Z,1552955757.898 [NAL9602](INFO): Powering up NAL9602
2019-03-19T00:36:00.346Z,1552955760.346 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-19T00:36:00.351Z,1552955760.351 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-19T00:36:02.405Z,1552955762.405 [ElevatorServo](FAULT): Overload Error
2019-03-19T00:36:02.405Z,1552955762.405 [ElevatorServo] Hardware Fault, FailCount= 1
2019-03-19T00:36:02.405Z,1552955762.405 [ElevatorServo](ERROR): Hardware Fault
2019-03-19T00:36:02.830Z,1552955762.830 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2019-03-19T00:36:02.862Z,1552955762.862 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-19T00:36:02.862Z,1552955762.862 [ElevatorServo](INFO): Powering down
2019-03-19T00:36:03.670Z,1552955763.670 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:36:03.786Z,1552955763.786 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-19T00:36:03.829Z,1552955763.829 [CBIT](INFO): Clearing failed state for component ElevatorServo
2019-03-19T00:36:03.829Z,1552955763.829 [ElevatorServo] No Fault, FailCount= 1
2019-03-19T00:36:08.601Z,1552955768.601 [ElevatorServo](FAULT): Overload Error
2019-03-19T00:36:08.601Z,1552955768.601 [ElevatorServo] Hardware Fault, FailCount= 2
2019-03-19T00:36:08.601Z,1552955768.601 [ElevatorServo](ERROR): Hardware Fault
2019-03-19T00:36:08.642Z,1552955768.642 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2019-03-19T00:36:08.898Z,1552955768.898 [NAL9602](INFO): NAL9602 initialized
2019-03-19T00:36:08.927Z,1552955768.927 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-19T00:36:08.927Z,1552955768.927 [ElevatorServo](INFO): Powering down
2019-03-19T00:36:09.717Z,1552955769.717 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:36:09.742Z,1552955769.742 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:36:09.858Z,1552955769.858 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-19T00:36:09.902Z,1552955769.902 [CBIT](INFO): Clearing failed state for component ElevatorServo
2019-03-19T00:36:09.902Z,1552955769.902 [ElevatorServo] No Fault, FailCount= 2
2019-03-19T00:36:11.397Z,1552955771.397 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007129
CHAN A1 (24V): 0.001671
CHAN A2 (12V): -0.005135
CHAN A3 (5V): -0.002160
CHAN B0 (3.3V): -0.000816
CHAN B1 (3.15aV): -0.001235
CHAN B2 (3.15bV): -0.001661
CHAN B3 (GND): -0.000633
OPEN: -0.001321
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-19T00:36:13.471Z,1552955773.471 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-19T00:36:15.013Z,1552955775.013 [ElevatorServo](FAULT): Overload Error
2019-03-19T00:36:15.013Z,1552955775.013 [ElevatorServo] Hardware Fault, FailCount= 3
2019-03-19T00:36:15.013Z,1552955775.013 [ElevatorServo](ERROR): Hardware Fault
2019-03-19T00:36:15.053Z,1552955775.053 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2019-03-19T00:36:15.054Z,1552955775.054 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2019-03-19T00:36:15.400Z,1552955775.400 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-19T00:36:15.400Z,1552955775.400 [ElevatorServo](INFO): Powering down
2019-03-19T00:36:15.407Z,1552955775.407 [CBIT](INFO): Critical error at 20190319T003615
2019-03-19T00:36:16.199Z,1552955776.199 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-19T00:36:16.314Z,1552955776.314 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-19T00:36:40.194Z,1552955800.194 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-03-19T00:36:40.194Z,1552955800.194 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-19T00:36:40.194Z,1552955800.194 [SBIT](FAULT): Control surface position failure.
2019-03-19T00:36:53.970Z,1552955813.970 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-03-19T00:36:53.970Z,1552955813.970 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-19T00:36:53.970Z,1552955813.970 [SBIT](FAULT): Control surface position failure.
2019-03-19T00:36:54.368Z,1552955814.368 [SBIT](CRITICAL): SBIT FAILED
2019-03-19T00:36:54.383Z,1552955814.383 [CBIT](INFO): Critical error at 20190319T003654
2019-03-19T00:36:54.419Z,1552955814.419 [CommandLine](IMPORTANT): got command configSet list
2019-03-19T00:36:54.419Z,1552955814.419 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-19T00:36:54.420Z,1552955814.420 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;
2019-03-19T00:36:54.420Z,1552955814.420 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;
2019-03-19T00:36:54.420Z,1552955814.420 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2019-03-19T00:36:54.742Z,1552955814.742 [MissionManager](IMPORTANT): Started mission Startup
2019-03-19T00:36:54.742Z,1552955814.742 [Startup] Running Loop=1
2019-03-19T00:36:54.742Z,1552955814.742 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-19T00:36:54.742Z,1552955814.742 [Startup:A.GoToSurface] Running Loop=1
2019-03-19T00:36:54.742Z,1552955814.742 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-19T00:36:54.743Z,1552955814.743 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-19T00:36:54.743Z,1552955814.743 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-19T00:36:54.744Z,1552955814.744 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-19T00:36:54.744Z,1552955814.744 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-19T00:36:54.744Z,1552955814.744 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-19T00:36:54.746Z,1552955814.746 [Startup:StartupSatComms] Running Loop=1
2019-03-19T00:36:54.746Z,1552955814.746 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-19T00:36:54.746Z,1552955814.746 [Startup:StartupSatComms:A] Running Loop=1
2019-03-19T00:36:55.138Z,1552955815.138 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-19T00:37:31.897Z,1552955851.897 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:37:31.898Z,1552955851.898 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-19T00:37:31.898Z,1552955851.898 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-19T00:37:31.899Z,1552955851.899 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:37:31.899Z,1552955851.899 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-19T00:37:31.899Z,1552955851.899 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-19T00:37:31.949Z,1552955851.949 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-19T00:37:31.950Z,1552955851.950 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-19T00:37:32.313Z,1552955852.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-19T00:37:32.313Z,1552955852.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-19T00:37:32.314Z,1552955852.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-19T00:37:32.314Z,1552955852.314 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-19T00:37:32.761Z,1552955852.761 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:37:54.930Z,1552955874.930 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-19T00:36:54.7Z
2019-03-19T00:37:54.930Z,1552955874.930 [Startup:StartupSatComms:A] Stopped
2019-03-19T00:37:54.930Z,1552955874.930 [Startup:StartupSatComms:B] Running Loop=1
2019-03-19T00:37:55.334Z,1552955875.334 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-19T00:37:57.855Z,1552955877.855 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004890
2019-03-19T00:38:03.912Z,1552955883.912 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190318T230907/Courier0028.lzma
2019-03-19T00:38:04.718Z,1552955884.718 [DataOverHttps](INFO): Moved sent file to Logs/20190318T230907/Courier0028.lzma.bak
2019-03-19T00:38:04.719Z,1552955884.719 [DataOverHttps](INFO): SBD MOMSN=10190175
2019-03-19T00:38:16.492Z,1552955896.492 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20190319T003521/Courier0000.lzma
2019-03-19T00:38:17.298Z,1552955897.298 [DataOverHttps](INFO): Moved sent file to Logs/20190319T003521/Courier0000.lzma.bak
2019-03-19T00:38:17.299Z,1552955897.299 [DataOverHttps](INFO): SBD MOMSN=10190178
2019-03-19T00:38:30.754Z,1552955910.754 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20190318T230907/Express0029.lzma
2019-03-19T00:38:31.558Z,1552955911.558 [DataOverHttps](INFO): Moved sent file to Logs/20190318T230907/Express0029.lzma.bak
2019-03-19T00:38:31.558Z,1552955911.558 [DataOverHttps](INFO): SBD MOMSN=10190184
2019-03-19T00:38:44.513Z,1552955924.513 [DataOverHttps](INFO): Sending 907 bytes from file Logs/20190319T003521/Express0001.lzma
2019-03-19T00:38:45.320Z,1552955925.320 [DataOverHttps](INFO): Moved sent file to Logs/20190319T003521/Express0001.lzma.bak
2019-03-19T00:38:45.320Z,1552955925.320 [DataOverHttps](INFO): SBD MOMSN=10190191
2019-03-19T00:38:48.706Z,1552955928.706 [Startup:StartupSatComms:B] Stopped
2019-03-19T00:38:48.706Z,1552955928.706 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-19T00:38:48.706Z,1552955928.706 [Startup:StartupSatComms] Stopped
2019-03-19T00:38:48.706Z,1552955928.706 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-19T00:38:48.707Z,1552955928.707 [Startup](INFO): Completed Startup
2019-03-19T00:38:48.707Z,1552955928.707 [MissionManager](INFO): Startup is completed.
2019-03-19T00:38:48.707Z,1552955928.707 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-19T00:38:48.707Z,1552955928.707 [Startup] Stopped
2019-03-19T00:38:48.707Z,1552955928.707 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-19T00:38:48.707Z,1552955928.707 [Startup:A.GoToSurface] Stopped
2019-03-19T00:38:48.707Z,1552955928.707 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-19T00:38:49.121Z,1552955929.121 [MissionManager](IMPORTANT): Started mission Default
2019-03-19T00:38:49.122Z,1552955929.122 [Default] Running Loop=1
2019-03-19T00:38:49.122Z,1552955929.122 [Default](DEBUG): Aggregate::initialize Default
2019-03-19T00:38:49.122Z,1552955929.122 [Default:B.GoToSurface] Running Loop=1
2019-03-19T00:38:49.122Z,1552955929.122 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-19T00:38:49.122Z,1552955929.122 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-19T00:38:49.122Z,1552955929.122 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-19T00:38:49.123Z,1552955929.123 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-19T00:38:49.123Z,1552955929.123 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-19T00:38:49.123Z,1552955929.123 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-19T00:38:49.124Z,1552955929.124 [Default:A.Wait] Running Loop=1
2019-03-19T00:38:49.124Z,1552955929.124 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-19T00:39:02.446Z,1552955942.446 [Default:A.Wait](INFO): Done Waiting.
2019-03-19T00:39:02.446Z,1552955942.446 [Default:A.Wait] Stopped
2019-03-19T00:39:02.446Z,1552955942.446 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-19T00:39:02.854Z,1552955942.854 [Default:CheckIn] Running Loop=1
2019-03-19T00:39:02.854Z,1552955942.854 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-19T00:39:02.854Z,1552955942.854 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-19T00:39:03.258Z,1552955943.258 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-19T00:39:33.148Z,1552955973.148 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:39:33.148Z,1552955973.148 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-19T00:39:33.148Z,1552955973.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-19T00:39:33.150Z,1552955973.150 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:39:33.150Z,1552955973.150 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-19T00:39:33.150Z,1552955973.150 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-19T00:39:33.176Z,1552955973.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-19T00:39:33.176Z,1552955973.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-19T00:39:33.573Z,1552955973.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-19T00:39:33.573Z,1552955973.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-19T00:39:33.574Z,1552955973.574 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-19T00:39:33.574Z,1552955973.574 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-19T00:39:33.955Z,1552955973.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:40:25.572Z,1552956025.572 [CommandLine](INFO): End of History
2019-03-19T00:40:26.341Z,1552956026.341 [CommandLine](INFO): End of History
2019-03-19T00:40:26.814Z,1552956026.814 [CommandLine](INFO): End of History
2019-03-19T00:40:42.590Z,1552956042.590 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 2.000000 centimeter
2019-03-19T00:40:42.592Z,1552956042.592 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2019-03-19T00:40:42.757Z,1552956042.757 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-19T00:41:12.130Z,1552956072.130 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-19T00:41:12.949Z,1552956072.949 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:15.773Z,1552956075.773 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:17.797Z,1552956077.797 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:19.813Z,1552956079.813 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:23.045Z,1552956083.045 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:25.873Z,1552956085.873 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:29.109Z,1552956089.109 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:31.937Z,1552956091.937 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:33.968Z,1552956093.968 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:41:33.968Z,1552956093.968 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-19T00:41:33.968Z,1552956093.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-19T00:41:33.969Z,1552956093.969 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:41:33.970Z,1552956093.970 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-19T00:41:33.970Z,1552956093.970 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-19T00:41:33.980Z,1552956093.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-19T00:41:33.980Z,1552956093.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-19T00:41:34.385Z,1552956094.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-19T00:41:34.385Z,1552956094.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-19T00:41:34.385Z,1552956094.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-19T00:41:34.385Z,1552956094.385 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-19T00:41:34.776Z,1552956094.776 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:41:35.169Z,1552956095.169 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:37.997Z,1552956097.997 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:40.821Z,1552956100.821 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:44.057Z,1552956104.057 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:44.702Z,1552956104.702 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 0.000000 centimeter
2019-03-19T00:41:46.885Z,1552956106.885 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:50.141Z,1552956110.141 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:52.969Z,1552956112.969 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:55.793Z,1552956115.793 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:41:57.817Z,1552956117.817 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:01.045Z,1552956121.045 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:03.873Z,1552956123.873 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:07.109Z,1552956127.109 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:09.933Z,1552956129.933 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:13.169Z,1552956133.169 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:15.997Z,1552956135.997 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:18.821Z,1552956138.821 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:22.057Z,1552956142.057 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:24.881Z,1552956144.881 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:28.113Z,1552956148.113 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:30.941Z,1552956150.941 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:33.777Z,1552956153.777 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:35.793Z,1552956155.793 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:37.813Z,1552956157.813 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:41.045Z,1552956161.045 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:43.875Z,1552956163.875 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:47.105Z,1552956167.105 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:49.933Z,1552956169.933 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:53.161Z,1552956173.161 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:55.993Z,1552956175.993 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:42:56.834Z,1552956176.834 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6.
2019-03-19T00:42:58.821Z,1552956178.821 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:02.053Z,1552956182.053 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:04.877Z,1552956184.877 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:08.113Z,1552956188.113 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:10.941Z,1552956190.941 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:13.769Z,1552956193.769 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:15.789Z,1552956195.789 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:17.809Z,1552956197.809 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:19.829Z,1552956199.829 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:23.061Z,1552956203.061 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:25.885Z,1552956205.885 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:29.117Z,1552956209.117 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:31.949Z,1552956211.949 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:34.773Z,1552956214.773 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:34.796Z,1552956214.796 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:43:34.796Z,1552956214.796 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-19T00:43:34.796Z,1552956214.796 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-19T00:43:34.798Z,1552956214.798 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:43:34.798Z,1552956214.798 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-19T00:43:34.798Z,1552956214.798 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-19T00:43:34.809Z,1552956214.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-19T00:43:34.809Z,1552956214.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-19T00:43:35.211Z,1552956215.211 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-19T00:43:35.211Z,1552956215.211 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-19T00:43:35.212Z,1552956215.212 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-19T00:43:35.212Z,1552956215.212 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-19T00:43:35.608Z,1552956215.608 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:43:36.797Z,1552956216.797 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:38.817Z,1552956218.817 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:42.045Z,1552956222.045 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:44.877Z,1552956224.877 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:48.109Z,1552956228.109 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:50.937Z,1552956230.937 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:53.761Z,1552956233.761 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:55.785Z,1552956235.785 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:57.805Z,1552956237.805 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:43:59.825Z,1552956239.825 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:03.053Z,1552956243.053 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:03.074Z,1552956243.074 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-19T00:39:02.9Z
2019-03-19T00:44:03.074Z,1552956243.074 [Default:CheckIn:Read_GPS] Stopped
2019-03-19T00:44:03.074Z,1552956243.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-19T00:44:03.485Z,1552956243.485 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-19T00:44:05.885Z,1552956245.885 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:09.113Z,1552956249.113 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:09.460Z,1552956249.460 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20190319T003521/Courier0004.lzma
2019-03-19T00:44:10.266Z,1552956250.266 [DataOverHttps](INFO): Moved sent file to Logs/20190319T003521/Courier0004.lzma.bak
2019-03-19T00:44:10.266Z,1552956250.266 [DataOverHttps](INFO): SBD MOMSN=10190259
2019-03-19T00:44:11.941Z,1552956251.941 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:15.173Z,1552956255.173 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:18.009Z,1552956258.009 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:20.829Z,1552956260.829 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:23.857Z,1552956263.857 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20190319T003521/Express0005.lzma
2019-03-19T00:44:24.065Z,1552956264.065 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:24.662Z,1552956264.662 [DataOverHttps](INFO): Moved sent file to Logs/20190319T003521/Express0005.lzma.bak
2019-03-19T00:44:24.662Z,1552956264.662 [DataOverHttps](INFO): SBD MOMSN=10190261
2019-03-19T00:44:26.889Z,1552956266.889 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:28.114Z,1552956268.114 [Default:CheckIn:Read_Iridium] Stopped
2019-03-19T00:44:28.114Z,1552956268.114 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-19T00:44:28.114Z,1552956268.114 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-19T00:44:30.121Z,1552956270.121 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:32.949Z,1552956272.949 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:35.777Z,1552956275.777 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:37.797Z,1552956277.797 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:39.821Z,1552956279.821 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:43.053Z,1552956283.053 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:45.881Z,1552956285.881 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:49.109Z,1552956289.109 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:51.941Z,1552956291.941 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:54.761Z,1552956294.761 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:56.789Z,1552956296.789 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:44:58.805Z,1552956298.805 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:00.829Z,1552956300.829 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:04.061Z,1552956304.061 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:06.881Z,1552956306.881 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:10.121Z,1552956310.121 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:12.945Z,1552956312.945 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:15.773Z,1552956315.773 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:17.797Z,1552956317.797 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:19.817Z,1552956319.817 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:23.045Z,1552956323.045 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:25.877Z,1552956325.877 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:29.109Z,1552956329.109 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:31.941Z,1552956331.941 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:35.169Z,1552956335.169 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:36.004Z,1552956336.004 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:45:36.004Z,1552956336.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-03-19T00:45:36.004Z,1552956336.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-19T00:45:36.005Z,1552956336.005 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:45:36.006Z,1552956336.006 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-03-19T00:45:36.006Z,1552956336.006 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-19T00:45:36.016Z,1552956336.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-19T00:45:36.016Z,1552956336.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-19T00:45:36.393Z,1552956336.393 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-19T00:45:36.393Z,1552956336.393 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-03-19T00:45:36.393Z,1552956336.393 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-19T00:45:36.393Z,1552956336.393 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-03-19T00:45:36.792Z,1552956336.792 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:45:37.997Z,1552956337.997 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:40.817Z,1552956340.817 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:44.057Z,1552956344.057 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:46.881Z,1552956346.881 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:50.113Z,1552956350.113 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:52.945Z,1552956352.945 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:55.773Z,1552956355.773 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:57.793Z,1552956357.793 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:45:59.809Z,1552956359.809 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:46:03.045Z,1552956363.045 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:46:05.877Z,1552956365.877 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:46:09.105Z,1552956369.105 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:46:11.922Z,1552956371.922 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-19T00:46:11.922Z,1552956371.922 [NAL9602] Data Fault, FailCount= 1
2019-03-19T00:46:11.922Z,1552956371.922 [NAL9602](ERROR): Data Fault
2019-03-19T00:46:11.950Z,1552956371.950 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-19T00:46:12.318Z,1552956372.318 [NAL9602](INFO): Powering down
2019-03-19T00:46:13.165Z,1552956373.165 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-19T00:46:13.165Z,1552956373.165 [NAL9602] No Fault, FailCount= 1
2019-03-19T00:46:42.626Z,1552956402.626 [NAL9602](INFO): Powering up NAL9602
2019-03-19T00:46:53.542Z,1552956413.542 [NAL9602](INFO): NAL9602 initialized
2019-03-19T00:46:54.361Z,1552956414.361 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:46:56.785Z,1552956416.785 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:46:59.609Z,1552956419.609 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:02.845Z,1552956422.845 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:05.669Z,1552956425.669 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:08.497Z,1552956428.497 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:10.517Z,1552956430.517 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:12.541Z,1552956432.541 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:14.561Z,1552956434.561 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:17.793Z,1552956437.793 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:20.617Z,1552956440.617 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:23.853Z,1552956443.853 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:26.677Z,1552956446.677 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:29.509Z,1552956449.509 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:31.525Z,1552956451.525 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:33.545Z,1552956453.545 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:36.781Z,1552956456.781 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:36.804Z,1552956456.804 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:47:36.804Z,1552956456.804 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-03-19T00:47:36.804Z,1552956456.804 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-19T00:47:36.807Z,1552956456.807 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-19T00:47:36.807Z,1552956456.807 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-03-19T00:47:36.807Z,1552956456.807 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-19T00:47:36.817Z,1552956456.817 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-19T00:47:36.818Z,1552956456.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-19T00:47:37.217Z,1552956457.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-19T00:47:37.217Z,1552956457.217 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-03-19T00:47:37.218Z,1552956457.218 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-19T00:47:37.218Z,1552956457.218 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-03-19T00:47:37.608Z,1552956457.608 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-19T00:47:39.605Z,1552956459.605 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:42.841Z,1552956462.841 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:45.665Z,1552956465.665 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:48.897Z,1552956468.897 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:51.729Z,1552956471.729 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:54.553Z,1552956474.553 [NAL9602](DEBUG): Fix Requested
2019-03-19T00:47:54.863Z,1552956474.863 [CommandLine](IMPORTANT): got command restart application
2019-03-19T00:47:55.869Z,1552956475.869 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:55.869Z,1552956475.869 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:55.893Z,1552956475.893 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-03-19T00:47:55.893Z,1552956475.893 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:55.894Z,1552956475.894 [CommandLine](INFO): Join timeout helper Thread ID is 1155
2019-03-19T00:47:55.894Z,1552956475.894 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-19T00:47:55.894Z,1552956475.894 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:55.895Z,1552956475.895 [NavChartDb](INFO): Join timeout helper Thread ID is 1156
2019-03-19T00:47:55.974Z,1552956475.974 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:55.974Z,1552956475.974 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:55.993Z,1552956475.993 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-19T00:47:55.993Z,1552956475.993 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:55.993Z,1552956475.993 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1157
2019-03-19T00:47:56.477Z,1552956476.477 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:56.477Z,1552956476.477 [WetLabsBB2FL](INFO): Powering down
2019-03-19T00:47:56.478Z,1552956476.478 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.481Z,1552956476.481 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-19T00:47:56.481Z,1552956476.481 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.481Z,1552956476.481 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1158
2019-03-19T00:47:56.485Z,1552956476.485 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:56.485Z,1552956476.485 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.501Z,1552956476.501 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-19T00:47:56.501Z,1552956476.501 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.501Z,1552956476.501 [Radio_Surface](INFO): Join timeout helper Thread ID is 1159
2019-03-19T00:47:56.733Z,1552956476.733 [Radio_Surface](INFO): Powering down
2019-03-19T00:47:56.734Z,1552956476.734 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:56.734Z,1552956476.734 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.742Z,1552956476.742 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-19T00:47:56.742Z,1552956476.742 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.742Z,1552956476.742 [DataOverHttps](INFO): Join timeout helper Thread ID is 1160
2019-03-19T00:47:56.813Z,1552956476.813 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:56.813Z,1552956476.813 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.821Z,1552956476.821 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-19T00:47:56.822Z,1552956476.822 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.822Z,1552956476.822 [logger](INFO): Join timeout helper Thread ID is 1161
2019-03-19T00:47:56.833Z,1552956476.833 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:56.833Z,1552956476.833 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.841Z,1552956476.841 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-19T00:47:56.842Z,1552956476.842 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.842Z,1552956476.842 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-19T00:47:56.842Z,1552956476.842 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:56.842Z,1552956476.842 [controlThread](INFO): Join timeout helper Thread ID is 1162
2019-03-19T00:47:56.957Z,1552956476.957 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-19T00:47:56.957Z,1552956476.957 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-19T00:47:56.958Z,1552956476.958 [NAL9602](INFO): Powering down
2019-03-19T00:47:56.959Z,1552956476.959 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-19T00:47:56.960Z,1552956476.960 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-19T00:47:56.961Z,1552956476.961 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-19T00:47:56.962Z,1552956476.962 [MissionManager](INFO): Uninitializing Mission Default
2019-03-19T00:47:56.962Z,1552956476.962 [Default] Stopped
2019-03-19T00:47:56.962Z,1552956476.962 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-19T00:47:56.962Z,1552956476.962 [Default:B.GoToSurface] Stopped
2019-03-19T00:47:56.962Z,1552956476.962 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-19T00:47:56.962Z,1552956476.962 [Default:CheckIn] Stopped
2019-03-19T00:47:56.962Z,1552956476.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-19T00:47:56.962Z,1552956476.962 [Default:CheckIn:C.Wait] Stopped
2019-03-19T00:47:56.962Z,1552956476.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-19T00:47:56.965Z,1552956476.965 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-19T00:47:56.965Z,1552956476.965 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-19T00:47:56.965Z,1552956476.965 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-19T00:47:56.965Z,1552956476.965 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-19T00:47:56.966Z,1552956476.966 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-19T00:47:56.966Z,1552956476.966 [BuoyancyServo](INFO): Powering down
2019-03-19T00:47:56.977Z,1552956476.977 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-19T00:47:56.977Z,1552956476.977 [ElevatorServo](INFO): Powering down
2019-03-19T00:47:56.978Z,1552956476.978 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-19T00:47:56.978Z,1552956476.978 [MassServo](INFO): Powering down
2019-03-19T00:47:56.979Z,1552956476.979 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-19T00:47:56.979Z,1552956476.979 [RudderServo](INFO): Powering down
2019-03-19T00:47:56.979Z,1552956476.979 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-19T00:47:56.980Z,1552956476.980 [ThrusterServo](INFO): Powering down
2019-03-19T00:47:56.980Z,1552956476.980 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-19T00:47:56.981Z,1552956476.981 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-19T00:47:56.981Z,1552956476.981 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-19T00:47:56.981Z,1552956476.981 [CBIT](DEBUG): Powering off loads.
2019-03-19T00:47:56.993Z,1552956476.993 [CBIT](DEBUG): Disabling WDT.
2019-03-19T00:47:57.005Z,1552956477.005 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-19T00:47:57.006Z,1552956477.006 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:57.086Z,1552956477.086 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:57.092Z,1552956477.092 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:57.123Z,1552956477.123 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:57.125Z,1552956477.125 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:57.407Z,1552956477.407 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-19T00:47:57.462Z,1552956477.462 [logger ThreadHandler](INFO): Thread cancelled.