2017-05-11T16:48:56.678Z,1494521336.678 [Supervisor](DEBUG): Initializing supervisor.
2017-05-11T16:48:56.684Z,1494521336.684 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-05-11T16:48:56.684Z,1494521336.684 [SyncHandler](INFO): Protected caller Thread ID is 769
2017-05-11T16:48:56.685Z,1494521336.685 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-05-11T16:48:56.688Z,1494521336.688 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-05-11T16:48:56.688Z,1494521336.688 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770
2017-05-11T16:48:56.691Z,1494521336.691 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-05-11T16:48:56.703Z,1494521336.703 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-05-11T16:48:56.708Z,1494521336.708 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-05-11T16:48:56.708Z,1494521336.708 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771
2017-05-11T16:48:56.709Z,1494521336.709 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-05-11T16:48:56.712Z,1494521336.712 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-05-11T16:48:56.712Z,1494521336.712 [logger ThreadHandler](INFO): Protected caller Thread ID is 772
2017-05-11T16:48:56.714Z,1494521336.714 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-05-11T16:48:56.715Z,1494521336.714 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-05-11T16:48:56.719Z,1494521336.719 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-05-11T16:48:56.815Z,1494521336.815 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-05-11T16:48:57.181Z,1494521337.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-05-11T16:48:57.182Z,1494521337.181 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-05-11T16:48:57.328Z,1494521337.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-05-11T16:48:57.329Z,1494521337.329 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-05-11T16:48:57.552Z,1494521337.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-05-11T16:48:57.553Z,1494521337.553 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-05-11T16:48:57.664Z,1494521337.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-05-11T16:48:57.665Z,1494521337.665 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-05-11T16:48:57.884Z,1494521337.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-05-11T16:48:57.886Z,1494521337.886 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-05-11T16:48:58.405Z,1494521338.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-05-11T16:48:58.405Z,1494521338.405 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-05-11T16:48:58.727Z,1494521338.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-05-11T16:48:58.728Z,1494521338.727 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-05-11T16:48:58.835Z,1494521338.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-05-11T16:48:58.835Z,1494521338.835 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-05-11T16:49:03.296Z,1494521343.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-05-11T16:49:03.297Z,1494521343.297 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-05-11T16:49:03.516Z,1494521343.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-05-11T16:49:03.517Z,1494521343.517 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-05-11T16:49:04.002Z,1494521344.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-05-11T16:49:04.002Z,1494521344.002 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-05-11T16:49:07.433Z,1494521347.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-05-11T16:49:07.433Z,1494521347.433 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-05-11T16:49:07.771Z,1494521347.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-05-11T16:49:07.772Z,1494521347.772 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-05-11T16:49:07.896Z,1494521347.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-05-11T16:49:07.898Z,1494521347.898 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-05-11T16:49:07.902Z,1494521347.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-05-11T16:49:08.013Z,1494521348.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-05-11T16:49:08.116Z,1494521348.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-05-11T16:49:08.215Z,1494521348.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-05-11T16:49:08.328Z,1494521348.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-05-11T16:49:08.430Z,1494521348.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-05-11T16:49:08.572Z,1494521348.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-05-11T16:49:08.677Z,1494521348.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-05-11T16:49:08.876Z,1494521348.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-05-11T16:49:11.885Z,1494521351.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-05-11T16:49:11.886Z,1494521351.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-05-11T16:49:12.547Z,1494521352.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-05-11T16:49:12.647Z,1494521352.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-05-11T16:49:12.728Z,1494521352.728 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-05-11T16:49:12.732Z,1494521352.731 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-05-11T16:49:12.804Z,1494521352.804 [VerticalControl](DEBUG): Construct VerticalControl.
2017-05-11T16:49:12.922Z,1494521352.922 [VerticalControl] Loaded
2017-05-11T16:49:12.922Z,1494521352.922 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-05-11T16:49:12.923Z,1494521352.923 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-05-11T16:49:12.994Z,1494521352.994 [HorizontalControl] Loaded
2017-05-11T16:49:12.994Z,1494521352.994 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-05-11T16:49:12.995Z,1494521352.995 [SpeedControl](DEBUG): Construct SpeedControl.
2017-05-11T16:49:13.001Z,1494521353.001 [SpeedControl] Loaded
2017-05-11T16:49:13.001Z,1494521353.001 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-05-11T16:49:13.002Z,1494521353.002 [LoopControl](DEBUG): Construct LoopControl.
2017-05-11T16:49:13.002Z,1494521353.002 [LoopControl] Loaded
2017-05-11T16:49:13.002Z,1494521353.002 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-05-11T16:49:13.003Z,1494521353.003 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-05-11T16:49:13.004Z,1494521353.004 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-05-11T16:49:13.163Z,1494521353.163 [SBIT](DEBUG): Construct Startup Built In Test.
2017-05-11T16:49:13.175Z,1494521353.175 [SBIT] Loaded
2017-05-11T16:49:13.175Z,1494521353.175 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-05-11T16:49:13.176Z,1494521353.176 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-05-11T16:49:13.205Z,1494521353.204 [IBIT] Loaded
2017-05-11T16:49:13.205Z,1494521353.205 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-05-11T16:49:13.208Z,1494521353.208 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-05-11T16:49:13.358Z,1494521353.358 [CBIT] Loaded
2017-05-11T16:49:13.358Z,1494521353.358 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-05-11T16:49:13.358Z,1494521353.358 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-05-11T16:49:13.360Z,1494521353.360 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-05-11T16:49:13.401Z,1494521353.401 [DepthRateCalculator] Loaded
2017-05-11T16:49:13.402Z,1494521353.402 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-05-11T16:49:13.407Z,1494521353.408 [PitchRateCalculator] Loaded
2017-05-11T16:49:13.408Z,1494521353.408 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-05-11T16:49:13.419Z,1494521353.419 [SpeedCalculator] Loaded
2017-05-11T16:49:13.419Z,1494521353.419 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-05-11T16:49:13.441Z,1494521353.441 [TempGradientCalculator] Loaded
2017-05-11T16:49:13.442Z,1494521353.442 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-05-11T16:49:13.458Z,1494521353.458 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-05-11T16:49:13.458Z,1494521353.458 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-05-11T16:49:13.463Z,1494521353.463 [YawRateCalculator] Loaded
2017-05-11T16:49:13.463Z,1494521353.463 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-05-11T16:49:13.464Z,1494521353.464 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-05-11T16:49:13.465Z,1494521353.464 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-05-11T16:49:13.590Z,1494521353.590 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-05-11T16:49:13.590Z,1494521353.590 [StratificationFrontDetector](DEBUG): (re)initializing
2017-05-11T16:49:13.591Z,1494521353.591 [StratificationFrontDetector] Loaded
2017-05-11T16:49:13.591Z,1494521353.591 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-05-11T16:49:13.615Z,1494521353.615 [DepAvgTempFrontDetector] Loaded
2017-05-11T16:49:13.615Z,1494521353.615 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread.
2017-05-11T16:49:13.615Z,1494521353.615 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-05-11T16:49:13.616Z,1494521353.616 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-05-11T16:49:13.902Z,1494521353.902 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-05-11T16:49:13.902Z,1494521353.902 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-05-11T16:49:14.030Z,1494521354.030 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-05-11T16:49:14.030Z,1494521354.030 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-05-11T16:49:14.086Z,1494521354.086 [DeadReckonUsingSpeedCalculator] Loaded
2017-05-11T16:49:14.086Z,1494521354.086 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-05-11T16:49:14.103Z,1494521354.103 [NavChart] Loaded
2017-05-11T16:49:14.103Z,1494521354.103 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-05-11T16:49:14.107Z,1494521354.107 [UniversalFixResidualReporter] Loaded
2017-05-11T16:49:14.108Z,1494521354.108 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-05-11T16:49:14.108Z,1494521354.108 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-05-11T16:49:14.109Z,1494521354.109 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-05-11T16:49:14.118Z,1494521354.118 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-05-11T16:49:14.119Z,1494521354.119 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-05-11T16:49:14.315Z,1494521354.315 [Aanderaa_O2] Loaded
2017-05-11T16:49:14.315Z,1494521354.315 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-05-11T16:49:14.397Z,1494521354.397 [CTD_NeilBrown] Loaded
2017-05-11T16:49:14.397Z,1494521354.397 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-05-11T16:49:14.399Z,1494521354.399 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407734E0
2017-05-11T16:49:14.399Z,1494521354.399 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 855
2017-05-11T16:49:14.729Z,1494521354.729 [CTD_Seabird] Loaded
2017-05-11T16:49:14.729Z,1494521354.729 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-05-11T16:49:14.730Z,1494521354.730 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407A34E0
2017-05-11T16:49:14.731Z,1494521354.731 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 856
2017-05-11T16:49:14.746Z,1494521354.746 [PAR_Licor] Loaded
2017-05-11T16:49:14.746Z,1494521354.746 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-05-11T16:49:14.797Z,1494521354.796 [WetLabsBB2FL] Loaded
2017-05-11T16:49:14.797Z,1494521354.797 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-05-11T16:49:14.798Z,1494521354.798 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407D34E0
2017-05-11T16:49:14.798Z,1494521354.798 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 857
2017-05-11T16:49:14.799Z,1494521354.799 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-05-11T16:49:14.799Z,1494521354.799 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-05-11T16:49:15.421Z,1494521355.421 [DataOverHttps] Loaded
2017-05-11T16:49:15.421Z,1494521355.421 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-05-11T16:49:15.435Z,1494521355.435 [Depth_Keller] Loaded
2017-05-11T16:49:15.435Z,1494521355.435 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-05-11T16:49:15.440Z,1494521355.441 [DropWeight] Loaded
2017-05-11T16:49:15.441Z,1494521355.441 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-05-11T16:49:15.533Z,1494521355.533 [NAL9602] Loaded
2017-05-11T16:49:15.533Z,1494521355.533 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-05-11T16:49:15.543Z,1494521355.543 [Onboard] Loaded
2017-05-11T16:49:15.543Z,1494521355.543 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-05-11T16:49:15.550Z,1494521355.550 [Radio_Surface] Loaded
2017-05-11T16:49:15.550Z,1494521355.550 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-05-11T16:49:15.552Z,1494521355.552 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0
2017-05-11T16:49:15.552Z,1494521355.552 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 858
2017-05-11T16:49:15.746Z,1494521355.746 [PNI_TCM] Loaded
2017-05-11T16:49:15.747Z,1494521355.747 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-05-11T16:49:15.810Z,1494521355.810 [Rowe_600LCM] Loaded
2017-05-11T16:49:15.810Z,1494521355.810 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-05-11T16:49:15.811Z,1494521355.811 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0
2017-05-11T16:49:15.811Z,1494521355.811 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 859
2017-05-11T16:49:17.771Z,1494521357.771 [BPC1] Loaded
2017-05-11T16:49:17.771Z,1494521357.772 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-05-11T16:49:17.772Z,1494521357.772 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-05-11T16:49:17.773Z,1494521357.773 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-05-11T16:49:17.898Z,1494521357.898 [BuoyancyServo] Loaded
2017-05-11T16:49:17.899Z,1494521357.899 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-05-11T16:49:17.911Z,1494521357.911 [ElevatorServo] Loaded
2017-05-11T16:49:17.911Z,1494521357.911 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-05-11T16:49:17.923Z,1494521357.923 [MassServo] Loaded
2017-05-11T16:49:17.923Z,1494521357.923 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-05-11T16:49:17.935Z,1494521357.935 [RudderServo] Loaded
2017-05-11T16:49:17.935Z,1494521357.935 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-05-11T16:49:17.947Z,1494521357.947 [ThrusterServo] Loaded
2017-05-11T16:49:17.947Z,1494521357.947 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-05-11T16:49:17.947Z,1494521357.947 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-05-11T16:49:17.948Z,1494521357.948 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-05-11T16:49:18.082Z,1494521358.082 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-05-11T16:49:18.083Z,1494521358.083 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-05-11T16:49:18.124Z,1494521358.124 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-05-11T16:49:18.127Z,1494521358.128 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-05-11T16:49:18.128Z,1494521358.128 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-05-11T16:49:18.135Z,1494521358.135 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-05-11T16:49:18.136Z,1494521358.136 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0
2017-05-11T16:49:18.137Z,1494521358.137 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 860
2017-05-11T16:49:18.141Z,1494521358.141 [Supervisor](INFO): Main Thread ID is 765
2017-05-11T16:49:18.142Z,1494521358.142 [Supervisor](DEBUG): Running supervisor.
2017-05-11T16:49:18.142Z,1494521358.142 [CommandLine ThreadHandler](INFO): Handler Thread ID is 861
2017-05-11T16:49:18.145Z,1494521358.145 [controlThread ThreadHandler](INFO): Handler Thread ID is 862
2017-05-11T16:49:18.146Z,1494521358.146 [controlThread](DEBUG): Initializing ControlThread
2017-05-11T16:49:18.146Z,1494521358.146 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-05-11T16:49:18.148Z,1494521358.148 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-05-11T16:49:18.149Z,1494521358.149 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-05-11T16:49:18.150Z,1494521358.150 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-05-11T16:49:18.150Z,1494521358.150 [SBIT](INFO): Initialize SBIT Component.
2017-05-11T16:49:18.151Z,1494521358.151 [SBIT](IMPORTANT): git: 2017-04-19-5-g443ab08
2017-05-11T16:49:18.151Z,1494521358.151 [SBIT](INFO): git hash: 443ab0852958b11c27b2467e632b948d2f0b0b23
2017-05-11T16:49:18.153Z,1494521358.152 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-05-11T16:49:18.153Z,1494521358.153 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-05-11T16:49:18.154Z,1494521358.154 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2017-05-11T16:49:18.154Z,1494521358.154 [IBIT](INFO): Initialize IBIT Component.
2017-05-11T16:49:18.155Z,1494521358.155 [CBIT](DEBUG): Initialize CBIT Component.
2017-05-11T16:49:18.155Z,1494521358.155 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-05-11T16:49:18.156Z,1494521358.156 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-05-11T16:49:18.157Z,1494521358.157 [logger ThreadHandler](INFO): Handler Thread ID is 863
2017-05-11T16:49:18.184Z,1494521358.184 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 864
2017-05-11T16:49:18.185Z,1494521358.185 [CTD_NeilBrown](INFO): Powering down
2017-05-11T16:49:18.219Z,1494521358.219 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 865
2017-05-11T16:49:18.220Z,1494521358.220 [CTD_Seabird](INFO): Initializing
2017-05-11T16:49:18.252Z,1494521358.252 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-05-11T16:49:18.252Z,1494521358.252 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-05-11T16:49:18.253Z,1494521358.253 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-05-11T16:49:18.253Z,1494521358.253 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-05-11T16:49:18.254Z,1494521358.254 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-05-11T16:49:18.255Z,1494521358.255 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-05-11T16:49:18.255Z,1494521358.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-11T16:49:18.257Z,1494521358.257 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:49:18.257Z,1494521358.257 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:49:18.257Z,1494521358.257 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-11T16:49:18.258Z,1494521358.258 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:49:18.258Z,1494521358.258 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:49:18.259Z,1494521358.259 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-05-11T16:49:18.259Z,1494521358.259 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-05-11T16:49:18.273Z,1494521358.273 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-05-11T16:49:18.285Z,1494521358.285 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 867
2017-05-11T16:49:18.288Z,1494521358.288 [WetLabsBB2FL](INFO): Powering down
2017-05-11T16:49:18.301Z,1494521358.301 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 868
2017-05-11T16:49:18.312Z,1494521358.312 [Radio_Surface](INFO): Powering up
2017-05-11T16:49:18.328Z,1494521358.328 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 869
2017-05-11T16:49:18.329Z,1494521358.329 [Rowe_600LCM](INFO): Initializing
2017-05-11T16:49:18.330Z,1494521358.330 [Rowe_600LCM](INFO): Checking LCM
2017-05-11T16:49:18.330Z,1494521358.330 [Rowe_600LCM](INFO): LCM OK
2017-05-11T16:49:18.330Z,1494521358.330 [Rowe_600LCM](INFO): Powering up
2017-05-11T16:49:18.346Z,1494521358.346 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-05-11T16:49:18.349Z,1494521358.349 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 870
2017-05-11T16:49:18.364Z,1494521358.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-05-11T16:49:18.364Z,1494521358.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-05-11T16:49:18.365Z,1494521358.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-05-11T16:49:18.365Z,1494521358.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-05-11T16:49:18.365Z,1494521358.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-05-11T16:49:18.365Z,1494521358.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-05-11T16:49:18.366Z,1494521358.366 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-05-11T16:49:18.366Z,1494521358.366 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-05-11T16:49:18.366Z,1494521358.366 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-05-11T16:49:18.366Z,1494521358.366 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-05-11T16:49:18.366Z,1494521358.366 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-05-11T16:49:18.366Z,1494521358.366 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-05-11T16:49:18.367Z,1494521358.367 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-05-11T16:49:18.367Z,1494521358.367 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-05-11T16:49:18.367Z,1494521358.367 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-05-11T16:49:18.367Z,1494521358.367 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-05-11T16:49:18.404Z,1494521358.404 [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>


2017-05-11T16:49:18.405Z,1494521358.405 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-05-11T16:49:18.477Z,1494521358.477 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-05-11T16:49:18.479Z,1494521358.479 [Default:A.Wait](DEBUG): Construct Wait.
2017-05-11T16:49:18.505Z,1494521358.505 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-05-11T16:49:18.535Z,1494521358.535 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-05-11T16:49:18.538Z,1494521358.537 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-05-11T16:49:18.557Z,1494521358.557 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-05-11T16:49:18.557Z,1494521358.557 [CTD_Seabird](INFO): Powering down
2017-05-11T16:49:18.595Z,1494521358.595 [Default:E.Execute](DEBUG): Construct Execute.
2017-05-11T16:49:18.599Z,1494521358.599 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

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

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

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

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

    </Aggregate>

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

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

</Mission>


2017-05-11T16:49:18.609Z,1494521358.609 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-05-11T16:49:18.780Z,1494521358.780 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-05-11T16:49:19.256Z,1494521359.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:19.408Z,1494521359.408 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:19.525Z,1494521359.525 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:19.532Z,1494521359.532 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-05-11T16:49:19.554Z,1494521359.554 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:19.560Z,1494521359.560 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-05-11T16:49:19.574Z,1494521359.574 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:19.580Z,1494521359.580 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:49:19.586Z,1494521359.586 [RudderServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:19.592Z,1494521359.592 [RudderServo](DEBUG): Initializing RudderServo.
2017-05-11T16:49:19.601Z,1494521359.601 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:19.608Z,1494521359.608 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-05-11T16:49:19.760Z,1494521359.760 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2017-05-11T16:49:19.760Z,1494521359.760 [DropWeight] Hardware Fault, FailCount= 1
2017-05-11T16:49:19.760Z,1494521359.760 [DropWeight](ERROR): Hardware Fault
2017-05-11T16:49:19.864Z,1494521359.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:19.865Z,1494521359.865 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:19.876Z,1494521359.876 [CommandLine](FAULT): Scheduling is paused
2017-05-11T16:49:19.876Z,1494521359.876 [CBIT](INFO): Critical error at 20170511T164919
2017-05-11T16:49:19.876Z,1494521359.876 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-05-11T16:49:19.879Z,1494521359.879 [CBIT](ERROR): Hardware Fault in component: DropWeight
2017-05-11T16:49:19.879Z,1494521359.879 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2017-05-11T16:49:20.144Z,1494521360.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:20.145Z,1494521360.145 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:20.488Z,1494521360.488 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-05-11T16:49:20.488Z,1494521360.488 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-05-11T16:49:20.488Z,1494521360.488 [BuoyancyServo] Communications Fault, FailCount= 1
2017-05-11T16:49:20.488Z,1494521360.488 [BuoyancyServo](ERROR): Communications Fault
2017-05-11T16:49:20.697Z,1494521360.697 [CBIT](INFO): Critical error at 20170511T164919
2017-05-11T16:49:20.700Z,1494521360.700 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-05-11T16:49:20.733Z,1494521360.733 [NAL9602](INFO): Powering up NAL9602
2017-05-11T16:49:20.780Z,1494521360.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:20.781Z,1494521360.781 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:20.794Z,1494521360.793 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-05-11T16:49:20.795Z,1494521360.795 [BuoyancyServo](INFO): Powering down
2017-05-11T16:49:21.172Z,1494521361.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:21.173Z,1494521361.173 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:21.552Z,1494521361.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:21.553Z,1494521361.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:22.028Z,1494521362.028 [Aanderaa_O2](INFO): Powering down
2017-05-11T16:49:22.144Z,1494521362.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:22.145Z,1494521362.145 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:22.401Z,1494521362.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:22.403Z,1494521362.403 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-11T16:49:22.622Z,1494521362.622 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-11T16:49:22.740Z,1494521362.740 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-05-11T16:49:22.938Z,1494521362.938 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-05-11T16:49:22.938Z,1494521362.938 [BuoyancyServo] No Fault, FailCount= 1
2017-05-11T16:49:23.305Z,1494521363.305 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:23.306Z,1494521363.306 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-05-11T16:49:23.746Z,1494521363.746 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-11T16:49:23.747Z,1494521363.747 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-11T16:49:23.748Z,1494521363.748 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-11T16:49:24.140Z,1494521364.140 [MassServo](FAULT): Overload Error
2017-05-11T16:49:24.140Z,1494521364.140 [MassServo] Hardware Fault, FailCount= 1
2017-05-11T16:49:24.140Z,1494521364.140 [MassServo](ERROR): Hardware Fault
2017-05-11T16:49:24.145Z,1494521364.145 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:49:24.465Z,1494521364.465 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:49:24.465Z,1494521364.466 [MassServo](INFO): Powering down
2017-05-11T16:49:25.213Z,1494521365.213 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:25.213Z,1494521365.213 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:49:25.226Z,1494521365.226 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:49:25.226Z,1494521365.226 [MassServo] No Fault, FailCount= 1
2017-05-11T16:49:29.276Z,1494521369.276 [MassServo](FAULT): Overload Error
2017-05-11T16:49:29.276Z,1494521369.276 [MassServo] Hardware Fault, FailCount= 2
2017-05-11T16:49:29.276Z,1494521369.276 [MassServo](ERROR): Hardware Fault
2017-05-11T16:49:29.353Z,1494521369.353 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:49:29.626Z,1494521369.626 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:49:29.627Z,1494521369.626 [MassServo](INFO): Powering down
2017-05-11T16:49:30.384Z,1494521370.385 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:30.385Z,1494521370.385 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:49:30.398Z,1494521370.398 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:49:30.398Z,1494521370.398 [MassServo] No Fault, FailCount= 2
2017-05-11T16:49:31.917Z,1494521371.917 [NAL9602](INFO): NAL9602 initialized
2017-05-11T16:49:34.849Z,1494521374.849 [MassServo](FAULT): Overload Error
2017-05-11T16:49:34.849Z,1494521374.849 [MassServo] Hardware Fault, FailCount= 3
2017-05-11T16:49:34.849Z,1494521374.849 [MassServo](ERROR): Hardware Fault
2017-05-11T16:49:34.854Z,1494521374.854 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:49:35.212Z,1494521375.212 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:49:35.212Z,1494521375.212 [MassServo](INFO): Powering down
2017-05-11T16:49:36.020Z,1494521376.020 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:36.021Z,1494521376.021 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:49:36.034Z,1494521376.034 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:49:36.034Z,1494521376.034 [MassServo] No Fault, FailCount= 3
2017-05-11T16:49:40.136Z,1494521380.136 [MassServo](FAULT): Overload Error
2017-05-11T16:49:40.136Z,1494521380.136 [MassServo] Hardware Fault, FailCount= 4
2017-05-11T16:49:40.136Z,1494521380.136 [MassServo](ERROR): Hardware Fault
2017-05-11T16:49:40.213Z,1494521380.213 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:49:40.445Z,1494521380.445 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:49:40.446Z,1494521380.446 [MassServo](INFO): Powering down
2017-05-11T16:49:41.235Z,1494521381.235 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:41.235Z,1494521381.235 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:49:41.257Z,1494521381.257 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:49:41.257Z,1494521381.257 [MassServo] No Fault, FailCount= 4
2017-05-11T16:49:45.244Z,1494521385.244 [MassServo](FAULT): Overload Error
2017-05-11T16:49:45.244Z,1494521385.244 [MassServo] Hardware Fault, FailCount= 5
2017-05-11T16:49:45.244Z,1494521385.244 [MassServo](ERROR): Hardware Fault
2017-05-11T16:49:45.249Z,1494521385.249 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:49:45.249Z,1494521385.249 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-11T16:49:45.630Z,1494521385.630 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:49:45.630Z,1494521385.630 [MassServo](INFO): Powering down
2017-05-11T16:49:45.633Z,1494521385.634 [CBIT](INFO): Critical error at 20170511T164945
2017-05-11T16:49:46.434Z,1494521386.434 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:49:46.434Z,1494521386.434 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:49:50.803Z,1494521390.803 [SBIT](IMPORTANT): Beginning Startup BIT
2017-05-11T16:49:50.824Z,1494521390.824 [CBIT](IMPORTANT): Beginning ground fault scan
2017-05-11T16:49:51.157Z,1494521391.157 [PNI_TCM](ERROR): CRC does not match. Expected:0x41380 got:0x17927
2017-05-11T16:49:57.545Z,1494521397.545 [PNI_TCM](ERROR): CRC does not match. Expected:0x50370 got:0x16702
2017-05-11T16:50:01.241Z,1494521401.241 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.013687
CHAN A1 (24V): 4.792929
CHAN A2 (12V): -0.067998
CHAN A3 (5V): -0.001245
CHAN B0 (3.3V): -0.000725
CHAN B1 (3.15aV): -0.001552
CHAN B2 (3.15bV): -0.001490
CHAN B3 (GND): 0.000108
OPEN: -0.000719
Full Scale Calc: 4.765 mA, -1.589 mA
2017-05-11T16:50:03.993Z,1494521403.993 [SBIT](ERROR): Could not read massPosReader_.
2017-05-11T16:50:24.684Z,1494521424.684 [CommandLine](IMPORTANT): got command failComponent
2017-05-11T16:50:24.685Z,1494521424.685 [CommandLine](IMPORTANT): Failed components:
2017-05-11T16:50:24.685Z,1494521424.685 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2017-05-11T16:50:24.686Z,1494521424.686 [CommandLine](IMPORTANT): MassServo: Hardware Fault
2017-05-11T16:50:30.800Z,1494521430.800 [SBIT](ERROR): Could not read massPosReader_.
2017-05-11T16:50:30.800Z,1494521430.800 [SBIT](FAULT): Control surface position failure.
2017-05-11T16:50:44.384Z,1494521444.384 [SBIT](ERROR): Could not read massPosReader_.
2017-05-11T16:50:44.384Z,1494521444.384 [SBIT](FAULT): Control surface position failure.
2017-05-11T16:50:44.798Z,1494521444.798 [SBIT](CRITICAL): SBIT FAILED
2017-05-11T16:50:44.800Z,1494521444.800 [CBIT](INFO): Critical error at 20170511T165044
2017-05-11T16:50:45.160Z,1494521445.160 [MissionManager](IMPORTANT): Started mission Startup
2017-05-11T16:50:45.161Z,1494521445.161 [Startup] Running Loop=1
2017-05-11T16:50:45.161Z,1494521445.161 [Startup](DEBUG): Aggregate::initialize Startup
2017-05-11T16:50:45.161Z,1494521445.161 [Startup:A.GoToSurface] Running Loop=1
2017-05-11T16:50:45.161Z,1494521445.161 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-05-11T16:50:45.162Z,1494521445.162 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-05-11T16:50:45.162Z,1494521445.162 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-05-11T16:50:45.162Z,1494521445.162 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-05-11T16:50:45.163Z,1494521445.163 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-05-11T16:50:45.163Z,1494521445.163 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-05-11T16:50:45.169Z,1494521445.169 [Startup:StartupSatComms] Running Loop=1
2017-05-11T16:50:45.169Z,1494521445.169 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-05-11T16:50:45.169Z,1494521445.169 [Startup:StartupSatComms:A] Running Loop=1
2017-05-11T16:50:45.600Z,1494521445.601 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-05-11T16:51:01.494Z,1494521461.494 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004700
2017-05-11T16:51:18.655Z,1494521478.655 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-11T16:51:18.656Z,1494521478.656 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-05-11T16:51:18.656Z,1494521478.656 [Rowe_600LCM](ERROR): Communications Fault
2017-05-11T16:51:18.757Z,1494521478.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-11T16:51:18.770Z,1494521478.770 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-11T16:51:19.060Z,1494521479.060 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-11T16:51:19.203Z,1494521479.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2017-05-11T16:51:19.295Z,1494521479.295 [Rowe_600LCM](INFO): Powering down
2017-05-11T16:51:19.536Z,1494521479.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-11T16:51:19.942Z,1494521479.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-11T16:51:20.338Z,1494521480.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-11T16:51:20.740Z,1494521480.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-11T16:51:20.770Z,1494521480.770 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-11T16:51:20.770Z,1494521480.770 [Rowe_600LCM] No Fault, FailCount= 1
2017-05-11T16:51:20.912Z,1494521480.912 [Rowe_600LCM](INFO): Initializing
2017-05-11T16:51:20.912Z,1494521480.912 [Rowe_600LCM](INFO): Checking LCM
2017-05-11T16:51:20.912Z,1494521480.912 [Rowe_600LCM](INFO): LCM OK
2017-05-11T16:51:20.913Z,1494521480.912 [Rowe_600LCM](INFO): Powering up
2017-05-11T16:51:21.145Z,1494521481.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-11T16:51:21.545Z,1494521481.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-11T16:51:21.950Z,1494521481.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-11T16:51:22.351Z,1494521482.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-11T16:51:22.737Z,1494521482.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-11T16:51:23.141Z,1494521483.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-11T16:51:23.558Z,1494521483.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-11T16:51:23.943Z,1494521483.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-11T16:51:24.345Z,1494521484.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-11T16:51:24.741Z,1494521484.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-11T16:51:25.042Z,1494521485.042 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-11T16:51:25.162Z,1494521485.162 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-05-11T16:51:25.172Z,1494521485.172 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-11T16:51:25.173Z,1494521485.173 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-11T16:51:25.174Z,1494521485.174 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-11T16:51:25.231Z,1494521485.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-05-11T16:51:25.555Z,1494521485.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-05-11T16:51:45.176Z,1494521505.176 [Startup:StartupSatComms:A](INFO): Timed out from 2017-05-11T16:50:45.2Z
2017-05-11T16:51:45.176Z,1494521505.176 [Startup:StartupSatComms:A] Stopped
2017-05-11T16:51:45.176Z,1494521505.176 [Startup:StartupSatComms:B] Running Loop=1
2017-05-11T16:51:45.537Z,1494521505.537 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-05-11T16:51:49.992Z,1494521509.992 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T194407/Courier0012.lzma
2017-05-11T16:51:50.766Z,1494521510.766 [DataOverHttps](INFO): Moved sent file to Logs/20170505T194407/Courier0012.lzma.bak
2017-05-11T16:51:50.766Z,1494521510.766 [DataOverHttps](INFO): SBD MOMSN=5021767
2017-05-11T16:52:00.238Z,1494521520.238 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20170508T184538/Courier0000.lzma
2017-05-11T16:52:01.006Z,1494521521.006 [DataOverHttps](INFO): Moved sent file to Logs/20170508T184538/Courier0000.lzma.bak
2017-05-11T16:52:01.006Z,1494521521.006 [DataOverHttps](INFO): SBD MOMSN=5021769
2017-05-11T16:52:01.068Z,1494521521.068 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-11T16:52:01.068Z,1494521521.068 [BPC1] Data Fault, FailCount= 1
2017-05-11T16:52:01.068Z,1494521521.068 [BPC1](ERROR): Data Fault
2017-05-11T16:52:01.119Z,1494521521.119 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-11T16:52:05.356Z,1494521525.356 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:52:05.356Z,1494521525.356 [BPC1] No Fault, FailCount= 1
2017-05-11T16:52:10.566Z,1494521530.566 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20170508T184924/Courier0000.lzma
2017-05-11T16:52:11.321Z,1494521531.321 [DataOverHttps](INFO): Moved sent file to Logs/20170508T184924/Courier0000.lzma.bak
2017-05-11T16:52:11.322Z,1494521531.322 [DataOverHttps](INFO): SBD MOMSN=5021774
2017-05-11T16:52:16.927Z,1494521536.927 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-11T16:52:16.927Z,1494521536.927 [BPC1] Data Fault, FailCount= 2
2017-05-11T16:52:16.927Z,1494521536.927 [BPC1](ERROR): Data Fault
2017-05-11T16:52:16.955Z,1494521536.955 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-11T16:52:17.332Z,1494521537.332 [PNI_TCM](ERROR): CRC does not match. Expected:0x54731 got:0x40832
2017-05-11T16:52:20.710Z,1494521540.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T16:52:20.710Z,1494521540.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-05-11T16:52:20.710Z,1494521540.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-11T16:52:20.712Z,1494521540.712 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T16:52:20.712Z,1494521540.712 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-05-11T16:52:20.712Z,1494521540.712 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-11T16:52:20.763Z,1494521540.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-11T16:52:20.763Z,1494521540.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-11T16:52:20.802Z,1494521540.802 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170511T162838/Courier0000.lzma
2017-05-11T16:52:20.878Z,1494521540.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-11T16:52:20.878Z,1494521540.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-05-11T16:52:20.879Z,1494521540.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-11T16:52:20.879Z,1494521540.879 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-05-11T16:52:20.880Z,1494521540.880 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:52:20.880Z,1494521540.880 [BPC1] No Fault, FailCount= 2
2017-05-11T16:52:21.312Z,1494521541.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-11T16:52:21.313Z,1494521541.313 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:52:21.314Z,1494521541.314 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:52:21.314Z,1494521541.314 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-11T16:52:21.315Z,1494521541.315 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:52:21.315Z,1494521541.315 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:52:21.586Z,1494521541.586 [DataOverHttps](INFO): Moved sent file to Logs/20170511T162838/Courier0000.lzma.bak
2017-05-11T16:52:21.586Z,1494521541.586 [DataOverHttps](INFO): SBD MOMSN=5021780
2017-05-11T16:52:35.109Z,1494521555.109 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20170511T164856/Courier0000.lzma
2017-05-11T16:52:35.838Z,1494521555.838 [DataOverHttps](INFO): Moved sent file to Logs/20170511T164856/Courier0000.lzma.bak
2017-05-11T16:52:35.838Z,1494521555.838 [DataOverHttps](INFO): SBD MOMSN=5021783
2017-05-11T16:52:45.237Z,1494521565.237 [Startup:StartupSatComms:B](INFO): Timed out from 2017-05-11T16:51:45.2Z
2017-05-11T16:52:45.237Z,1494521565.237 [Startup:StartupSatComms:B] Stopped
2017-05-11T16:52:45.237Z,1494521565.237 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-05-11T16:52:45.237Z,1494521565.237 [Startup:StartupSatComms] Stopped
2017-05-11T16:52:45.237Z,1494521565.237 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-05-11T16:52:45.238Z,1494521565.238 [Startup](INFO): Completed Startup
2017-05-11T16:52:45.239Z,1494521565.239 [MissionManager](INFO): Startup is completed.
2017-05-11T16:52:45.239Z,1494521565.239 [MissionManager](INFO): Uninitializing Mission Startup
2017-05-11T16:52:45.239Z,1494521565.239 [Startup] Stopped
2017-05-11T16:52:45.239Z,1494521565.239 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-05-11T16:52:45.239Z,1494521565.239 [Startup:A.GoToSurface] Stopped
2017-05-11T16:52:45.239Z,1494521565.239 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-05-11T16:52:45.591Z,1494521565.591 [MissionManager](IMPORTANT): Started mission Default
2017-05-11T16:52:45.596Z,1494521565.596 [Default] Running Loop=1
2017-05-11T16:52:45.596Z,1494521565.596 [Default](DEBUG): Aggregate::initialize Default
2017-05-11T16:52:45.596Z,1494521565.596 [Default:B.GoToSurface] Running Loop=1
2017-05-11T16:52:45.596Z,1494521565.596 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-05-11T16:52:45.596Z,1494521565.596 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-05-11T16:52:45.597Z,1494521565.597 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-05-11T16:52:45.597Z,1494521565.597 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-05-11T16:52:45.597Z,1494521565.597 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-05-11T16:52:45.598Z,1494521565.598 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-05-11T16:52:45.598Z,1494521565.598 [Default:A.Wait] Running Loop=1
2017-05-11T16:52:45.598Z,1494521565.598 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-05-11T16:52:49.043Z,1494521569.043 [DataOverHttps](INFO): Sending 769 bytes from file Logs/20170505T135544/Express0001.lzma
2017-05-11T16:52:49.794Z,1494521569.794 [DataOverHttps](INFO): Moved sent file to Logs/20170505T135544/Express0001.lzma.bak
2017-05-11T16:52:49.794Z,1494521569.794 [DataOverHttps](INFO): SBD MOMSN=5005362
2017-05-11T16:52:58.663Z,1494521578.663 [Default:A.Wait](INFO): Done Waiting.
2017-05-11T16:52:58.664Z,1494521578.664 [Default:A.Wait] Stopped
2017-05-11T16:52:58.664Z,1494521578.664 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-05-11T16:52:59.069Z,1494521579.069 [Default:CheckIn] Running Loop=1
2017-05-11T16:52:59.069Z,1494521579.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-05-11T16:52:59.069Z,1494521579.069 [Default:CheckIn:Read_GPS] Running Loop=1
2017-05-11T16:52:59.472Z,1494521579.473 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-05-11T16:53:21.258Z,1494521601.258 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-11T16:53:21.258Z,1494521601.258 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-05-11T16:53:21.258Z,1494521601.258 [Rowe_600LCM](ERROR): Communications Fault
2017-05-11T16:53:21.462Z,1494521601.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-11T16:53:21.488Z,1494521601.488 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-11T16:53:21.660Z,1494521601.660 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-11T16:53:21.866Z,1494521601.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-11T16:53:21.900Z,1494521601.900 [Rowe_600LCM](INFO): Powering down
2017-05-11T16:53:22.266Z,1494521602.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-11T16:53:22.649Z,1494521602.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-11T16:53:23.054Z,1494521603.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-11T16:53:23.503Z,1494521603.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2017-05-11T16:53:23.525Z,1494521603.525 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-11T16:53:23.525Z,1494521603.525 [Rowe_600LCM] No Fault, FailCount= 2
2017-05-11T16:53:23.887Z,1494521603.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-11T16:53:23.928Z,1494521603.928 [Rowe_600LCM](INFO): Initializing
2017-05-11T16:53:23.928Z,1494521603.928 [Rowe_600LCM](INFO): Checking LCM
2017-05-11T16:53:23.928Z,1494521603.928 [Rowe_600LCM](INFO): LCM OK
2017-05-11T16:53:23.928Z,1494521603.928 [Rowe_600LCM](INFO): Powering up
2017-05-11T16:53:24.256Z,1494521604.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-11T16:53:24.650Z,1494521604.650 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-11T16:53:25.098Z,1494521605.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2017-05-11T16:53:25.466Z,1494521605.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-11T16:53:25.890Z,1494521605.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-11T16:53:26.306Z,1494521606.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2017-05-11T16:53:26.658Z,1494521606.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-11T16:53:27.043Z,1494521607.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-11T16:53:27.446Z,1494521607.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-11T16:53:27.848Z,1494521607.848 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-11T16:53:28.059Z,1494521608.059 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-11T16:53:28.126Z,1494521608.126 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-05-11T16:53:28.128Z,1494521608.128 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-11T16:53:28.130Z,1494521608.130 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-11T16:53:28.131Z,1494521608.131 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-11T16:53:28.296Z,1494521608.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-05-11T16:54:35.417Z,1494521675.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-05-11T16:54:45.491Z,1494521685.491 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:54:45.496Z,1494521685.496 [MassServo] No Fault, FailCount= 5
2017-05-11T16:54:51.052Z,1494521691.052 [MassServo](FAULT): Overload Error
2017-05-11T16:54:51.052Z,1494521691.052 [MassServo] Hardware Fault, FailCount= 1
2017-05-11T16:54:51.052Z,1494521691.052 [MassServo](ERROR): Hardware Fault
2017-05-11T16:54:51.140Z,1494521691.141 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:54:51.450Z,1494521691.450 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:54:51.450Z,1494521691.450 [MassServo](INFO): Powering down
2017-05-11T16:54:52.275Z,1494521692.275 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:54:52.276Z,1494521692.276 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:54:52.281Z,1494521692.281 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:54:52.281Z,1494521692.281 [MassServo] No Fault, FailCount= 1
2017-05-11T16:54:56.632Z,1494521696.632 [MassServo](FAULT): Overload Error
2017-05-11T16:54:56.632Z,1494521696.632 [MassServo] Hardware Fault, FailCount= 2
2017-05-11T16:54:56.632Z,1494521696.632 [MassServo](ERROR): Hardware Fault
2017-05-11T16:54:56.637Z,1494521696.637 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:54:57.016Z,1494521697.016 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:54:57.016Z,1494521697.016 [MassServo](INFO): Powering down
2017-05-11T16:54:57.814Z,1494521697.814 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:54:57.814Z,1494521697.814 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:54:57.820Z,1494521697.820 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:54:57.820Z,1494521697.820 [MassServo] No Fault, FailCount= 2
2017-05-11T16:55:01.824Z,1494521701.824 [MassServo](FAULT): Overload Error
2017-05-11T16:55:01.824Z,1494521701.824 [MassServo] Hardware Fault, FailCount= 3
2017-05-11T16:55:01.824Z,1494521701.824 [MassServo](ERROR): Hardware Fault
2017-05-11T16:55:01.905Z,1494521701.905 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:55:02.226Z,1494521702.226 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:55:02.226Z,1494521702.226 [MassServo](INFO): Powering down
2017-05-11T16:55:03.063Z,1494521703.063 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:55:03.063Z,1494521703.063 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:55:03.072Z,1494521703.072 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:55:03.072Z,1494521703.072 [MassServo] No Fault, FailCount= 3
2017-05-11T16:55:07.456Z,1494521707.456 [MassServo](FAULT): Overload Error
2017-05-11T16:55:07.456Z,1494521707.456 [MassServo] Hardware Fault, FailCount= 4
2017-05-11T16:55:07.456Z,1494521707.456 [MassServo](ERROR): Hardware Fault
2017-05-11T16:55:07.461Z,1494521707.461 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:55:07.816Z,1494521707.816 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:55:07.817Z,1494521707.816 [MassServo](INFO): Powering down
2017-05-11T16:55:08.596Z,1494521708.596 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:55:08.596Z,1494521708.596 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:55:08.613Z,1494521708.613 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T16:55:08.613Z,1494521708.613 [MassServo] No Fault, FailCount= 4
2017-05-11T16:55:12.664Z,1494521712.664 [MassServo](FAULT): Overload Error
2017-05-11T16:55:12.664Z,1494521712.664 [MassServo] Hardware Fault, FailCount= 5
2017-05-11T16:55:12.664Z,1494521712.664 [MassServo](ERROR): Hardware Fault
2017-05-11T16:55:12.757Z,1494521712.757 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T16:55:12.757Z,1494521712.757 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-11T16:55:13.021Z,1494521713.021 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T16:55:13.021Z,1494521713.021 [MassServo](INFO): Powering down
2017-05-11T16:55:13.026Z,1494521713.026 [CBIT](INFO): Critical error at 20170511T165512
2017-05-11T16:55:13.804Z,1494521713.804 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T16:55:13.804Z,1494521713.804 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T16:55:20.573Z,1494521720.573 [BPC1](ERROR): Failed to receive battery data
2017-05-11T16:55:20.574Z,1494521720.574 [BPC1] Communications Fault, FailCount= 1
2017-05-11T16:55:20.574Z,1494521720.574 [BPC1](ERROR): Communications Fault
2017-05-11T16:55:20.625Z,1494521720.625 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-11T16:55:21.410Z,1494521721.410 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T16:55:21.410Z,1494521721.410 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-05-11T16:55:21.410Z,1494521721.410 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-11T16:55:21.413Z,1494521721.413 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T16:55:21.413Z,1494521721.413 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-05-11T16:55:21.413Z,1494521721.413 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-11T16:55:21.442Z,1494521721.442 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-11T16:55:21.442Z,1494521721.442 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-11T16:55:21.825Z,1494521721.825 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-11T16:55:21.825Z,1494521721.825 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-05-11T16:55:21.826Z,1494521721.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-11T16:55:21.826Z,1494521721.826 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-05-11T16:55:21.827Z,1494521721.827 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:55:21.830Z,1494521721.830 [BPC1] No Fault, FailCount= 1
2017-05-11T16:55:22.257Z,1494521722.257 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-11T16:55:22.258Z,1494521722.258 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:55:22.258Z,1494521722.258 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:55:22.259Z,1494521722.259 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-11T16:55:22.259Z,1494521722.259 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:55:22.259Z,1494521722.260 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:55:24.056Z,1494521724.056 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-11T16:55:24.056Z,1494521724.056 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-05-11T16:55:24.056Z,1494521724.056 [Rowe_600LCM](ERROR): Communications Fault
2017-05-11T16:55:24.189Z,1494521724.189 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-11T16:55:24.221Z,1494521724.221 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-11T16:55:24.461Z,1494521724.461 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-11T16:55:24.624Z,1494521724.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-11T16:55:24.672Z,1494521724.672 [Rowe_600LCM](INFO): Powering down
2017-05-11T16:55:24.994Z,1494521724.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-11T16:55:25.394Z,1494521725.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-11T16:55:25.805Z,1494521725.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-11T16:55:26.201Z,1494521726.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-11T16:55:26.607Z,1494521726.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-11T16:55:26.995Z,1494521726.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-11T16:55:27.406Z,1494521727.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-11T16:55:27.845Z,1494521727.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2017-05-11T16:55:28.205Z,1494521728.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-11T16:55:28.611Z,1494521728.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-11T16:55:29.018Z,1494521729.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-11T16:55:29.398Z,1494521729.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-11T16:55:29.837Z,1494521729.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2017-05-11T16:55:30.242Z,1494521730.242 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-05-11T16:55:30.610Z,1494521730.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-11T16:55:31.003Z,1494521731.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-05-11T16:55:31.400Z,1494521731.400 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-05-11T16:55:31.816Z,1494521731.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-05-11T16:55:32.213Z,1494521732.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-05-11T16:55:32.621Z,1494521732.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-05-11T16:55:33.003Z,1494521733.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-05-11T16:55:33.382Z,1494521733.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-05-11T16:55:33.787Z,1494521733.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-05-11T16:55:34.194Z,1494521734.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-05-11T16:55:34.628Z,1494521734.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-05-11T16:55:34.997Z,1494521734.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-05-11T16:55:35.399Z,1494521735.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-05-11T16:55:35.799Z,1494521735.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-05-11T16:55:36.202Z,1494521736.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-05-11T16:55:36.606Z,1494521736.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-05-11T16:55:37.002Z,1494521737.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-05-11T16:55:37.391Z,1494521737.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-05-11T16:55:37.811Z,1494521737.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-05-11T16:55:38.211Z,1494521738.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-05-11T16:55:38.610Z,1494521738.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-05-11T16:55:38.999Z,1494521738.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-05-11T16:55:39.403Z,1494521739.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-05-11T16:55:39.815Z,1494521739.815 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-05-11T16:55:40.213Z,1494521740.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-05-11T16:55:40.667Z,1494521740.667 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-05-11T16:55:40.987Z,1494521740.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-05-11T16:55:41.387Z,1494521741.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-05-11T16:55:41.817Z,1494521741.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-05-11T16:55:42.250Z,1494521742.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-05-11T16:55:42.599Z,1494521742.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-05-11T16:55:42.995Z,1494521742.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-05-11T16:55:43.429Z,1494521743.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-05-11T16:56:51.300Z,1494521811.300 [PNI_TCM](ERROR): CRC does not match. Expected:0x36973 got:0x16698
2017-05-11T16:56:52.910Z,1494521812.911 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-11T16:56:52.911Z,1494521812.911 [BPC1] Data Fault, FailCount= 1
2017-05-11T16:56:52.911Z,1494521812.911 [BPC1](ERROR): Data Fault
2017-05-11T16:56:52.960Z,1494521812.960 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-11T16:56:54.130Z,1494521814.130 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:56:54.130Z,1494521814.130 [BPC1] No Fault, FailCount= 1
2017-05-11T16:56:54.505Z,1494521814.505 [PNI_TCM](ERROR): CRC does not match. Expected:0x7988 got:0x49147
2017-05-11T16:57:53.542Z,1494521873.542 [BPC1](ERROR): Failed to receive battery data
2017-05-11T16:57:53.542Z,1494521873.542 [BPC1] Communications Fault, FailCount= 1
2017-05-11T16:57:53.542Z,1494521873.542 [BPC1](ERROR): Communications Fault
2017-05-11T16:57:53.571Z,1494521873.571 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-11T16:57:54.773Z,1494521874.773 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:57:54.773Z,1494521874.773 [BPC1] No Fault, FailCount= 1
2017-05-11T16:57:59.164Z,1494521879.164 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-05-11T16:52:59.1Z
2017-05-11T16:57:59.164Z,1494521879.164 [Default:CheckIn:Read_GPS] Stopped
2017-05-11T16:57:59.164Z,1494521879.164 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-05-11T16:57:59.541Z,1494521879.541 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-05-11T16:58:16.226Z,1494521896.226 [BPC1](ERROR): Failed to receive battery data
2017-05-11T16:58:16.227Z,1494521896.227 [BPC1] Communications Fault, FailCount= 2
2017-05-11T16:58:16.227Z,1494521896.227 [BPC1](ERROR): Communications Fault
2017-05-11T16:58:16.247Z,1494521896.247 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-11T16:58:20.065Z,1494521900.065 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20170511T164856/Courier0004.lzma
2017-05-11T16:58:20.146Z,1494521900.146 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:58:20.146Z,1494521900.146 [BPC1] No Fault, FailCount= 2
2017-05-11T16:58:20.814Z,1494521900.814 [DataOverHttps](INFO): Moved sent file to Logs/20170511T164856/Courier0004.lzma.bak
2017-05-11T16:58:20.814Z,1494521900.814 [DataOverHttps](INFO): SBD MOMSN=5021791
2017-05-11T16:58:25.131Z,1494521905.131 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T16:58:25.131Z,1494521905.131 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2017-05-11T16:58:25.131Z,1494521905.131 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-11T16:58:25.133Z,1494521905.133 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T16:58:25.133Z,1494521905.133 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2017-05-11T16:58:25.133Z,1494521905.134 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-11T16:58:25.146Z,1494521905.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-11T16:58:25.146Z,1494521905.146 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-11T16:58:25.290Z,1494521905.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-11T16:58:25.290Z,1494521905.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2017-05-11T16:58:25.290Z,1494521905.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-11T16:58:25.290Z,1494521905.290 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2017-05-11T16:58:25.613Z,1494521905.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-11T16:58:25.613Z,1494521905.613 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:58:25.614Z,1494521905.614 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:58:25.614Z,1494521905.614 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-11T16:58:25.614Z,1494521905.614 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T16:58:25.615Z,1494521905.615 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T16:58:26.007Z,1494521906.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2017-05-11T16:58:26.402Z,1494521906.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2017-05-11T16:58:26.818Z,1494521906.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2017-05-11T16:58:30.120Z,1494521910.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s.
2017-05-11T16:58:30.200Z,1494521910.200 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20170505T135544/Express0005.lzma
2017-05-11T16:58:30.281Z,1494521910.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-11T16:58:30.622Z,1494521910.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2017-05-11T16:58:30.987Z,1494521910.987 [DataOverHttps](INFO): Moved sent file to Logs/20170505T135544/Express0005.lzma.bak
2017-05-11T16:58:30.987Z,1494521910.987 [DataOverHttps](INFO): SBD MOMSN=5021794
2017-05-11T16:58:31.050Z,1494521911.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2017-05-11T16:58:31.606Z,1494521911.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-05-11T16:58:31.852Z,1494521911.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2017-05-11T16:58:35.163Z,1494521915.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-05-11T16:58:35.313Z,1494521915.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2017-05-11T16:58:35.645Z,1494521915.645 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-05-11T16:58:36.088Z,1494521916.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2017-05-11T16:58:36.445Z,1494521916.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2017-05-11T16:58:36.909Z,1494521916.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2017-05-11T16:58:40.141Z,1494521920.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2017-05-11T16:58:40.226Z,1494521920.226 [DataOverHttps](INFO): Sending 304 bytes from file Logs/20170505T135544/Express0009.lzma
2017-05-11T16:58:40.312Z,1494521920.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-05-11T16:58:40.617Z,1494521920.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2017-05-11T16:58:41.091Z,1494521921.091 [DataOverHttps](INFO): Moved sent file to Logs/20170505T135544/Express0009.lzma.bak
2017-05-11T16:58:41.091Z,1494521921.091 [DataOverHttps](INFO): SBD MOMSN=5021797
2017-05-11T16:58:41.174Z,1494521921.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-05-11T16:58:41.592Z,1494521921.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-05-11T16:58:41.850Z,1494521921.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2017-05-11T16:58:45.065Z,1494521925.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2017-05-11T16:58:50.145Z,1494521930.145 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T135544/Express0013.lzma
2017-05-11T16:58:50.890Z,1494521930.890 [DataOverHttps](INFO): Moved sent file to Logs/20170505T135544/Express0013.lzma.bak
2017-05-11T16:58:50.890Z,1494521930.890 [DataOverHttps](INFO): SBD MOMSN=5021805
2017-05-11T16:59:01.488Z,1494521941.488 [DataOverHttps](INFO): Sending 672 bytes from file Logs/20170505T141451/Express0001.lzma
2017-05-11T16:59:02.166Z,1494521942.166 [DataOverHttps](INFO): Moved sent file to Logs/20170505T141451/Express0001.lzma.bak
2017-05-11T16:59:02.166Z,1494521942.166 [DataOverHttps](INFO): SBD MOMSN=5021807
2017-05-11T16:59:11.344Z,1494521951.344 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20170505T141451/Express0005.lzma
2017-05-11T16:59:12.106Z,1494521952.106 [DataOverHttps](INFO): Moved sent file to Logs/20170505T141451/Express0005.lzma.bak
2017-05-11T16:59:12.106Z,1494521952.106 [DataOverHttps](INFO): SBD MOMSN=5021819
2017-05-11T16:59:21.249Z,1494521961.249 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T141451/Express0009.lzma
2017-05-11T16:59:22.022Z,1494521962.022 [DataOverHttps](INFO): Moved sent file to Logs/20170505T141451/Express0009.lzma.bak
2017-05-11T16:59:22.022Z,1494521962.022 [DataOverHttps](INFO): SBD MOMSN=5021822
2017-05-11T16:59:31.243Z,1494521971.243 [DataOverHttps](INFO): Sending 758 bytes from file Logs/20170505T142539/Express0001.lzma
2017-05-11T16:59:32.018Z,1494521972.018 [DataOverHttps](INFO): Moved sent file to Logs/20170505T142539/Express0001.lzma.bak
2017-05-11T16:59:32.018Z,1494521972.018 [DataOverHttps](INFO): SBD MOMSN=5021824
2017-05-11T16:59:36.071Z,1494521976.071 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-05-11T16:59:36.071Z,1494521976.071 [NAL9602] Data Fault, FailCount= 1
2017-05-11T16:59:36.071Z,1494521976.071 [NAL9602](ERROR): Data Fault
2017-05-11T16:59:36.172Z,1494521976.172 [CBIT](ERROR): Data Fault in component: NAL9602
2017-05-11T16:59:36.227Z,1494521976.227 [NAL9602](INFO): Powering down
2017-05-11T16:59:37.059Z,1494521977.059 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-11T16:59:37.059Z,1494521977.059 [NAL9602] No Fault, FailCount= 1
2017-05-11T16:59:41.321Z,1494521981.321 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20170505T142539/Express0005.lzma
2017-05-11T16:59:41.325Z,1494521981.325 [NAL9602](INFO): Powering up NAL9602
2017-05-11T16:59:42.058Z,1494521982.058 [DataOverHttps](INFO): Moved sent file to Logs/20170505T142539/Express0005.lzma.bak
2017-05-11T16:59:42.058Z,1494521982.058 [DataOverHttps](INFO): SBD MOMSN=5021836
2017-05-11T16:59:47.474Z,1494521987.474 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-11T16:59:47.474Z,1494521987.474 [BPC1] Data Fault, FailCount= 1
2017-05-11T16:59:47.475Z,1494521987.474 [BPC1](ERROR): Data Fault
2017-05-11T16:59:47.541Z,1494521987.541 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-11T16:59:51.212Z,1494521991.212 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T142539/Express0009.lzma
2017-05-11T16:59:51.275Z,1494521991.275 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-11T16:59:51.275Z,1494521991.275 [BPC1] No Fault, FailCount= 1
2017-05-11T16:59:52.002Z,1494521992.002 [DataOverHttps](INFO): Moved sent file to Logs/20170505T142539/Express0009.lzma.bak
2017-05-11T16:59:52.002Z,1494521992.002 [DataOverHttps](INFO): SBD MOMSN=5021842
2017-05-11T16:59:52.684Z,1494521992.684 [NAL9602](INFO): NAL9602 initialized
2017-05-11T17:00:01.147Z,1494522001.147 [DataOverHttps](INFO): Sending 674 bytes from file Logs/20170505T144002/Express0001.lzma
2017-05-11T17:00:01.890Z,1494522001.890 [DataOverHttps](INFO): Moved sent file to Logs/20170505T144002/Express0001.lzma.bak
2017-05-11T17:00:01.890Z,1494522001.890 [DataOverHttps](INFO): SBD MOMSN=5021844
2017-05-11T17:00:11.114Z,1494522011.114 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20170505T144002/Express0005.lzma
2017-05-11T17:00:11.866Z,1494522011.866 [DataOverHttps](INFO): Moved sent file to Logs/20170505T144002/Express0005.lzma.bak
2017-05-11T17:00:11.866Z,1494522011.866 [DataOverHttps](INFO): SBD MOMSN=5021856
2017-05-11T17:00:12.752Z,1494522012.752 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T17:00:12.752Z,1494522012.752 [MassServo] No Fault, FailCount= 5
2017-05-11T17:00:21.180Z,1494522021.180 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T144002/Express0009.lzma
2017-05-11T17:00:21.320Z,1494522021.320 [MassServo](FAULT): Overload Error
2017-05-11T17:00:21.320Z,1494522021.320 [MassServo] Hardware Fault, FailCount= 1
2017-05-11T17:00:21.320Z,1494522021.320 [MassServo](ERROR): Hardware Fault
2017-05-11T17:00:21.325Z,1494522021.325 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T17:00:21.641Z,1494522021.641 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T17:00:21.641Z,1494522021.641 [MassServo](INFO): Powering down
2017-05-11T17:00:21.954Z,1494522021.954 [DataOverHttps](INFO): Moved sent file to Logs/20170505T144002/Express0009.lzma.bak
2017-05-11T17:00:21.954Z,1494522021.954 [DataOverHttps](INFO): SBD MOMSN=5021860
2017-05-11T17:00:22.704Z,1494522022.704 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T17:00:22.825Z,1494522022.825 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T17:00:22.830Z,1494522022.830 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T17:00:22.831Z,1494522022.830 [MassServo] No Fault, FailCount= 1
2017-05-11T17:00:31.199Z,1494522031.199 [DataOverHttps](INFO): Sending 661 bytes from file Logs/20170505T145306/Express0001.lzma
2017-05-11T17:00:31.324Z,1494522031.324 [MassServo](FAULT): Overload Error
2017-05-11T17:00:31.324Z,1494522031.324 [MassServo] Hardware Fault, FailCount= 2
2017-05-11T17:00:31.324Z,1494522031.324 [MassServo](ERROR): Hardware Fault
2017-05-11T17:00:31.409Z,1494522031.409 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T17:00:31.633Z,1494522031.633 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T17:00:31.633Z,1494522031.633 [MassServo](INFO): Powering down
2017-05-11T17:00:31.978Z,1494522031.978 [DataOverHttps](INFO): Moved sent file to Logs/20170505T145306/Express0001.lzma.bak
2017-05-11T17:00:31.978Z,1494522031.978 [DataOverHttps](INFO): SBD MOMSN=5021862
2017-05-11T17:00:32.624Z,1494522032.624 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T17:00:32.746Z,1494522032.746 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T17:00:32.751Z,1494522032.751 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T17:00:32.751Z,1494522032.751 [MassServo] No Fault, FailCount= 2
2017-05-11T17:00:41.262Z,1494522041.262 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20170505T145306/Express0005.lzma
2017-05-11T17:00:41.372Z,1494522041.372 [MassServo](FAULT): Overload Error
2017-05-11T17:00:41.372Z,1494522041.372 [MassServo] Hardware Fault, FailCount= 3
2017-05-11T17:00:41.372Z,1494522041.372 [MassServo](ERROR): Hardware Fault
2017-05-11T17:00:41.377Z,1494522041.377 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T17:00:41.691Z,1494522041.691 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T17:00:41.691Z,1494522041.691 [MassServo](INFO): Powering down
2017-05-11T17:00:42.026Z,1494522042.026 [DataOverHttps](INFO): Moved sent file to Logs/20170505T145306/Express0005.lzma.bak
2017-05-11T17:00:42.026Z,1494522042.026 [DataOverHttps](INFO): SBD MOMSN=5021873
2017-05-11T17:00:42.677Z,1494522042.677 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T17:00:42.797Z,1494522042.797 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T17:00:42.802Z,1494522042.802 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T17:00:42.802Z,1494522042.802 [MassServo] No Fault, FailCount= 3
2017-05-11T17:00:51.232Z,1494522051.232 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T145306/Express0009.lzma
2017-05-11T17:00:51.356Z,1494522051.356 [MassServo](FAULT): Overload Error
2017-05-11T17:00:51.356Z,1494522051.356 [MassServo] Hardware Fault, FailCount= 4
2017-05-11T17:00:51.356Z,1494522051.356 [MassServo](ERROR): Hardware Fault
2017-05-11T17:00:51.437Z,1494522051.437 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T17:00:51.701Z,1494522051.701 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T17:00:51.701Z,1494522051.701 [MassServo](INFO): Powering down
2017-05-11T17:00:52.014Z,1494522052.014 [DataOverHttps](INFO): Moved sent file to Logs/20170505T145306/Express0009.lzma.bak
2017-05-11T17:00:52.014Z,1494522052.014 [DataOverHttps](INFO): SBD MOMSN=5021879
2017-05-11T17:00:52.764Z,1494522052.764 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T17:00:52.886Z,1494522052.886 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T17:00:52.892Z,1494522052.892 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-11T17:00:52.892Z,1494522052.892 [MassServo] No Fault, FailCount= 4
2017-05-11T17:01:01.355Z,1494522061.355 [DataOverHttps](INFO): Sending 759 bytes from file Logs/20170505T150901/Express0001.lzma
2017-05-11T17:01:01.472Z,1494522061.472 [MassServo](FAULT): Overload Error
2017-05-11T17:01:01.472Z,1494522061.473 [MassServo] Hardware Fault, FailCount= 5
2017-05-11T17:01:01.472Z,1494522061.473 [MassServo](ERROR): Hardware Fault
2017-05-11T17:01:01.477Z,1494522061.477 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-11T17:01:01.478Z,1494522061.478 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-11T17:01:01.815Z,1494522061.815 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-11T17:01:01.815Z,1494522061.815 [MassServo](INFO): Powering down
2017-05-11T17:01:01.822Z,1494522061.822 [CBIT](INFO): Critical error at 20170511T170101
2017-05-11T17:01:02.130Z,1494522062.130 [DataOverHttps](INFO): Moved sent file to Logs/20170505T150901/Express0001.lzma.bak
2017-05-11T17:01:02.130Z,1494522062.130 [DataOverHttps](INFO): SBD MOMSN=5021881
2017-05-11T17:01:02.766Z,1494522062.766 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-11T17:01:02.881Z,1494522062.881 [MassServo](DEBUG): Initializing MassServo.
2017-05-11T17:01:11.318Z,1494522071.318 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20170505T150901/Express0005.lzma
2017-05-11T17:01:12.086Z,1494522072.086 [DataOverHttps](INFO): Moved sent file to Logs/20170505T150901/Express0005.lzma.bak
2017-05-11T17:01:12.086Z,1494522072.086 [DataOverHttps](INFO): SBD MOMSN=5021893
2017-05-11T17:01:21.280Z,1494522081.280 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170505T150901/Express0009.lzma
2017-05-11T17:01:22.058Z,1494522082.058 [DataOverHttps](INFO): Moved sent file to Logs/20170505T150901/Express0009.lzma.bak
2017-05-11T17:01:22.058Z,1494522082.058 [DataOverHttps](INFO): SBD MOMSN=5021897
2017-05-11T17:01:26.196Z,1494522086.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T17:01:26.196Z,1494522086.196 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2017-05-11T17:01:26.196Z,1494522086.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-11T17:01:26.198Z,1494522086.198 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-11T17:01:26.198Z,1494522086.198 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2017-05-11T17:01:26.198Z,1494522086.198 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-11T17:01:26.210Z,1494522086.210 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-11T17:01:26.210Z,1494522086.210 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-11T17:01:26.373Z,1494522086.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-11T17:01:26.373Z,1494522086.373 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2017-05-11T17:01:26.373Z,1494522086.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-11T17:01:26.374Z,1494522086.374 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2017-05-11T17:01:26.668Z,1494522086.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-11T17:01:26.668Z,1494522086.668 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T17:01:26.669Z,1494522086.668 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T17:01:26.669Z,1494522086.669 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-11T17:01:26.669Z,1494522086.669 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-11T17:01:26.670Z,1494522086.670 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-11T17:01:27.094Z,1494522087.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2017-05-11T17:01:27.479Z,1494522087.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2017-05-11T17:01:27.877Z,1494522087.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2017-05-11T17:01:31.615Z,1494522091.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.4 s old, using for 20.0 s.
2017-05-11T17:01:31.722Z,1494522091.722 [DataOverHttps](INFO): Sending 664 bytes from file Logs/20170505T152249/Express0001.lzma
2017-05-11T17:01:31.802Z,1494522091.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-11T17:01:32.096Z,1494522092.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2017-05-11T17:01:32.448Z,1494522092.448 [DataOverHttps](INFO): Moved sent file to Logs/20170505T152249/Express0001.lzma.bak
2017-05-11T17:01:32.448Z,1494522092.448 [DataOverHttps](INFO): SBD MOMSN=5021899
2017-05-11T17:01:32.504Z,1494522092.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2017-05-11T17:01:33.073Z,1494522093.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-05-11T17:01:33.294Z,1494522093.294 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2017-05-11T17:01:36.588Z,1494522096.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-05-11T17:01:36.736Z,1494522096.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-05-11T17:01:37.090Z,1494522097.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2017-05-11T17:01:37.456Z,1494522097.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2017-05-11T17:01:37.859Z,1494522097.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2017-05-11T17:01:38.306Z,1494522098.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2017-05-11T17:01:41.514Z,1494522101.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2017-05-11T17:01:41.590Z,1494522101.590 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20170505T152249/Express0005.lzma
2017-05-11T17:01:41.670Z,1494522101.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2017-05-11T17:01:42.050Z,1494522102.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2017-05-11T17:01:42.345Z,1494522102.345 [DataOverHttps](INFO): Moved sent file to Logs/20170505T152249/Express0005.lzma.bak
2017-05-11T17:01:42.346Z,1494522102.346 [DataOverHttps](INFO): SBD MOMSN=5021910
2017-05-11T17:01:42.418Z,1494522102.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2017-05-11T17:01:43.009Z,1494522103.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): B