2017-05-12T04:28:52.186Z,1494563332.186 [Supervisor](DEBUG): Initializing supervisor.
2017-05-12T04:28:52.197Z,1494563332.197 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-05-12T04:28:52.198Z,1494563332.198 [SyncHandler](INFO): Protected caller Thread ID is 771
2017-05-12T04:28:52.198Z,1494563332.198 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-05-12T04:28:52.209Z,1494563332.209 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-05-12T04:28:52.210Z,1494563332.210 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 772
2017-05-12T04:28:52.213Z,1494563332.213 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-05-12T04:28:52.235Z,1494563332.235 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-05-12T04:28:52.245Z,1494563332.245 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-05-12T04:28:52.246Z,1494563332.246 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 773
2017-05-12T04:28:52.247Z,1494563332.247 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-05-12T04:28:52.257Z,1494563332.257 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-05-12T04:28:52.258Z,1494563332.258 [logger ThreadHandler](INFO): Protected caller Thread ID is 774
2017-05-12T04:28:52.260Z,1494563332.260 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-05-12T04:28:52.260Z,1494563332.260 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-05-12T04:28:52.267Z,1494563332.267 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-05-12T04:28:52.418Z,1494563332.418 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-05-12T04:28:53.042Z,1494563333.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-05-12T04:28:53.043Z,1494563333.043 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-05-12T04:28:53.177Z,1494563333.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-05-12T04:28:53.178Z,1494563333.178 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-05-12T04:28:53.378Z,1494563333.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-05-12T04:28:53.379Z,1494563333.379 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-05-12T04:28:53.479Z,1494563333.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-05-12T04:28:53.480Z,1494563333.480 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-05-12T04:28:53.676Z,1494563333.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-05-12T04:28:53.678Z,1494563333.678 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-05-12T04:28:54.146Z,1494563334.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-05-12T04:28:54.146Z,1494563334.146 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-05-12T04:28:54.434Z,1494563334.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-05-12T04:28:54.435Z,1494563334.435 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-05-12T04:28:54.522Z,1494563334.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-05-12T04:28:54.522Z,1494563334.523 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-05-12T04:28:54.687Z,1494563334.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-05-12T04:28:54.688Z,1494563334.688 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-05-12T04:28:56.418Z,1494563336.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-05-12T04:28:56.419Z,1494563336.419 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-05-12T04:28:58.165Z,1494563338.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-05-12T04:28:58.165Z,1494563338.165 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-05-12T04:28:58.531Z,1494563338.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-05-12T04:28:58.532Z,1494563338.532 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-05-12T04:28:58.839Z,1494563338.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-05-12T04:28:58.840Z,1494563338.839 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-05-12T04:28:58.951Z,1494563338.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-05-12T04:28:58.953Z,1494563338.953 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-05-12T04:28:58.957Z,1494563338.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-05-12T04:28:59.062Z,1494563339.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-05-12T04:28:59.157Z,1494563339.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-05-12T04:28:59.245Z,1494563339.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-05-12T04:28:59.350Z,1494563339.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-05-12T04:28:59.441Z,1494563339.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-05-12T04:28:59.571Z,1494563339.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-05-12T04:28:59.668Z,1494563339.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-05-12T04:28:59.904Z,1494563339.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-05-12T04:29:05.271Z,1494563345.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-05-12T04:29:05.272Z,1494563345.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-05-12T04:29:05.428Z,1494563345.428 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-05-12T04:29:05.529Z,1494563345.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-05-12T04:29:05.610Z,1494563345.610 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-05-12T04:29:05.613Z,1494563345.613 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-05-12T04:29:05.686Z,1494563345.686 [VerticalControl](DEBUG): Construct VerticalControl.
2017-05-12T04:29:05.804Z,1494563345.804 [VerticalControl] Loaded
2017-05-12T04:29:05.804Z,1494563345.804 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-05-12T04:29:05.805Z,1494563345.805 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-05-12T04:29:05.875Z,1494563345.875 [HorizontalControl] Loaded
2017-05-12T04:29:05.875Z,1494563345.875 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-05-12T04:29:05.876Z,1494563345.876 [SpeedControl](DEBUG): Construct SpeedControl.
2017-05-12T04:29:05.882Z,1494563345.882 [SpeedControl] Loaded
2017-05-12T04:29:05.882Z,1494563345.882 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-05-12T04:29:05.883Z,1494563345.883 [LoopControl](DEBUG): Construct LoopControl.
2017-05-12T04:29:05.883Z,1494563345.883 [LoopControl] Loaded
2017-05-12T04:29:05.883Z,1494563345.883 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-05-12T04:29:05.884Z,1494563345.884 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-05-12T04:29:05.885Z,1494563345.885 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-05-12T04:29:06.045Z,1494563346.045 [SBIT](DEBUG): Construct Startup Built In Test.
2017-05-12T04:29:06.057Z,1494563346.057 [SBIT] Loaded
2017-05-12T04:29:06.057Z,1494563346.057 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-05-12T04:29:06.058Z,1494563346.058 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-05-12T04:29:06.086Z,1494563346.086 [IBIT] Loaded
2017-05-12T04:29:06.087Z,1494563346.087 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-05-12T04:29:06.090Z,1494563346.090 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-05-12T04:29:06.239Z,1494563346.239 [CBIT] Loaded
2017-05-12T04:29:06.239Z,1494563346.239 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-05-12T04:29:06.240Z,1494563346.240 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-05-12T04:29:06.242Z,1494563346.242 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-05-12T04:29:06.283Z,1494563346.283 [DepthRateCalculator] Loaded
2017-05-12T04:29:06.284Z,1494563346.284 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-05-12T04:29:06.289Z,1494563346.289 [PitchRateCalculator] Loaded
2017-05-12T04:29:06.290Z,1494563346.290 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-05-12T04:29:06.301Z,1494563346.301 [SpeedCalculator] Loaded
2017-05-12T04:29:06.301Z,1494563346.301 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-05-12T04:29:06.323Z,1494563346.323 [TempGradientCalculator] Loaded
2017-05-12T04:29:06.324Z,1494563346.323 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-05-12T04:29:06.339Z,1494563346.339 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-05-12T04:29:06.340Z,1494563346.340 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-05-12T04:29:06.345Z,1494563346.345 [YawRateCalculator] Loaded
2017-05-12T04:29:06.345Z,1494563346.345 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-05-12T04:29:06.346Z,1494563346.346 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-05-12T04:29:06.346Z,1494563346.346 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-05-12T04:29:06.468Z,1494563346.468 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-05-12T04:29:06.469Z,1494563346.469 [StratificationFrontDetector](DEBUG): (re)initializing
2017-05-12T04:29:06.469Z,1494563346.469 [StratificationFrontDetector] Loaded
2017-05-12T04:29:06.469Z,1494563346.469 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-05-12T04:29:06.493Z,1494563346.493 [DepAvgTempFrontDetector] Loaded
2017-05-12T04:29:06.493Z,1494563346.493 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread.
2017-05-12T04:29:06.494Z,1494563346.494 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-05-12T04:29:06.495Z,1494563346.494 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-05-12T04:29:06.780Z,1494563346.780 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-05-12T04:29:06.780Z,1494563346.780 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-05-12T04:29:06.907Z,1494563346.907 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-05-12T04:29:06.907Z,1494563346.907 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-05-12T04:29:06.962Z,1494563346.962 [DeadReckonUsingSpeedCalculator] Loaded
2017-05-12T04:29:06.963Z,1494563346.963 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-05-12T04:29:06.979Z,1494563346.980 [NavChart] Loaded
2017-05-12T04:29:06.980Z,1494563346.980 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-05-12T04:29:06.984Z,1494563346.984 [UniversalFixResidualReporter] Loaded
2017-05-12T04:29:06.984Z,1494563346.984 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-05-12T04:29:06.985Z,1494563346.985 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-05-12T04:29:06.986Z,1494563346.986 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-05-12T04:29:06.995Z,1494563346.995 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-05-12T04:29:06.996Z,1494563346.996 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-05-12T04:29:07.189Z,1494563347.189 [Aanderaa_O2] Loaded
2017-05-12T04:29:07.189Z,1494563347.189 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-05-12T04:29:07.270Z,1494563347.270 [CTD_NeilBrown] Loaded
2017-05-12T04:29:07.270Z,1494563347.270 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-05-12T04:29:07.271Z,1494563347.271 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407734E0
2017-05-12T04:29:07.272Z,1494563347.272 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 857
2017-05-12T04:29:07.604Z,1494563347.604 [CTD_Seabird] Loaded
2017-05-12T04:29:07.604Z,1494563347.604 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-05-12T04:29:07.605Z,1494563347.605 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407A34E0
2017-05-12T04:29:07.605Z,1494563347.605 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 858
2017-05-12T04:29:07.621Z,1494563347.621 [PAR_Licor] Loaded
2017-05-12T04:29:07.621Z,1494563347.621 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-05-12T04:29:07.671Z,1494563347.671 [WetLabsBB2FL] Loaded
2017-05-12T04:29:07.671Z,1494563347.671 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-05-12T04:29:07.673Z,1494563347.672 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407D34E0
2017-05-12T04:29:07.673Z,1494563347.673 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 859
2017-05-12T04:29:07.673Z,1494563347.673 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-05-12T04:29:07.674Z,1494563347.674 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-05-12T04:29:08.296Z,1494563348.296 [DataOverHttps] Loaded
2017-05-12T04:29:08.296Z,1494563348.296 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-05-12T04:29:08.310Z,1494563348.310 [Depth_Keller] Loaded
2017-05-12T04:29:08.310Z,1494563348.310 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-05-12T04:29:08.315Z,1494563348.315 [DropWeight] Loaded
2017-05-12T04:29:08.316Z,1494563348.316 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-05-12T04:29:08.408Z,1494563348.408 [NAL9602] Loaded
2017-05-12T04:29:08.408Z,1494563348.408 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-05-12T04:29:08.417Z,1494563348.417 [Onboard] Loaded
2017-05-12T04:29:08.418Z,1494563348.418 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-05-12T04:29:08.425Z,1494563348.425 [Radio_Surface] Loaded
2017-05-12T04:29:08.425Z,1494563348.425 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-05-12T04:29:08.426Z,1494563348.426 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0
2017-05-12T04:29:08.427Z,1494563348.427 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 860
2017-05-12T04:29:08.621Z,1494563348.621 [PNI_TCM] Loaded
2017-05-12T04:29:08.622Z,1494563348.622 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-05-12T04:29:08.685Z,1494563348.685 [Rowe_600LCM] Loaded
2017-05-12T04:29:08.685Z,1494563348.685 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-05-12T04:29:08.686Z,1494563348.686 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0
2017-05-12T04:29:08.687Z,1494563348.687 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 861
2017-05-12T04:29:10.525Z,1494563350.525 [BPC1] Loaded
2017-05-12T04:29:10.526Z,1494563350.526 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-05-12T04:29:10.526Z,1494563350.526 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-05-12T04:29:10.527Z,1494563350.527 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-05-12T04:29:10.755Z,1494563350.755 [BuoyancyServo] Loaded
2017-05-12T04:29:10.755Z,1494563350.755 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-05-12T04:29:10.767Z,1494563350.767 [ElevatorServo] Loaded
2017-05-12T04:29:10.768Z,1494563350.768 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-05-12T04:29:10.779Z,1494563350.779 [MassServo] Loaded
2017-05-12T04:29:10.780Z,1494563350.780 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-05-12T04:29:10.792Z,1494563350.792 [RudderServo] Loaded
2017-05-12T04:29:10.792Z,1494563350.792 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-05-12T04:29:10.803Z,1494563350.803 [ThrusterServo] Loaded
2017-05-12T04:29:10.804Z,1494563350.804 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-05-12T04:29:10.804Z,1494563350.804 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-05-12T04:29:10.805Z,1494563350.805 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-05-12T04:29:10.938Z,1494563350.938 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-05-12T04:29:10.939Z,1494563350.939 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-05-12T04:29:10.980Z,1494563350.980 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-05-12T04:29:10.984Z,1494563350.984 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-05-12T04:29:10.984Z,1494563350.984 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-05-12T04:29:10.991Z,1494563350.991 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-05-12T04:29:10.993Z,1494563350.993 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0
2017-05-12T04:29:10.993Z,1494563350.993 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 862
2017-05-12T04:29:10.998Z,1494563350.998 [Supervisor](INFO): Main Thread ID is 766
2017-05-12T04:29:10.998Z,1494563350.998 [Supervisor](DEBUG): Running supervisor.
2017-05-12T04:29:10.998Z,1494563350.999 [CommandLine ThreadHandler](INFO): Handler Thread ID is 863
2017-05-12T04:29:11.001Z,1494563351.001 [controlThread ThreadHandler](INFO): Handler Thread ID is 864
2017-05-12T04:29:11.002Z,1494563351.002 [controlThread](DEBUG): Initializing ControlThread
2017-05-12T04:29:11.003Z,1494563351.003 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-05-12T04:29:11.004Z,1494563351.004 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-05-12T04:29:11.005Z,1494563351.005 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-05-12T04:29:11.006Z,1494563351.006 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-05-12T04:29:11.006Z,1494563351.006 [SBIT](INFO): Initialize SBIT Component.
2017-05-12T04:29:11.007Z,1494563351.007 [SBIT](IMPORTANT): git: 2017-04-19-5-g443ab08
2017-05-12T04:29:11.007Z,1494563351.007 [SBIT](INFO): git hash: 443ab0852958b11c27b2467e632b948d2f0b0b23
2017-05-12T04:29:11.009Z,1494563351.009 [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-12T04:29:11.009Z,1494563351.009 [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-12T04:29:11.010Z,1494563351.010 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2017-05-12T04:29:11.011Z,1494563351.011 [IBIT](INFO): Initialize IBIT Component.
2017-05-12T04:29:11.012Z,1494563351.012 [CBIT](DEBUG): Initialize CBIT Component.
2017-05-12T04:29:11.012Z,1494563351.012 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-05-12T04:29:11.012Z,1494563351.012 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-05-12T04:29:11.013Z,1494563351.013 [logger ThreadHandler](INFO): Handler Thread ID is 865
2017-05-12T04:29:11.030Z,1494563351.030 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 866
2017-05-12T04:29:11.031Z,1494563351.031 [CTD_NeilBrown](INFO): Powering down
2017-05-12T04:29:11.061Z,1494563351.061 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 867
2017-05-12T04:29:11.061Z,1494563351.061 [CTD_Seabird](INFO): Initializing
2017-05-12T04:29:11.105Z,1494563351.105 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-05-12T04:29:11.105Z,1494563351.105 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-05-12T04:29:11.105Z,1494563351.105 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-05-12T04:29:11.106Z,1494563351.106 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-05-12T04:29:11.107Z,1494563351.107 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-05-12T04:29:11.108Z,1494563351.108 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-05-12T04:29:11.109Z,1494563351.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:29:11.110Z,1494563351.110 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:29:11.110Z,1494563351.110 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:29:11.111Z,1494563351.111 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:29:11.111Z,1494563351.111 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:29:11.112Z,1494563351.112 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:29:11.112Z,1494563351.112 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-05-12T04:29:11.112Z,1494563351.112 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-05-12T04:29:11.122Z,1494563351.122 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 869
2017-05-12T04:29:11.123Z,1494563351.124 [WetLabsBB2FL](INFO): Powering down
2017-05-12T04:29:11.158Z,1494563351.158 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-05-12T04:29:11.170Z,1494563351.170 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 870
2017-05-12T04:29:11.178Z,1494563351.178 [Radio_Surface](INFO): Powering up
2017-05-12T04:29:11.190Z,1494563351.190 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 871
2017-05-12T04:29:11.191Z,1494563351.191 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:29:11.192Z,1494563351.192 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:29:11.192Z,1494563351.192 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:29:11.192Z,1494563351.192 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:29:11.210Z,1494563351.210 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 872
2017-05-12T04:29:11.219Z,1494563351.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-05-12T04:29:11.219Z,1494563351.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-05-12T04:29:11.220Z,1494563351.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-05-12T04:29:11.220Z,1494563351.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-05-12T04:29:11.220Z,1494563351.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-05-12T04:29:11.220Z,1494563351.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-05-12T04:29:11.220Z,1494563351.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-05-12T04:29:11.221Z,1494563351.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-05-12T04:29:11.221Z,1494563351.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-05-12T04:29:11.221Z,1494563351.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-05-12T04:29:11.221Z,1494563351.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-05-12T04:29:11.222Z,1494563351.222 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-05-12T04:29:11.222Z,1494563351.222 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-05-12T04:29:11.222Z,1494563351.222 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-05-12T04:29:11.222Z,1494563351.222 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-05-12T04:29:11.222Z,1494563351.223 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-05-12T04:29:11.226Z,1494563351.226 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-05-12T04:29:11.276Z,1494563351.276 [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-12T04:29:11.298Z,1494563351.297 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-05-12T04:29:11.382Z,1494563351.382 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-05-12T04:29:11.382Z,1494563351.382 [CTD_Seabird](INFO): Powering down
2017-05-12T04:29:11.406Z,1494563351.406 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-05-12T04:29:11.407Z,1494563351.407 [Default:A.Wait](DEBUG): Construct Wait.
2017-05-12T04:29:11.409Z,1494563351.409 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-05-12T04:29:11.455Z,1494563351.455 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-05-12T04:29:11.481Z,1494563351.481 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-05-12T04:29:11.487Z,1494563351.487 [Default:E.Execute](DEBUG): Construct Execute.
2017-05-12T04:29:11.506Z,1494563351.506 [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-12T04:29:11.534Z,1494563351.534 [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-12T04:29:11.618Z,1494563351.618 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-05-12T04:29:11.628Z,1494563351.628 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-05-12T04:29:11.866Z,1494563351.866 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-05-12T04:29:11.963Z,1494563351.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:12.186Z,1494563352.186 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:12.300Z,1494563352.300 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:12.306Z,1494563352.306 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-05-12T04:29:12.328Z,1494563352.328 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:12.334Z,1494563352.334 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-05-12T04:29:12.347Z,1494563352.348 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:12.354Z,1494563352.354 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:29:12.368Z,1494563352.368 [RudderServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:12.374Z,1494563352.374 [RudderServo](DEBUG): Initializing RudderServo.
2017-05-12T04:29:12.398Z,1494563352.398 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:12.406Z,1494563352.406 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-05-12T04:29:12.643Z,1494563352.643 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2017-05-12T04:29:12.643Z,1494563352.644 [DropWeight] Hardware Fault, FailCount= 1
2017-05-12T04:29:12.644Z,1494563352.644 [DropWeight](ERROR): Hardware Fault
2017-05-12T04:29:12.711Z,1494563352.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:12.712Z,1494563352.712 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:12.721Z,1494563352.721 [CommandLine](FAULT): Scheduling is paused
2017-05-12T04:29:12.722Z,1494563352.722 [CBIT](INFO): Critical error at 20170512T042912
2017-05-12T04:29:12.722Z,1494563352.722 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-05-12T04:29:12.725Z,1494563352.725 [CBIT](ERROR): Hardware Fault in component: DropWeight
2017-05-12T04:29:12.729Z,1494563352.730 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2017-05-12T04:29:12.927Z,1494563352.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:12.928Z,1494563352.928 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:13.273Z,1494563353.273 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-05-12T04:29:13.273Z,1494563353.273 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-05-12T04:29:13.274Z,1494563353.274 [BuoyancyServo] Communications Fault, FailCount= 1
2017-05-12T04:29:13.274Z,1494563353.274 [BuoyancyServo](ERROR): Communications Fault
2017-05-12T04:29:13.483Z,1494563353.483 [CBIT](INFO): Critical error at 20170512T042912
2017-05-12T04:29:13.486Z,1494563353.486 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-05-12T04:29:13.507Z,1494563353.507 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:29:13.550Z,1494563353.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:13.551Z,1494563353.551 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:13.557Z,1494563353.557 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-05-12T04:29:13.566Z,1494563353.566 [BuoyancyServo](INFO): Powering down
2017-05-12T04:29:13.965Z,1494563353.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:13.967Z,1494563353.967 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:14.349Z,1494563354.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:14.351Z,1494563354.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:14.814Z,1494563354.814 [Aanderaa_O2](INFO): Powering down
2017-05-12T04:29:14.922Z,1494563354.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:14.923Z,1494563354.923 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:15.153Z,1494563355.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:15.165Z,1494563355.165 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-05-12T04:29:15.447Z,1494563355.447 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:29:15.577Z,1494563355.577 [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-12T04:29:15.855Z,1494563355.855 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-05-12T04:29:15.855Z,1494563355.855 [BuoyancyServo] No Fault, FailCount= 1
2017-05-12T04:29:16.171Z,1494563356.171 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:16.171Z,1494563356.171 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-05-12T04:29:16.544Z,1494563356.544 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:29:16.545Z,1494563356.545 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:29:16.546Z,1494563356.546 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:29:16.682Z,1494563356.681 [MassServo](FAULT): Overload Error
2017-05-12T04:29:16.682Z,1494563356.682 [MassServo] Hardware Fault, FailCount= 1
2017-05-12T04:29:16.682Z,1494563356.682 [MassServo](ERROR): Hardware Fault
2017-05-12T04:29:16.745Z,1494563356.745 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:29:17.058Z,1494563357.059 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:29:17.059Z,1494563357.059 [MassServo](INFO): Powering down
2017-05-12T04:29:17.777Z,1494563357.777 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:17.789Z,1494563357.789 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:29:17.794Z,1494563357.794 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:29:17.794Z,1494563357.794 [MassServo] No Fault, FailCount= 1
2017-05-12T04:29:21.422Z,1494563361.422 [MassServo](FAULT): Overload Error
2017-05-12T04:29:21.422Z,1494563361.422 [MassServo] Hardware Fault, FailCount= 2
2017-05-12T04:29:21.422Z,1494563361.422 [MassServo](ERROR): Hardware Fault
2017-05-12T04:29:21.427Z,1494563361.427 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:29:21.780Z,1494563361.780 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:29:21.780Z,1494563361.780 [MassServo](INFO): Powering down
2017-05-12T04:29:22.625Z,1494563362.625 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:22.634Z,1494563362.634 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:29:22.665Z,1494563362.665 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:29:22.665Z,1494563362.665 [MassServo] No Fault, FailCount= 2
2017-05-12T04:29:26.595Z,1494563366.595 [MassServo](FAULT): Overload Error
2017-05-12T04:29:26.595Z,1494563366.595 [MassServo] Hardware Fault, FailCount= 3
2017-05-12T04:29:26.595Z,1494563366.595 [MassServo](ERROR): Hardware Fault
2017-05-12T04:29:26.674Z,1494563366.674 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:29:26.992Z,1494563366.991 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:29:26.992Z,1494563366.992 [MassServo](INFO): Powering down
2017-05-12T04:29:27.804Z,1494563367.804 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:27.805Z,1494563367.805 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:29:27.826Z,1494563367.826 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:29:27.826Z,1494563367.826 [MassServo] No Fault, FailCount= 3
2017-05-12T04:29:32.190Z,1494563372.190 [MassServo](FAULT): Overload Error
2017-05-12T04:29:32.191Z,1494563372.191 [MassServo] Hardware Fault, FailCount= 4
2017-05-12T04:29:32.191Z,1494563372.191 [MassServo](ERROR): Hardware Fault
2017-05-12T04:29:32.197Z,1494563372.197 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:29:32.576Z,1494563372.576 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:29:32.576Z,1494563372.576 [MassServo](INFO): Powering down
2017-05-12T04:29:33.368Z,1494563373.368 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:33.368Z,1494563373.368 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:29:33.389Z,1494563373.389 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:29:33.389Z,1494563373.389 [MassServo] No Fault, FailCount= 4
2017-05-12T04:29:34.491Z,1494563374.491 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:29:34.491Z,1494563374.491 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:29:34.491Z,1494563374.491 [NAL9602](ERROR): Communications Fault
2017-05-12T04:29:34.571Z,1494563374.571 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:29:34.891Z,1494563374.891 [NAL9602](INFO): Powering down
2017-05-12T04:29:35.858Z,1494563375.858 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:29:35.858Z,1494563375.858 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:29:37.422Z,1494563377.422 [MassServo](FAULT): Overload Error
2017-05-12T04:29:37.422Z,1494563377.422 [MassServo] Hardware Fault, FailCount= 5
2017-05-12T04:29:37.422Z,1494563377.422 [MassServo](ERROR): Hardware Fault
2017-05-12T04:29:37.499Z,1494563377.499 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:29:37.499Z,1494563377.499 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-12T04:29:37.774Z,1494563377.774 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:29:37.774Z,1494563377.774 [MassServo](INFO): Powering down
2017-05-12T04:29:37.782Z,1494563377.782 [CBIT](INFO): Critical error at 20170512T042937
2017-05-12T04:29:38.645Z,1494563378.645 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:29:38.658Z,1494563378.658 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:29:38.916Z,1494563378.916 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-12T04:29:38.916Z,1494563378.917 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:29:38.917Z,1494563378.917 [BPC1](ERROR): Data Fault
2017-05-12T04:29:38.964Z,1494563378.964 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:29:40.091Z,1494563380.091 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:29:40.155Z,1494563380.155 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:29:40.155Z,1494563380.155 [BPC1] No Fault, FailCount= 1
2017-05-12T04:29:43.748Z,1494563383.748 [SBIT](IMPORTANT): Beginning Startup BIT
2017-05-12T04:29:43.768Z,1494563383.769 [CBIT](IMPORTANT): Beginning ground fault scan
2017-05-12T04:29:54.224Z,1494563394.224 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027234
CHAN A2 (12V): -0.006158
CHAN A3 (5V): -0.001082
CHAN B0 (3.3V): -0.000922
CHAN B1 (3.15aV): -0.001041
CHAN B2 (3.15bV): -0.000649
CHAN B3 (GND): -0.000088
OPEN: -0.000803
Full Scale Calc: 4.765 mA, -1.589 mA
2017-05-12T04:29:56.985Z,1494563396.985 [SBIT](ERROR): Could not read massPosReader_.
2017-05-12T04:30:01.303Z,1494563401.303 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:30:01.303Z,1494563401.303 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:30:01.303Z,1494563401.303 [NAL9602](ERROR): Communications Fault
2017-05-12T04:30:01.451Z,1494563401.451 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:30:01.696Z,1494563401.696 [NAL9602](INFO): Powering down
2017-05-12T04:30:02.619Z,1494563402.619 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:30:02.619Z,1494563402.619 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:30:06.906Z,1494563406.906 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:30:23.746Z,1494563423.746 [SBIT](ERROR): Could not read massPosReader_.
2017-05-12T04:30:23.746Z,1494563423.746 [SBIT](FAULT): Control surface position failure.
2017-05-12T04:30:24.922Z,1494563424.922 [BPC1](ERROR): Failed to receive battery data
2017-05-12T04:30:24.922Z,1494563424.922 [BPC1] Communications Fault, FailCount= 1
2017-05-12T04:30:24.922Z,1494563424.922 [BPC1](ERROR): Communications Fault
2017-05-12T04:30:24.969Z,1494563424.969 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-12T04:30:26.233Z,1494563426.233 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:30:26.233Z,1494563426.233 [BPC1] No Fault, FailCount= 1
2017-05-12T04:30:28.097Z,1494563428.098 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:30:28.098Z,1494563428.098 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:30:28.098Z,1494563428.098 [NAL9602](ERROR): Communications Fault
2017-05-12T04:30:28.215Z,1494563428.215 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:30:28.499Z,1494563428.499 [NAL9602](INFO): Powering down
2017-05-12T04:30:29.356Z,1494563429.356 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:30:29.356Z,1494563429.356 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:30:33.687Z,1494563433.687 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:30:37.348Z,1494563437.348 [SBIT](ERROR): Could not read massPosReader_.
2017-05-12T04:30:37.349Z,1494563437.349 [SBIT](FAULT): Control surface position failure.
2017-05-12T04:30:37.744Z,1494563437.744 [SBIT](CRITICAL): SBIT FAILED
2017-05-12T04:30:37.745Z,1494563437.745 [CBIT](INFO): Critical error at 20170512T043037
2017-05-12T04:30:38.143Z,1494563438.143 [MissionManager](IMPORTANT): Started mission Startup
2017-05-12T04:30:38.143Z,1494563438.143 [Startup] Running Loop=1
2017-05-12T04:30:38.143Z,1494563438.143 [Startup](DEBUG): Aggregate::initialize Startup
2017-05-12T04:30:38.143Z,1494563438.143 [Startup:A.GoToSurface] Running Loop=1
2017-05-12T04:30:38.143Z,1494563438.143 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-05-12T04:30:38.144Z,1494563438.144 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-05-12T04:30:38.144Z,1494563438.144 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-05-12T04:30:38.145Z,1494563438.145 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-05-12T04:30:38.149Z,1494563438.149 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-05-12T04:30:38.150Z,1494563438.150 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-05-12T04:30:38.160Z,1494563438.160 [Startup:StartupSatComms] Running Loop=1
2017-05-12T04:30:38.160Z,1494563438.160 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-05-12T04:30:38.160Z,1494563438.160 [Startup:StartupSatComms:A] Running Loop=1
2017-05-12T04:30:38.604Z,1494563438.604 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-05-12T04:30:53.747Z,1494563453.747 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003900
2017-05-12T04:30:54.547Z,1494563454.547 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:30:54.547Z,1494563454.547 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:30:54.547Z,1494563454.547 [NAL9602](ERROR): Communications Fault
2017-05-12T04:30:54.625Z,1494563454.625 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:30:54.957Z,1494563454.957 [NAL9602](INFO): Powering down
2017-05-12T04:30:55.846Z,1494563455.846 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:30:55.846Z,1494563455.846 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:31:00.151Z,1494563460.151 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:31:11.905Z,1494563471.905 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:31:11.905Z,1494563471.905 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-05-12T04:31:11.905Z,1494563471.905 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:31:12.207Z,1494563472.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:31:12.250Z,1494563472.250 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:31:12.310Z,1494563472.310 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:31:12.415Z,1494563472.415 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:31:12.600Z,1494563472.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:31:12.995Z,1494563472.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:31:13.402Z,1494563473.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:31:13.814Z,1494563473.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:31:13.848Z,1494563473.848 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:31:13.848Z,1494563473.848 [Rowe_600LCM] No Fault, FailCount= 1
2017-05-12T04:31:14.029Z,1494563474.029 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:31:14.030Z,1494563474.030 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:31:14.030Z,1494563474.030 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:31:14.030Z,1494563474.030 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:31:14.206Z,1494563474.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:31:14.602Z,1494563474.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:31:14.000Z,1494563475.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:31:15.411Z,1494563475.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:31:15.813Z,1494563475.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:31:16.204Z,1494563476.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:31:16.603Z,1494563476.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:31:17.007Z,1494563477.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:31:17.409Z,1494563477.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:31:17.811Z,1494563477.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:31:18.242Z,1494563478.242 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:31:18.246Z,1494563478.246 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:31:18.361Z,1494563478.361 [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-12T04:31:18.363Z,1494563478.363 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:31:18.364Z,1494563478.364 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:31:18.365Z,1494563478.365 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:31:18.643Z,1494563478.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:31:19.393Z,1494563479.392 [BPC1](ERROR): Failed to parse message.
2017-05-12T04:31:19.393Z,1494563479.393 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-12T04:31:19.393Z,1494563479.393 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:31:19.393Z,1494563479.393 [BPC1](ERROR): Data Fault
2017-05-12T04:31:19.452Z,1494563479.452 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:31:20.646Z,1494563480.646 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:31:20.646Z,1494563480.646 [BPC1] No Fault, FailCount= 1
2017-05-12T04:31:21.347Z,1494563481.347 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:31:21.347Z,1494563481.347 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:31:21.347Z,1494563481.347 [NAL9602](ERROR): Communications Fault
2017-05-12T04:31:21.448Z,1494563481.448 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:31:21.449Z,1494563481.449 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:31:21.747Z,1494563481.747 [NAL9602](INFO): Powering down
2017-05-12T04:31:21.834Z,1494563481.834 [CBIT](INFO): Critical error at 20170512T043121
2017-05-12T04:31:26.987Z,1494563486.987 [PNI_TCM](ERROR): CRC does not match. Expected:0x21334 got:0x7106
2017-05-12T04:31:28.586Z,1494563488.586 [PNI_TCM](ERROR): CRC does not match. Expected:0x15209 got:0x16786
2017-05-12T04:31:30.188Z,1494563490.188 [PNI_TCM](ERROR): CRC does not match. Expected:0x25393 got:0x17355
2017-05-12T04:31:31.792Z,1494563491.792 [PNI_TCM](ERROR): CRC does not match. Expected:0x5684 got:0x16822
2017-05-12T04:31:38.221Z,1494563498.221 [Startup:StartupSatComms:A](INFO): Timed out from 2017-05-12T04:30:38.2Z
2017-05-12T04:31:38.221Z,1494563498.221 [Startup:StartupSatComms:A] Stopped
2017-05-12T04:31:38.221Z,1494563498.221 [Startup:StartupSatComms:B] Running Loop=1
2017-05-12T04:31:38.601Z,1494563498.601 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-05-12T04:31:43.468Z,1494563503.468 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20170511T175437/Courier0244.lzma
2017-05-12T04:31:43.964Z,1494563503.964 [DataOverHttps](INFO): Moved sent file to Logs/20170511T175437/Courier0244.lzma.bak
2017-05-12T04:31:43.965Z,1494563503.965 [DataOverHttps](INFO): SBD MOMSN=5023780
2017-05-12T04:31:58.048Z,1494563518.048 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170511T175437/Courier0248.lzma
2017-05-12T04:31:59.898Z,1494563519.898 [DataOverHttps](INFO): Moved sent file to Logs/20170511T175437/Courier0248.lzma.bak
2017-05-12T04:31:59.898Z,1494563519.898 [DataOverHttps](INFO): SBD MOMSN=5023786
2017-05-12T04:32:09.303Z,1494563529.303 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20170512T042852/Courier0000.lzma
2017-05-12T04:32:10.068Z,1494563530.068 [DataOverHttps](INFO): Moved sent file to Logs/20170512T042852/Courier0000.lzma.bak
2017-05-12T04:32:10.068Z,1494563530.068 [DataOverHttps](INFO): SBD MOMSN=5023788
2017-05-12T04:32:14.273Z,1494563534.273 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:32:14.273Z,1494563534.273 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-05-12T04:32:14.273Z,1494563534.273 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:32:14.275Z,1494563534.275 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:32:14.275Z,1494563534.275 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-05-12T04:32:14.275Z,1494563534.275 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:32:14.316Z,1494563534.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:32:14.316Z,1494563534.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:32:14.439Z,1494563534.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:32:14.439Z,1494563534.439 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-05-12T04:32:14.439Z,1494563534.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:32:14.439Z,1494563534.439 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-05-12T04:32:14.792Z,1494563534.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:32:14.793Z,1494563534.793 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:32:14.793Z,1494563534.793 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:32:14.794Z,1494563534.794 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:32:14.794Z,1494563534.794 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:32:14.794Z,1494563534.794 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:32:19.524Z,1494563539.524 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20170511T175437/Express0245.lzma
2017-05-12T04:32:20.264Z,1494563540.264 [DataOverHttps](INFO): Moved sent file to Logs/20170511T175437/Express0245.lzma.bak
2017-05-12T04:32:20.264Z,1494563540.264 [DataOverHttps](INFO): SBD MOMSN=5023796
2017-05-12T04:32:29.786Z,1494563549.786 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170511T175437/Express0249.lzma
2017-05-12T04:32:30.536Z,1494563550.536 [DataOverHttps](INFO): Moved sent file to Logs/20170511T175437/Express0249.lzma.bak
2017-05-12T04:32:30.536Z,1494563550.536 [DataOverHttps](INFO): SBD MOMSN=5023812
2017-05-12T04:32:38.574Z,1494563558.574 [Startup:StartupSatComms:B](INFO): Timed out from 2017-05-12T04:31:38.2Z
2017-05-12T04:32:38.574Z,1494563558.574 [Startup:StartupSatComms:B] Stopped
2017-05-12T04:32:38.575Z,1494563558.575 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-05-12T04:32:38.575Z,1494563558.575 [Startup:StartupSatComms] Stopped
2017-05-12T04:32:38.575Z,1494563558.575 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-05-12T04:32:38.576Z,1494563558.576 [Startup](INFO): Completed Startup
2017-05-12T04:32:38.576Z,1494563558.576 [MissionManager](INFO): Startup is completed.
2017-05-12T04:32:38.576Z,1494563558.576 [MissionManager](INFO): Uninitializing Mission Startup
2017-05-12T04:32:38.576Z,1494563558.576 [Startup] Stopped
2017-05-12T04:32:38.576Z,1494563558.576 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-05-12T04:32:38.576Z,1494563558.576 [Startup:A.GoToSurface] Stopped
2017-05-12T04:32:38.576Z,1494563558.576 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-05-12T04:32:38.734Z,1494563558.734 [MissionManager](IMPORTANT): Started mission Default
2017-05-12T04:32:38.734Z,1494563558.734 [Default] Running Loop=1
2017-05-12T04:32:38.734Z,1494563558.734 [Default](DEBUG): Aggregate::initialize Default
2017-05-12T04:32:38.734Z,1494563558.734 [Default:B.GoToSurface] Running Loop=1
2017-05-12T04:32:38.735Z,1494563558.735 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-05-12T04:32:38.735Z,1494563558.735 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-05-12T04:32:38.735Z,1494563558.735 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-05-12T04:32:38.735Z,1494563558.736 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-05-12T04:32:38.736Z,1494563558.736 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-05-12T04:32:38.736Z,1494563558.736 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-05-12T04:32:38.736Z,1494563558.736 [Default:A.Wait] Running Loop=1
2017-05-12T04:32:38.737Z,1494563558.737 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-05-12T04:32:44.958Z,1494563564.958 [DataOverHttps](INFO): Sending 768 bytes from file Logs/20170512T042852/Express0001.lzma
2017-05-12T04:33:01.420Z,1494563581.420 [DataOverHttps](INFO): Moved sent file to Logs/20170512T042852/Express0001.lzma.bak
2017-05-12T04:33:01.421Z,1494563581.421 [DataOverHttps](INFO): SBD MOMSN=5023814
2017-05-12T04:33:01.679Z,1494563581.679 [Default:A.Wait](INFO): Done Waiting.
2017-05-12T04:33:01.679Z,1494563581.679 [Default:A.Wait] Stopped
2017-05-12T04:33:01.679Z,1494563581.679 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-05-12T04:33:01.837Z,1494563581.837 [Default:CheckIn] Running Loop=1
2017-05-12T04:33:01.838Z,1494563581.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-05-12T04:33:01.838Z,1494563581.838 [Default:CheckIn:Read_GPS] Running Loop=1
2017-05-12T04:33:02.253Z,1494563582.253 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-05-12T04:33:14.421Z,1494563594.421 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:33:14.421Z,1494563594.421 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-05-12T04:33:14.421Z,1494563594.421 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:33:14.585Z,1494563594.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:33:14.610Z,1494563594.611 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:33:14.826Z,1494563594.826 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:33:14.981Z,1494563594.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:33:15.008Z,1494563595.008 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:33:15.404Z,1494563595.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:33:15.782Z,1494563595.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:33:16.192Z,1494563596.192 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:33:16.596Z,1494563596.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:33:16.610Z,1494563596.610 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:33:16.610Z,1494563596.610 [Rowe_600LCM] No Fault, FailCount= 2
2017-05-12T04:33:16.633Z,1494563596.633 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:33:16.634Z,1494563596.634 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:33:16.634Z,1494563596.634 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:33:16.634Z,1494563596.634 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:33:16.969Z,1494563596.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:33:17.364Z,1494563597.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:33:17.762Z,1494563597.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:33:18.167Z,1494563598.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:33:18.567Z,1494563598.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:33:18.972Z,1494563598.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:33:19.367Z,1494563599.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:33:19.772Z,1494563599.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:33:20.163Z,1494563600.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:33:20.563Z,1494563600.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:33:20.761Z,1494563600.761 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:33:20.829Z,1494563600.829 [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-12T04:33:20.831Z,1494563600.831 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:33:20.832Z,1494563600.832 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:33:20.833Z,1494563600.833 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:33:20.981Z,1494563600.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:33:21.382Z,1494563601.382 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:33:21.382Z,1494563601.382 [NAL9602] No Fault, FailCount= 5
2017-05-12T04:33:21.728Z,1494563601.728 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:33:43.914Z,1494563623.914 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:33:43.914Z,1494563623.914 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:33:43.914Z,1494563623.914 [NAL9602](ERROR): Communications Fault
2017-05-12T04:33:44.074Z,1494563624.074 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:33:44.146Z,1494563624.146 [NAL9602](INFO): Powering down
2017-05-12T04:33:44.972Z,1494563624.972 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:33:44.972Z,1494563624.972 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:33:49.299Z,1494563629.299 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:33:57.731Z,1494563637.731 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-12T04:33:57.731Z,1494563637.731 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:33:57.732Z,1494563637.731 [BPC1](ERROR): Data Fault
2017-05-12T04:33:57.780Z,1494563637.780 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:33:58.951Z,1494563638.951 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:33:58.951Z,1494563638.951 [BPC1] No Fault, FailCount= 1
2017-05-12T04:34:10.515Z,1494563650.515 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:34:10.515Z,1494563650.515 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:34:10.515Z,1494563650.515 [NAL9602](ERROR): Communications Fault
2017-05-12T04:34:10.581Z,1494563650.581 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:34:10.899Z,1494563650.899 [NAL9602](INFO): Powering down
2017-05-12T04:34:11.828Z,1494563651.828 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:34:11.828Z,1494563651.828 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:34:16.099Z,1494563656.099 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:34:36.899Z,1494563676.899 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:34:36.899Z,1494563676.899 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:34:36.899Z,1494563676.899 [NAL9602](ERROR): Communications Fault
2017-05-12T04:34:36.981Z,1494563676.981 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:34:37.299Z,1494563677.299 [NAL9602](INFO): Powering down
2017-05-12T04:34:37.760Z,1494563677.760 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:34:37.760Z,1494563677.760 [MassServo] No Fault, FailCount= 5
2017-05-12T04:34:38.266Z,1494563678.266 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:34:38.266Z,1494563678.266 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:34:43.915Z,1494563683.915 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:34:44.085Z,1494563684.085 [MassServo](FAULT): Overload Error
2017-05-12T04:34:44.086Z,1494563684.086 [MassServo] Hardware Fault, FailCount= 1
2017-05-12T04:34:44.086Z,1494563684.086 [MassServo](ERROR): Hardware Fault
2017-05-12T04:34:44.090Z,1494563684.090 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:34:44.209Z,1494563684.209 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:34:44.209Z,1494563684.209 [MassServo](INFO): Powering down
2017-05-12T04:34:44.961Z,1494563684.961 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:34:44.962Z,1494563684.962 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:34:44.966Z,1494563684.966 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:34:44.966Z,1494563684.967 [MassServo] No Fault, FailCount= 1
2017-05-12T04:34:49.001Z,1494563689.001 [MassServo](FAULT): Overload Error
2017-05-12T04:34:49.002Z,1494563689.001 [MassServo] Hardware Fault, FailCount= 2
2017-05-12T04:34:49.002Z,1494563689.002 [MassServo](ERROR): Hardware Fault
2017-05-12T04:34:49.098Z,1494563689.098 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:34:49.367Z,1494563689.367 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:34:49.367Z,1494563689.367 [MassServo](INFO): Powering down
2017-05-12T04:34:50.236Z,1494563690.236 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:34:50.236Z,1494563690.236 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:34:50.263Z,1494563690.263 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:34:50.263Z,1494563690.263 [MassServo] No Fault, FailCount= 2
2017-05-12T04:34:54.230Z,1494563694.230 [MassServo](FAULT): Overload Error
2017-05-12T04:34:54.230Z,1494563694.230 [MassServo] Hardware Fault, FailCount= 3
2017-05-12T04:34:54.230Z,1494563694.230 [MassServo](ERROR): Hardware Fault
2017-05-12T04:34:54.235Z,1494563694.235 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:34:54.573Z,1494563694.573 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:34:54.573Z,1494563694.573 [MassServo](INFO): Powering down
2017-05-12T04:34:55.368Z,1494563695.368 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:34:55.368Z,1494563695.368 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:34:55.373Z,1494563695.373 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:34:55.373Z,1494563695.373 [MassServo] No Fault, FailCount= 3
2017-05-12T04:34:59.390Z,1494563699.390 [MassServo](FAULT): Overload Error
2017-05-12T04:34:59.390Z,1494563699.390 [MassServo] Hardware Fault, FailCount= 4
2017-05-12T04:34:59.390Z,1494563699.390 [MassServo](ERROR): Hardware Fault
2017-05-12T04:34:59.478Z,1494563699.478 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:34:59.809Z,1494563699.809 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:34:59.813Z,1494563699.813 [MassServo](INFO): Powering down
2017-05-12T04:35:00.563Z,1494563700.563 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:35:00.564Z,1494563700.564 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:35:00.569Z,1494563700.569 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:35:00.569Z,1494563700.569 [MassServo] No Fault, FailCount= 4
2017-05-12T04:35:04.903Z,1494563704.903 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:35:04.903Z,1494563704.903 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:35:04.903Z,1494563704.903 [NAL9602](ERROR): Communications Fault
2017-05-12T04:35:05.001Z,1494563705.001 [MassServo](FAULT): Overload Error
2017-05-12T04:35:05.002Z,1494563705.002 [MassServo] Hardware Fault, FailCount= 5
2017-05-12T04:35:05.002Z,1494563705.002 [MassServo](ERROR): Hardware Fault
2017-05-12T04:35:05.010Z,1494563705.010 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:35:05.010Z,1494563705.010 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:35:05.011Z,1494563705.011 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-12T04:35:05.303Z,1494563705.303 [NAL9602](INFO): Powering down
2017-05-12T04:35:05.387Z,1494563705.388 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:35:05.388Z,1494563705.388 [MassServo](INFO): Powering down
2017-05-12T04:35:05.391Z,1494563705.391 [CBIT](INFO): Critical error at 20170512T043505
2017-05-12T04:35:06.180Z,1494563706.180 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:35:06.181Z,1494563706.181 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:35:06.206Z,1494563706.206 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:35:06.206Z,1494563706.206 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:35:10.511Z,1494563710.511 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:35:14.948Z,1494563714.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:35:14.948Z,1494563714.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-05-12T04:35:14.948Z,1494563714.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:35:14.950Z,1494563714.950 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:35:14.950Z,1494563714.950 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-05-12T04:35:14.950Z,1494563714.950 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:35:14.970Z,1494563714.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:35:14.971Z,1494563714.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:35:15.374Z,1494563715.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:35:15.374Z,1494563715.374 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-05-12T04:35:15.374Z,1494563715.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:35:15.374Z,1494563715.374 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-05-12T04:35:15.739Z,1494563715.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:35:15.739Z,1494563715.739 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:35:15.739Z,1494563715.740 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:35:15.740Z,1494563715.740 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:35:15.740Z,1494563715.740 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:35:15.741Z,1494563715.741 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:35:16.672Z,1494563716.672 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:35:16.672Z,1494563716.672 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-05-12T04:35:16.672Z,1494563716.672 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:35:16.958Z,1494563716.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:35:16.991Z,1494563716.991 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:35:17.078Z,1494563717.078 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:35:17.173Z,1494563717.173 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:35:17.357Z,1494563717.357 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:35:17.748Z,1494563717.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:35:18.145Z,1494563718.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:35:18.546Z,1494563718.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:35:18.559Z,1494563718.559 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:35:18.559Z,1494563718.559 [Rowe_600LCM] No Fault, FailCount= 3
2017-05-12T04:35:18.789Z,1494563718.789 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:35:18.790Z,1494563718.790 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:35:18.790Z,1494563718.790 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:35:18.790Z,1494563718.790 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:35:18.951Z,1494563718.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:35:19.358Z,1494563719.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:35:19.763Z,1494563719.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:35:20.141Z,1494563720.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:35:20.543Z,1494563720.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:35:20.944Z,1494563720.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:35:21.366Z,1494563721.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:35:21.771Z,1494563721.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:35:22.174Z,1494563722.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:35:22.572Z,1494563722.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:35:22.987Z,1494563722.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:35:23.036Z,1494563723.036 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:35:23.119Z,1494563723.118 [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-12T04:35:23.121Z,1494563723.121 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:35:23.122Z,1494563723.122 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:35:23.123Z,1494563723.123 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:35:23.402Z,1494563723.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-05-12T04:35:31.715Z,1494563731.715 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:35:31.715Z,1494563731.715 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:35:31.715Z,1494563731.715 [NAL9602](ERROR): Communications Fault
2017-05-12T04:35:31.805Z,1494563731.805 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:35:31.809Z,1494563731.809 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:35:32.108Z,1494563732.108 [NAL9602](INFO): Powering down
2017-05-12T04:35:32.172Z,1494563732.172 [CBIT](INFO): Critical error at 20170512T043531
2017-05-12T04:36:11.696Z,1494563771.696 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-12T04:36:11.696Z,1494563771.696 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:36:11.696Z,1494563771.696 [BPC1](ERROR): Data Fault
2017-05-12T04:36:11.723Z,1494563771.723 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:36:12.924Z,1494563772.924 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:36:12.924Z,1494563772.924 [BPC1] No Fault, FailCount= 1
2017-05-12T04:36:36.084Z,1494563796.084 [PNI_TCM](ERROR): CRC does not match. Expected:0x59732 got:0x41223
2017-05-12T04:36:40.091Z,1494563800.091 [PNI_TCM](ERROR): CRC does not match. Expected:0x11822 got:0x16786
2017-05-12T04:37:19.041Z,1494563839.041 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:37:19.041Z,1494563839.041 [Rowe_600LCM] Communications Fault, FailCount= 4
2017-05-12T04:37:19.041Z,1494563839.041 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:37:19.185Z,1494563839.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:37:19.213Z,1494563839.213 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:37:19.446Z,1494563839.446 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:37:19.592Z,1494563839.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:37:19.663Z,1494563839.663 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:37:19.985Z,1494563839.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:37:20.376Z,1494563840.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:37:20.788Z,1494563840.788 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:37:21.168Z,1494563841.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:37:21.210Z,1494563841.210 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:37:21.210Z,1494563841.210 [Rowe_600LCM] No Fault, FailCount= 4
2017-05-12T04:37:21.278Z,1494563841.277 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:37:21.278Z,1494563841.278 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:37:21.278Z,1494563841.278 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:37:21.278Z,1494563841.278 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:37:21.580Z,1494563841.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:37:21.973Z,1494563841.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:37:22.376Z,1494563842.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:37:22.777Z,1494563842.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:37:23.191Z,1494563843.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:37:23.579Z,1494563843.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:37:23.975Z,1494563843.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:37:24.380Z,1494563844.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:37:24.785Z,1494563844.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:37:25.211Z,1494563845.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:37:25.401Z,1494563845.401 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:37:25.472Z,1494563845.472 [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-12T04:37:25.474Z,1494563845.474 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:37:25.475Z,1494563845.475 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:37:25.476Z,1494563845.476 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:37:25.669Z,1494563845.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-05-12T04:37:32.012Z,1494563852.012 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:37:32.012Z,1494563852.012 [NAL9602] No Fault, FailCount= 5
2017-05-12T04:37:32.347Z,1494563852.347 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:37:55.499Z,1494563875.499 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:37:55.499Z,1494563875.499 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:37:55.499Z,1494563875.499 [NAL9602](ERROR): Communications Fault
2017-05-12T04:37:55.592Z,1494563875.592 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:37:55.899Z,1494563875.898 [NAL9602](INFO): Powering down
2017-05-12T04:37:56.819Z,1494563876.819 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:37:56.819Z,1494563876.819 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:38:01.107Z,1494563881.107 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:38:01.931Z,1494563881.931 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-05-12T04:33:01.8Z
2017-05-12T04:38:01.931Z,1494563881.931 [Default:CheckIn:Read_GPS] Stopped
2017-05-12T04:38:01.931Z,1494563881.931 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-05-12T04:38:02.346Z,1494563882.346 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-05-12T04:38:17.169Z,1494563897.168 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:38:17.169Z,1494563897.169 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2017-05-12T04:38:17.169Z,1494563897.169 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:38:17.171Z,1494563897.171 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:38:17.171Z,1494563897.171 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2017-05-12T04:38:17.171Z,1494563897.171 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:38:17.183Z,1494563897.183 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:38:17.184Z,1494563897.184 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:38:17.259Z,1494563897.259 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20170512T042852/Courier0004.lzma
2017-05-12T04:38:17.354Z,1494563897.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:38:17.354Z,1494563897.354 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2017-05-12T04:38:17.354Z,1494563897.354 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:38:17.354Z,1494563897.354 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2017-05-12T04:38:17.691Z,1494563897.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:38:17.691Z,1494563897.691 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:38:17.692Z,1494563897.692 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:38:17.692Z,1494563897.692 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:38:17.692Z,1494563897.692 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:38:17.693Z,1494563897.693 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:38:18.016Z,1494563898.016 [DataOverHttps](INFO): Moved sent file to Logs/20170512T042852/Courier0004.lzma.bak
2017-05-12T04:38:18.016Z,1494563898.016 [DataOverHttps](INFO): SBD MOMSN=5023833
2017-05-12T04:38:23.825Z,1494563903.825 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:38:23.826Z,1494563903.826 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:38:23.826Z,1494563903.826 [NAL9602](ERROR): Communications Fault
2017-05-12T04:38:23.902Z,1494563903.902 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:38:24.227Z,1494563904.227 [NAL9602](INFO): Powering down
2017-05-12T04:38:27.795Z,1494563907.795 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20170512T042852/Express0005.lzma
2017-05-12T04:38:27.901Z,1494563907.901 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:38:27.901Z,1494563907.901 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:38:28.516Z,1494563908.516 [DataOverHttps](INFO): Moved sent file to Logs/20170512T042852/Express0005.lzma.bak
2017-05-12T04:38:28.516Z,1494563908.516 [DataOverHttps](INFO): SBD MOMSN=5023837
2017-05-12T04:38:29.200Z,1494563909.200 [Default:CheckIn:Read_Iridium] Stopped
2017-05-12T04:38:29.200Z,1494563909.200 [Default:CheckIn:C.Wait] Running Loop=1
2017-05-12T04:38:29.200Z,1494563909.200 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-05-12T04:38:29.331Z,1494563909.331 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:38:29.375Z,1494563909.375 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-12T04:38:29.375Z,1494563909.375 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:38:29.375Z,1494563909.375 [BPC1](ERROR): Data Fault
2017-05-12T04:38:29.406Z,1494563909.406 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:38:33.306Z,1494563913.306 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:38:33.306Z,1494563913.306 [BPC1] No Fault, FailCount= 1
2017-05-12T04:38:50.043Z,1494563930.043 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:38:50.043Z,1494563930.043 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:38:50.043Z,1494563930.043 [NAL9602](ERROR): Communications Fault
2017-05-12T04:38:50.129Z,1494563930.129 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:38:50.431Z,1494563930.431 [NAL9602](INFO): Powering down
2017-05-12T04:38:51.305Z,1494563931.305 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:38:51.305Z,1494563931.305 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:38:55.671Z,1494563935.671 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:39:00.864Z,1494563940.864 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-12T04:39:00.864Z,1494563940.864 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:39:00.864Z,1494563940.864 [BPC1](ERROR): Data Fault
2017-05-12T04:39:00.921Z,1494563940.921 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:39:02.096Z,1494563942.096 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:39:02.096Z,1494563942.096 [BPC1] No Fault, FailCount= 1
2017-05-12T04:39:16.831Z,1494563956.831 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:39:16.831Z,1494563956.831 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:39:16.831Z,1494563956.831 [NAL9602](ERROR): Communications Fault
2017-05-12T04:39:16.932Z,1494563956.932 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:39:17.243Z,1494563957.243 [NAL9602](INFO): Powering down
2017-05-12T04:39:18.104Z,1494563958.104 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:39:18.105Z,1494563958.105 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:39:22.435Z,1494563962.435 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:39:43.467Z,1494563983.467 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:39:43.467Z,1494563983.467 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:39:43.467Z,1494563983.467 [NAL9602](ERROR): Communications Fault
2017-05-12T04:39:43.551Z,1494563983.551 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:39:43.552Z,1494563983.552 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:39:43.871Z,1494563983.871 [NAL9602](INFO): Powering down
2017-05-12T04:39:43.936Z,1494563983.935 [CBIT](INFO): Critical error at 20170512T043943
2017-05-12T04:39:45.504Z,1494563985.504 [BPC1](ERROR): Failed to parse message.
2017-05-12T04:39:45.504Z,1494563985.504 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-12T04:39:45.504Z,1494563985.504 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:39:45.504Z,1494563985.504 [BPC1](ERROR): Data Fault
2017-05-12T04:39:45.561Z,1494563985.561 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:39:46.793Z,1494563986.793 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:39:46.793Z,1494563986.793 [BPC1] No Fault, FailCount= 1
2017-05-12T04:39:56.465Z,1494563996.465 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:39:56.465Z,1494563996.465 [Rowe_600LCM] Communications Fault, FailCount= 5
2017-05-12T04:39:56.465Z,1494563996.465 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:39:56.716Z,1494563996.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:39:56.736Z,1494563996.736 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:39:56.737Z,1494563996.737 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2017-05-12T04:39:56.869Z,1494563996.869 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:39:56.969Z,1494563996.969 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:39:57.114Z,1494563997.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:39:57.513Z,1494563997.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:39:57.911Z,1494563997.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:39:58.352Z,1494563998.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:39:58.711Z,1494563998.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:39:59.119Z,1494563999.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:39:59.520Z,1494563999.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:39:59.915Z,1494563999.915 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:40:00.321Z,1494564000.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:40:00.696Z,1494564000.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:40:01.124Z,1494564001.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:40:01.499Z,1494564001.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:40:01.898Z,1494564001.898 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:40:02.308Z,1494564002.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:40:02.692Z,1494564002.693 [BPC1](ERROR): Failed to receive battery data
2017-05-12T04:40:02.693Z,1494564002.693 [BPC1] Communications Fault, FailCount= 2
2017-05-12T04:40:02.693Z,1494564002.693 [BPC1](ERROR): Communications Fault
2017-05-12T04:40:02.703Z,1494564002.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:40:02.753Z,1494564002.753 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-12T04:40:03.106Z,1494564003.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:40:03.509Z,1494564003.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-05-12T04:40:03.908Z,1494564003.908 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-05-12T04:40:03.937Z,1494564003.937 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:40:03.937Z,1494564003.937 [BPC1] No Fault, FailCount= 2
2017-05-12T04:40:04.360Z,1494564004.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2017-05-12T04:40:04.744Z,1494564004.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-05-12T04:40:05.112Z,1494564005.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-05-12T04:40:05.157Z,1494564005.157 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:40:05.157Z,1494564005.157 [MassServo] No Fault, FailCount= 5
2017-05-12T04:40:05.516Z,1494564005.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-05-12T04:40:05.916Z,1494564005.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-05-12T04:40:06.323Z,1494564006.323 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-05-12T04:40:06.716Z,1494564006.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-05-12T04:40:07.119Z,1494564007.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-05-12T04:40:07.524Z,1494564007.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-05-12T04:40:07.916Z,1494564007.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-05-12T04:40:08.321Z,1494564008.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-05-12T04:40:08.727Z,1494564008.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-05-12T04:40:08.765Z,1494564008.765 [MassServo](FAULT): Overload Error
2017-05-12T04:40:08.765Z,1494564008.765 [MassServo] Hardware Fault, FailCount= 1
2017-05-12T04:40:08.766Z,1494564008.766 [MassServo](ERROR): Hardware Fault
2017-05-12T04:40:08.854Z,1494564008.854 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:40:09.115Z,1494564009.115 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-05-12T04:40:09.124Z,1494564009.124 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:40:09.124Z,1494564009.124 [MassServo](INFO): Powering down
2017-05-12T04:40:09.520Z,1494564009.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-05-12T04:40:09.936Z,1494564009.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-05-12T04:40:09.968Z,1494564009.968 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:40:10.086Z,1494564010.086 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:40:10.091Z,1494564010.092 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:40:10.091Z,1494564010.092 [MassServo] No Fault, FailCount= 1
2017-05-12T04:40:10.300Z,1494564010.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-05-12T04:40:10.744Z,1494564010.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-05-12T04:40:11.135Z,1494564011.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-05-12T04:40:11.548Z,1494564011.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-05-12T04:40:11.927Z,1494564011.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-05-12T04:40:12.339Z,1494564012.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-05-12T04:40:12.704Z,1494564012.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-05-12T04:40:13.112Z,1494564013.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-05-12T04:40:13.519Z,1494564013.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-05-12T04:40:13.911Z,1494564013.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-05-12T04:40:14.319Z,1494564014.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-05-12T04:40:14.362Z,1494564014.362 [MassServo](FAULT): Overload Error
2017-05-12T04:40:14.362Z,1494564014.362 [MassServo] Hardware Fault, FailCount= 2
2017-05-12T04:40:14.362Z,1494564014.362 [MassServo](ERROR): Hardware Fault
2017-05-12T04:40:14.367Z,1494564014.367 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:40:14.719Z,1494564014.719 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-05-12T04:40:14.729Z,1494564014.728 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:40:14.729Z,1494564014.729 [MassServo](INFO): Powering down
2017-05-12T04:40:15.119Z,1494564015.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-05-12T04:40:15.516Z,1494564015.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-05-12T04:40:15.534Z,1494564015.534 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:40:15.654Z,1494564015.654 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:40:15.659Z,1494564015.659 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:40:15.660Z,1494564015.659 [MassServo] No Fault, FailCount= 2
2017-05-12T04:40:15.924Z,1494564015.924 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-05-12T04:40:16.300Z,1494564016.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2017-05-12T04:40:19.545Z,1494564019.546 [MassServo](FAULT): Overload Error
2017-05-12T04:40:19.546Z,1494564019.546 [MassServo] Hardware Fault, FailCount= 3
2017-05-12T04:40:19.546Z,1494564019.546 [MassServo](ERROR): Hardware Fault
2017-05-12T04:40:19.622Z,1494564019.622 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:40:19.926Z,1494564019.926 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:40:19.926Z,1494564019.926 [MassServo](INFO): Powering down
2017-05-12T04:40:20.732Z,1494564020.732 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:40:20.850Z,1494564020.850 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:40:20.856Z,1494564020.856 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:40:20.856Z,1494564020.856 [MassServo] No Fault, FailCount= 3
2017-05-12T04:40:25.178Z,1494564025.178 [MassServo](FAULT): Overload Error
2017-05-12T04:40:25.178Z,1494564025.178 [MassServo] Hardware Fault, FailCount= 4
2017-05-12T04:40:25.178Z,1494564025.178 [MassServo](ERROR): Hardware Fault
2017-05-12T04:40:25.183Z,1494564025.183 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:40:25.545Z,1494564025.545 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:40:25.545Z,1494564025.546 [MassServo](INFO): Powering down
2017-05-12T04:40:26.340Z,1494564026.340 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:40:26.458Z,1494564026.458 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:40:26.464Z,1494564026.464 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:40:26.464Z,1494564026.464 [MassServo] No Fault, FailCount= 4
2017-05-12T04:40:33.201Z,1494564033.201 [MassServo](FAULT): Overload Error
2017-05-12T04:40:33.202Z,1494564033.202 [MassServo] Hardware Fault, FailCount= 5
2017-05-12T04:40:33.202Z,1494564033.202 [MassServo](ERROR): Hardware Fault
2017-05-12T04:40:33.278Z,1494564033.279 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:40:33.279Z,1494564033.279 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-12T04:40:33.360Z,1494564033.360 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:40:33.360Z,1494564033.360 [MassServo](INFO): Powering down
2017-05-12T04:40:33.368Z,1494564033.368 [CBIT](INFO): Critical error at 20170512T044033
2017-05-12T04:40:34.168Z,1494564034.168 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:40:34.286Z,1494564034.286 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:40:34.931Z,1494564034.931 [PNI_TCM](ERROR): CRC does not match. Expected:0x42787 got:0x1857
2017-05-12T04:40:39.716Z,1494564039.716 [PNI_TCM](ERROR): CRC does not match. Expected:0x60617 got:0x27673
2017-05-12T04:40:41.315Z,1494564041.315 [PNI_TCM](ERROR): CRC does not match. Expected:0x50933 got:0x65309
2017-05-12T04:41:17.750Z,1494564077.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:41:17.750Z,1494564077.750 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2017-05-12T04:41:17.750Z,1494564077.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:41:17.752Z,1494564077.752 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:41:17.752Z,1494564077.752 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2017-05-12T04:41:17.752Z,1494564077.752 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:41:17.764Z,1494564077.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:41:17.765Z,1494564077.765 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:41:18.159Z,1494564078.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:41:18.159Z,1494564078.159 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2017-05-12T04:41:18.160Z,1494564078.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:41:18.160Z,1494564078.160 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2017-05-12T04:41:18.543Z,1494564078.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:41:18.543Z,1494564078.543 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:41:18.543Z,1494564078.543 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:41:18.544Z,1494564078.544 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:41:18.544Z,1494564078.544 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:41:18.544Z,1494564078.544 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:41:18.950Z,1494564078.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:41:19.352Z,1494564079.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:41:19.751Z,1494564079.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:41:20.152Z,1494564080.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:41:20.552Z,1494564080.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:41:20.933Z,1494564080.933 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:41:21.333Z,1494564081.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:41:21.733Z,1494564081.733 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:41:22.137Z,1494564082.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:41:22.532Z,1494564082.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:41:22.932Z,1494564082.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:41:23.330Z,1494564083.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:41:23.733Z,1494564083.733 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:41:24.132Z,1494564084.132 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:41:24.548Z,1494564084.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:41:24.935Z,1494564084.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-05-12T04:41:25.344Z,1494564085.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-05-12T04:41:25.731Z,1494564085.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-05-12T04:41:26.144Z,1494564086.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-05-12T04:41:26.534Z,1494564086.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-05-12T04:41:26.931Z,1494564086.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-05-12T04:41:27.333Z,1494564087.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-05-12T04:41:27.726Z,1494564087.726 [BPC1](ERROR): Failed to parse message.
2017-05-12T04:41:27.726Z,1494564087.726 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-12T04:41:27.726Z,1494564087.727 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:41:27.727Z,1494564087.727 [BPC1](ERROR): Data Fault
2017-05-12T04:41:27.735Z,1494564087.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-05-12T04:41:27.750Z,1494564087.750 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:41:28.131Z,1494564088.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-05-12T04:41:28.531Z,1494564088.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-05-12T04:41:28.936Z,1494564088.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-05-12T04:41:28.950Z,1494564088.950 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:41:28.950Z,1494564088.950 [BPC1] No Fault, FailCount= 1
2017-05-12T04:41:29.380Z,1494564089.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-05-12T04:41:29.744Z,1494564089.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-05-12T04:41:30.157Z,1494564090.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-05-12T04:41:33.397Z,1494564093.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-05-12T04:41:33.539Z,1494564093.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2017-05-12T04:41:33.896Z,1494564093.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2017-05-12T04:41:34.306Z,1494564094.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2017-05-12T04:41:34.696Z,1494564094.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-05-12T04:41:35.096Z,1494564095.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2017-05-12T04:41:35.501Z,1494564095.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2017-05-12T04:41:35.896Z,1494564095.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2017-05-12T04:41:36.297Z,1494564096.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-05-12T04:41:36.699Z,1494564096.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2017-05-12T04:41:37.099Z,1494564097.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2017-05-12T04:41:37.497Z,1494564097.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2017-05-12T04:41:43.570Z,1494564103.570 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:41:43.570Z,1494564103.570 [NAL9602] No Fault, FailCount= 5
2017-05-12T04:41:43.863Z,1494564103.863 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:42:05.063Z,1494564125.063 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:42:05.063Z,1494564125.063 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:42:05.063Z,1494564125.063 [NAL9602](ERROR): Communications Fault
2017-05-12T04:42:05.144Z,1494564125.144 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:42:05.455Z,1494564125.455 [NAL9602](INFO): Powering down
2017-05-12T04:42:06.321Z,1494564126.321 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:42:06.321Z,1494564126.321 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:42:10.667Z,1494564130.667 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:42:34.964Z,1494564154.964 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:42:34.964Z,1494564154.964 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:42:34.964Z,1494564154.964 [NAL9602](ERROR): Communications Fault
2017-05-12T04:42:35.108Z,1494564155.108 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:42:35.155Z,1494564155.155 [NAL9602](INFO): Powering down
2017-05-12T04:42:35.992Z,1494564155.992 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:42:35.992Z,1494564155.992 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:42:40.323Z,1494564160.323 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:43:01.123Z,1494564181.123 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:43:01.123Z,1494564181.123 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:43:01.123Z,1494564181.123 [NAL9602](ERROR): Communications Fault
2017-05-12T04:43:01.205Z,1494564181.205 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:43:01.523Z,1494564181.523 [NAL9602](INFO): Powering down
2017-05-12T04:43:02.377Z,1494564182.378 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:43:02.378Z,1494564182.378 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:43:06.723Z,1494564186.723 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:43:27.928Z,1494564207.928 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:43:27.928Z,1494564207.928 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:43:27.928Z,1494564207.928 [NAL9602](ERROR): Communications Fault
2017-05-12T04:43:27.999Z,1494564207.999 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:43:28.335Z,1494564208.335 [NAL9602](INFO): Powering down
2017-05-12T04:43:29.205Z,1494564209.205 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:43:29.205Z,1494564209.205 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:43:29.567Z,1494564209.567 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-05-12T04:43:29.567Z,1494564209.567 [Default:CheckIn:C.Wait] Stopped
2017-05-12T04:43:29.567Z,1494564209.567 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-05-12T04:43:29.567Z,1494564209.568 [Default:CheckIn:D] Running Loop=1
2017-05-12T04:43:29.968Z,1494564209.968 [Default:CheckIn:D] Stopped
2017-05-12T04:43:29.968Z,1494564209.968 [Default:CheckIn:E] Running Loop=1
2017-05-12T04:43:30.368Z,1494564210.368 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.853861 min 
2017-05-12T04:43:30.371Z,1494564210.371 [Default:CheckIn:E] Stopped
2017-05-12T04:43:30.371Z,1494564210.371 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-05-12T04:43:30.371Z,1494564210.371 [Default:CheckIn] Stopped
2017-05-12T04:43:30.371Z,1494564210.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-05-12T04:43:30.371Z,1494564210.371 [Default:CheckIn](INFO): Running loop #2
2017-05-12T04:43:30.371Z,1494564210.371 [Default:CheckIn] Running Loop=2
2017-05-12T04:43:30.371Z,1494564210.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-05-12T04:43:30.372Z,1494564210.372 [Default:CheckIn:Read_GPS] Running Loop=1
2017-05-12T04:43:33.681Z,1494564213.681 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:43:43.426Z,1494564223.426 [PNI_TCM](ERROR): CRC does not match. Expected:0x47873 got:0x6335
2017-05-12T04:43:44.235Z,1494564224.235 [PNI_TCM](ERROR): CRC does not match. Expected:0x4147 got:0x31239
2017-05-12T04:43:45.033Z,1494564225.033 [PNI_TCM](ERROR): CRC does not match. Expected:0x35524 got:0x22809
2017-05-12T04:43:48.220Z,1494564228.220 [PNI_TCM](ERROR): CRC does not match. Expected:0x4834 got:0x4461
2017-05-12T04:43:49.820Z,1494564229.820 [PNI_TCM](ERROR): CRC does not match. Expected:0x15467 got:0x60679
2017-05-12T04:43:51.424Z,1494564231.424 [PNI_TCM](ERROR): CRC does not match. Expected:0x11176 got:0x49144
2017-05-12T04:43:54.587Z,1494564234.587 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:43:54.587Z,1494564234.587 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:43:54.587Z,1494564234.587 [NAL9602](ERROR): Communications Fault
2017-05-12T04:43:54.674Z,1494564234.674 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:43:54.675Z,1494564234.675 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:43:54.987Z,1494564234.987 [NAL9602](INFO): Powering down
2017-05-12T04:43:55.075Z,1494564235.075 [CBIT](INFO): Critical error at 20170512T044354
2017-05-12T04:43:56.212Z,1494564236.212 [PNI_TCM](ERROR): CRC does not match. Expected:0x18625 got:0x16704
2017-05-12T04:44:13.040Z,1494564253.039 [CBIT](INFO): Clearing failed state for component DropWeight
2017-05-12T04:44:13.040Z,1494564253.039 [DropWeight] No Fault, FailCount= 1
2017-05-12T04:44:18.639Z,1494564258.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:44:18.639Z,1494564258.639 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2017-05-12T04:44:18.639Z,1494564258.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:44:18.641Z,1494564258.641 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:44:18.641Z,1494564258.641 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2017-05-12T04:44:18.641Z,1494564258.641 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:44:18.653Z,1494564258.653 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:44:18.654Z,1494564258.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:44:19.052Z,1494564259.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:44:19.052Z,1494564259.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2017-05-12T04:44:19.052Z,1494564259.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:44:19.053Z,1494564259.053 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2017-05-12T04:44:19.454Z,1494564259.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:44:19.455Z,1494564259.455 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:44:19.455Z,1494564259.455 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:44:19.456Z,1494564259.456 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:44:19.456Z,1494564259.456 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:44:19.456Z,1494564259.456 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:44:19.836Z,1494564259.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:44:20.248Z,1494564260.248 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:44:20.668Z,1494564260.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:44:21.042Z,1494564261.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:44:21.448Z,1494564261.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:44:21.818Z,1494564261.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:44:22.223Z,1494564262.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:44:22.619Z,1494564262.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:44:23.026Z,1494564263.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:44:23.428Z,1494564263.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:44:23.819Z,1494564263.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:44:24.228Z,1494564264.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:44:24.634Z,1494564264.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:44:25.026Z,1494564265.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:44:25.432Z,1494564265.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:44:25.831Z,1494564265.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-05-12T04:44:26.229Z,1494564266.229 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-05-12T04:44:26.632Z,1494564266.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-05-12T04:44:27.029Z,1494564267.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-05-12T04:44:27.427Z,1494564267.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-05-12T04:44:27.834Z,1494564267.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-05-12T04:44:28.239Z,1494564268.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-05-12T04:44:28.635Z,1494564268.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-05-12T04:44:29.038Z,1494564269.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-05-12T04:44:29.436Z,1494564269.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-05-12T04:44:29.835Z,1494564269.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-05-12T04:44:30.244Z,1494564270.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-05-12T04:44:30.640Z,1494564270.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-05-12T04:44:34.018Z,1494564274.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2017-05-12T04:44:34.145Z,1494564274.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2017-05-12T04:44:34.487Z,1494564274.487 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2017-05-12T04:44:34.900Z,1494564274.900 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2017-05-12T04:44:35.332Z,1494564275.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2017-05-12T04:44:35.695Z,1494564275.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2017-05-12T04:44:36.108Z,1494564276.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2017-05-12T04:44:36.536Z,1494564276.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2017-05-12T04:44:36.894Z,1494564276.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.3 s old, using for 20.0 s.
2017-05-12T04:44:37.295Z,1494564277.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2017-05-12T04:44:37.691Z,1494564277.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2017-05-12T04:44:38.095Z,1494564278.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2017-05-12T04:44:38.497Z,1494564278.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2017-05-12T04:45:14.488Z,1494564314.488 [PNI_TCM](ERROR): CRC does not match. Expected:0x29059 got:0x9759
2017-05-12T04:45:22.478Z,1494564322.478 [PNI_TCM](ERROR): CRC does not match. Expected:0x64848 got:0x1857
2017-05-12T04:45:24.076Z,1494564324.076 [PNI_TCM](ERROR): CRC does not match. Expected:0x11280 got:0x90
2017-05-12T04:45:34.151Z,1494564334.151 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:45:34.151Z,1494564334.151 [MassServo] No Fault, FailCount= 5
2017-05-12T04:45:37.445Z,1494564337.445 [MassServo](FAULT): Overload Error
2017-05-12T04:45:37.446Z,1494564337.446 [MassServo] Hardware Fault, FailCount= 1
2017-05-12T04:45:37.446Z,1494564337.446 [MassServo](ERROR): Hardware Fault
2017-05-12T04:45:37.451Z,1494564337.451 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:45:37.826Z,1494564337.826 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:45:37.826Z,1494564337.826 [MassServo](INFO): Powering down
2017-05-12T04:45:38.620Z,1494564338.620 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:45:38.738Z,1494564338.738 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:45:38.743Z,1494564338.743 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:45:38.743Z,1494564338.743 [MassServo] No Fault, FailCount= 1
2017-05-12T04:45:42.657Z,1494564342.657 [MassServo](FAULT): Overload Error
2017-05-12T04:45:42.657Z,1494564342.657 [MassServo] Hardware Fault, FailCount= 2
2017-05-12T04:45:42.658Z,1494564342.658 [MassServo](ERROR): Hardware Fault
2017-05-12T04:45:42.750Z,1494564342.750 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:45:43.022Z,1494564343.022 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:45:43.022Z,1494564343.022 [MassServo](INFO): Powering down
2017-05-12T04:45:43.822Z,1494564343.822 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:45:43.938Z,1494564343.938 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:45:43.944Z,1494564343.944 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:45:43.944Z,1494564343.944 [MassServo] No Fault, FailCount= 2
2017-05-12T04:45:47.853Z,1494564347.853 [MassServo](FAULT): Overload Error
2017-05-12T04:45:47.854Z,1494564347.853 [MassServo] Hardware Fault, FailCount= 3
2017-05-12T04:45:47.854Z,1494564347.854 [MassServo](ERROR): Hardware Fault
2017-05-12T04:45:47.859Z,1494564347.859 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:45:48.290Z,1494564348.290 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:45:48.290Z,1494564348.290 [MassServo](INFO): Powering down
2017-05-12T04:45:49.056Z,1494564349.056 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:45:49.174Z,1494564349.174 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:45:49.179Z,1494564349.179 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:45:49.179Z,1494564349.180 [MassServo] No Fault, FailCount= 3
2017-05-12T04:45:53.065Z,1494564353.066 [MassServo](FAULT): Overload Error
2017-05-12T04:45:53.066Z,1494564353.066 [MassServo] Hardware Fault, FailCount= 4
2017-05-12T04:45:53.066Z,1494564353.066 [MassServo](ERROR): Hardware Fault
2017-05-12T04:45:53.142Z,1494564353.142 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:45:53.443Z,1494564353.443 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:45:53.443Z,1494564353.443 [MassServo](INFO): Powering down
2017-05-12T04:45:54.248Z,1494564354.247 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:45:54.366Z,1494564354.366 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:45:54.371Z,1494564354.371 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:45:54.371Z,1494564354.371 [MassServo] No Fault, FailCount= 4
2017-05-12T04:45:54.626Z,1494564354.626 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:45:54.626Z,1494564354.626 [NAL9602] No Fault, FailCount= 5
2017-05-12T04:45:54.963Z,1494564354.963 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:45:58.662Z,1494564358.662 [MassServo](FAULT): Overload Error
2017-05-12T04:45:58.662Z,1494564358.662 [MassServo] Hardware Fault, FailCount= 5
2017-05-12T04:45:58.662Z,1494564358.662 [MassServo](ERROR): Hardware Fault
2017-05-12T04:45:58.667Z,1494564358.667 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:45:58.667Z,1494564358.667 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-12T04:45:59.043Z,1494564359.043 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:45:59.043Z,1494564359.043 [MassServo](INFO): Powering down
2017-05-12T04:45:59.051Z,1494564359.051 [CBIT](INFO): Critical error at 20170512T044558
2017-05-12T04:45:59.815Z,1494564359.815 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:45:59.930Z,1494564359.930 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:46:15.763Z,1494564375.763 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:46:15.763Z,1494564375.763 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:46:15.763Z,1494564375.763 [NAL9602](ERROR): Communications Fault
2017-05-12T04:46:15.834Z,1494564375.834 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:46:16.163Z,1494564376.163 [NAL9602](INFO): Powering down
2017-05-12T04:46:17.044Z,1494564377.044 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:46:17.044Z,1494564377.044 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:46:21.363Z,1494564381.363 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:46:44.799Z,1494564404.799 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:46:44.799Z,1494564404.799 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:46:44.799Z,1494564404.799 [NAL9602](ERROR): Communications Fault
2017-05-12T04:46:44.880Z,1494564404.880 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:46:45.199Z,1494564405.199 [NAL9602](INFO): Powering down
2017-05-12T04:46:46.072Z,1494564406.072 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:46:46.072Z,1494564406.072 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:46:50.399Z,1494564410.399 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:47:11.199Z,1494564431.199 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:47:11.199Z,1494564431.199 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:47:11.199Z,1494564431.200 [NAL9602](ERROR): Communications Fault
2017-05-12T04:47:11.292Z,1494564431.292 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:47:11.599Z,1494564431.599 [NAL9602](INFO): Powering down
2017-05-12T04:47:12.478Z,1494564432.478 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:47:12.478Z,1494564432.478 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:47:16.813Z,1494564436.813 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:47:17.234Z,1494564437.234 [BPC1](ERROR): Failed to parse bank B battery data
2017-05-12T04:47:17.234Z,1494564437.234 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:47:17.234Z,1494564437.234 [BPC1](ERROR): Data Fault
2017-05-12T04:47:17.255Z,1494564437.255 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:47:18.461Z,1494564438.461 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:47:18.461Z,1494564438.461 [BPC1] No Fault, FailCount= 1
2017-05-12T04:47:19.643Z,1494564439.643 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:47:19.643Z,1494564439.643 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2017-05-12T04:47:19.643Z,1494564439.643 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:47:19.645Z,1494564439.645 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:47:19.645Z,1494564439.645 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2017-05-12T04:47:19.645Z,1494564439.645 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:47:19.657Z,1494564439.657 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:47:19.658Z,1494564439.658 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:47:20.057Z,1494564440.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:47:20.057Z,1494564440.057 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2017-05-12T04:47:20.058Z,1494564440.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:47:20.058Z,1494564440.058 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2017-05-12T04:47:20.454Z,1494564440.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:47:20.455Z,1494564440.455 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:47:20.455Z,1494564440.455 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:47:20.455Z,1494564440.455 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:47:20.456Z,1494564440.456 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:47:20.456Z,1494564440.456 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:47:20.851Z,1494564440.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:47:21.251Z,1494564441.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:47:21.656Z,1494564441.656 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:47:22.048Z,1494564442.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:47:22.455Z,1494564442.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:47:22.854Z,1494564442.854 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:47:23.228Z,1494564443.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:47:23.656Z,1494564443.656 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:47:24.066Z,1494564444.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:47:24.428Z,1494564444.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:47:24.866Z,1494564444.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:47:25.234Z,1494564445.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:47:25.643Z,1494564445.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:47:26.038Z,1494564446.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:47:26.496Z,1494564446.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-05-12T04:47:26.872Z,1494564446.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-05-12T04:47:27.261Z,1494564447.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-05-12T04:47:27.664Z,1494564447.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-05-12T04:47:28.135Z,1494564448.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2017-05-12T04:47:28.488Z,1494564448.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-05-12T04:47:28.879Z,1494564448.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-05-12T04:47:29.272Z,1494564449.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-05-12T04:47:29.680Z,1494564449.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-05-12T04:47:30.076Z,1494564450.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-05-12T04:47:30.449Z,1494564450.449 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-12T04:47:30.449Z,1494564450.449 [BPC1] Data Fault, FailCount= 2
2017-05-12T04:47:30.449Z,1494564450.449 [BPC1](ERROR): Data Fault
2017-05-12T04:47:30.499Z,1494564450.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2017-05-12T04:47:30.513Z,1494564450.513 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:47:30.888Z,1494564450.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-05-12T04:47:31.256Z,1494564451.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-05-12T04:47:36.167Z,1494564456.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-05-12T04:47:36.180Z,1494564456.180 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:47:36.180Z,1494564456.180 [BPC1] No Fault, FailCount= 2
2017-05-12T04:47:36.361Z,1494564456.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2017-05-12T04:47:36.701Z,1494564456.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2017-05-12T04:47:37.072Z,1494564457.072 [PNI_TCM](ERROR): CRC does not match. Expected:0x45631 got:0x16704
2017-05-12T04:47:37.102Z,1494564457.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2017-05-12T04:47:37.476Z,1494564457.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2017-05-12T04:47:37.823Z,1494564457.823 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:47:37.823Z,1494564457.823 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:47:37.823Z,1494564457.823 [NAL9602](ERROR): Communications Fault
2017-05-12T04:47:37.880Z,1494564457.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.2 s old, using for 20.0 s.
2017-05-12T04:47:37.917Z,1494564457.917 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:47:38.223Z,1494564458.223 [NAL9602](INFO): Powering down
2017-05-12T04:47:38.284Z,1494564458.284 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2017-05-12T04:47:38.671Z,1494564458.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2017-05-12T04:47:39.066Z,1494564459.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2017-05-12T04:47:39.079Z,1494564459.079 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:47:39.079Z,1494564459.079 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:47:39.479Z,1494564459.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2017-05-12T04:47:41.467Z,1494564461.467 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2017-05-12T04:47:43.067Z,1494564463.067 [PNI_TCM](ERROR): CRC does not match. Expected:0x62102 got:0x56279
2017-05-12T04:47:43.423Z,1494564463.423 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:48:04.227Z,1494564484.227 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:48:04.227Z,1494564484.227 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:48:04.227Z,1494564484.227 [NAL9602](ERROR): Communications Fault
2017-05-12T04:48:04.328Z,1494564484.328 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:48:04.328Z,1494564484.328 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:48:04.631Z,1494564484.631 [NAL9602](INFO): Powering down
2017-05-12T04:48:04.707Z,1494564484.707 [CBIT](INFO): Critical error at 20170512T044804
2017-05-12T04:48:30.678Z,1494564510.678 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-05-12T04:43:30.4Z
2017-05-12T04:48:30.678Z,1494564510.678 [Default:CheckIn:Read_GPS] Stopped
2017-05-12T04:48:30.678Z,1494564510.678 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-05-12T04:48:34.703Z,1494564514.703 [BPC1](ERROR): Failed to receive battery data
2017-05-12T04:48:34.703Z,1494564514.703 [BPC1] Communications Fault, FailCount= 1
2017-05-12T04:48:34.703Z,1494564514.703 [BPC1](ERROR): Communications Fault
2017-05-12T04:48:34.760Z,1494564514.760 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-12T04:48:34.798Z,1494564514.798 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20170512T042852/Courier0008.lzma
2017-05-12T04:48:35.576Z,1494564515.576 [DataOverHttps](INFO): Moved sent file to Logs/20170512T042852/Courier0008.lzma.bak
2017-05-12T04:48:35.576Z,1494564515.576 [DataOverHttps](INFO): SBD MOMSN=5023848
2017-05-12T04:48:35.667Z,1494564515.667 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:48:35.667Z,1494564515.667 [BPC1] No Fault, FailCount= 1
2017-05-12T04:48:44.700Z,1494564524.700 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20170512T042852/Express0009.lzma
2017-05-12T04:48:45.436Z,1494564525.436 [DataOverHttps](INFO): Moved sent file to Logs/20170512T042852/Express0009.lzma.bak
2017-05-12T04:48:45.436Z,1494564525.436 [DataOverHttps](INFO): SBD MOMSN=5023855
2017-05-12T04:48:46.120Z,1494564526.120 [Default:CheckIn:Read_Iridium] Stopped
2017-05-12T04:48:46.120Z,1494564526.120 [Default:CheckIn:C.Wait] Running Loop=1
2017-05-12T04:48:46.120Z,1494564526.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-05-12T04:49:32.033Z,1494564572.033 [BPC1](ERROR): Failed to parse message.
2017-05-12T04:49:32.033Z,1494564572.033 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-12T04:49:32.033Z,1494564572.033 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:49:32.034Z,1494564572.033 [BPC1](ERROR): Data Fault
2017-05-12T04:49:32.056Z,1494564572.056 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:49:33.253Z,1494564573.253 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:49:33.253Z,1494564573.253 [BPC1] No Fault, FailCount= 1
2017-05-12T04:49:50.124Z,1494564590.124 [BPC1](ERROR): Failed to receive battery data
2017-05-12T04:49:50.124Z,1494564590.124 [BPC1] Communications Fault, FailCount= 2
2017-05-12T04:49:50.124Z,1494564590.124 [BPC1](ERROR): Communications Fault
2017-05-12T04:49:50.146Z,1494564590.146 [CBIT](ERROR): Communications Fault in component: BPC1
2017-05-12T04:49:51.048Z,1494564591.048 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:49:51.048Z,1494564591.048 [BPC1] No Fault, FailCount= 2
2017-05-12T04:49:56.613Z,1494564596.613 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:49:56.613Z,1494564596.613 [Rowe_600LCM] No Fault, FailCount= 5
2017-05-12T04:49:56.718Z,1494564596.718 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:49:56.718Z,1494564596.718 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:49:56.719Z,1494564596.718 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:49:56.719Z,1494564596.719 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:50:00.854Z,1494564600.854 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:50:00.917Z,1494564600.917 [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-12T04:50:00.918Z,1494564600.918 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:50:00.920Z,1494564600.920 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:50:00.921Z,1494564600.921 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:50:04.246Z,1494564604.246 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:50:04.247Z,1494564604.247 [NAL9602] No Fault, FailCount= 5
2017-05-12T04:50:04.559Z,1494564604.559 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:50:20.612Z,1494564620.612 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:50:20.613Z,1494564620.612 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2017-05-12T04:50:20.613Z,1494564620.613 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:50:20.615Z,1494564620.615 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:50:20.615Z,1494564620.615 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2017-05-12T04:50:20.615Z,1494564620.615 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:50:20.698Z,1494564620.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:50:20.698Z,1494564620.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:50:21.035Z,1494564621.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:50:21.035Z,1494564621.035 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2017-05-12T04:50:21.036Z,1494564621.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:50:21.036Z,1494564621.036 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2017-05-12T04:50:21.414Z,1494564621.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:50:21.415Z,1494564621.415 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:50:21.415Z,1494564621.415 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:50:21.416Z,1494564621.416 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:50:21.416Z,1494564621.416 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:50:21.417Z,1494564621.417 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:50:25.359Z,1494564625.359 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:50:25.359Z,1494564625.359 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:50:25.359Z,1494564625.359 [NAL9602](ERROR): Communications Fault
2017-05-12T04:50:25.453Z,1494564625.453 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:50:25.759Z,1494564625.759 [NAL9602](INFO): Powering down
2017-05-12T04:50:26.637Z,1494564626.637 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:50:26.637Z,1494564626.638 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:50:30.959Z,1494564630.959 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:50:51.637Z,1494564651.637 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:50:51.637Z,1494564651.638 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:50:51.638Z,1494564651.638 [NAL9602](ERROR): Communications Fault
2017-05-12T04:50:51.714Z,1494564651.714 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:50:52.042Z,1494564652.042 [NAL9602](INFO): Powering down
2017-05-12T04:50:52.909Z,1494564652.909 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:50:52.909Z,1494564652.909 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:50:57.223Z,1494564657.223 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:50:58.891Z,1494564658.891 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:50:58.891Z,1494564658.891 [MassServo] No Fault, FailCount= 5
2017-05-12T04:51:02.541Z,1494564662.541 [MassServo](FAULT): Overload Error
2017-05-12T04:51:02.541Z,1494564662.542 [MassServo] Hardware Fault, FailCount= 1
2017-05-12T04:51:02.542Z,1494564662.542 [MassServo](ERROR): Hardware Fault
2017-05-12T04:51:02.621Z,1494564662.621 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:51:02.889Z,1494564662.889 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:51:02.889Z,1494564662.889 [MassServo](INFO): Powering down
2017-05-12T04:51:03.693Z,1494564663.693 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:51:03.697Z,1494564663.697 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:51:03.706Z,1494564663.706 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:51:03.706Z,1494564663.706 [MassServo] No Fault, FailCount= 1
2017-05-12T04:51:07.721Z,1494564667.721 [MassServo](FAULT): Overload Error
2017-05-12T04:51:07.721Z,1494564667.721 [MassServo] Hardware Fault, FailCount= 2
2017-05-12T04:51:07.722Z,1494564667.722 [MassServo](ERROR): Hardware Fault
2017-05-12T04:51:07.726Z,1494564667.726 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:51:08.096Z,1494564668.096 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:51:08.096Z,1494564668.096 [MassServo](INFO): Powering down
2017-05-12T04:51:08.893Z,1494564668.893 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:51:08.894Z,1494564668.894 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:51:08.902Z,1494564668.902 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:51:08.902Z,1494564668.902 [MassServo] No Fault, FailCount= 2
2017-05-12T04:51:12.894Z,1494564672.894 [MassServo](FAULT): Overload Error
2017-05-12T04:51:12.894Z,1494564672.894 [MassServo] Hardware Fault, FailCount= 3
2017-05-12T04:51:12.894Z,1494564672.894 [MassServo](ERROR): Hardware Fault
2017-05-12T04:51:12.982Z,1494564672.982 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:51:13.278Z,1494564673.278 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:51:13.278Z,1494564673.279 [MassServo](INFO): Powering down
2017-05-12T04:51:14.086Z,1494564674.086 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:51:14.086Z,1494564674.086 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:51:14.091Z,1494564674.091 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:51:14.091Z,1494564674.091 [MassServo] No Fault, FailCount= 3
2017-05-12T04:51:18.431Z,1494564678.431 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:51:18.431Z,1494564678.431 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:51:18.431Z,1494564678.431 [NAL9602](ERROR): Communications Fault
2017-05-12T04:51:18.558Z,1494564678.558 [MassServo](FAULT): Overload Error
2017-05-12T04:51:18.558Z,1494564678.558 [MassServo] Hardware Fault, FailCount= 4
2017-05-12T04:51:18.558Z,1494564678.558 [MassServo](ERROR): Hardware Fault
2017-05-12T04:51:18.574Z,1494564678.574 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:51:18.575Z,1494564678.575 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:51:18.831Z,1494564678.831 [NAL9602](INFO): Powering down
2017-05-12T04:51:18.895Z,1494564678.895 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:51:18.895Z,1494564678.895 [MassServo](INFO): Powering down
2017-05-12T04:51:19.674Z,1494564679.674 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:51:19.675Z,1494564679.675 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:51:19.679Z,1494564679.679 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:51:19.679Z,1494564679.680 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:51:19.680Z,1494564679.680 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:51:19.680Z,1494564679.680 [MassServo] No Fault, FailCount= 4
2017-05-12T04:51:23.777Z,1494564683.777 [MassServo](FAULT): Overload Error
2017-05-12T04:51:23.778Z,1494564683.778 [MassServo] Hardware Fault, FailCount= 5
2017-05-12T04:51:23.778Z,1494564683.778 [MassServo](ERROR): Hardware Fault
2017-05-12T04:51:23.854Z,1494564683.854 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:51:23.854Z,1494564683.855 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-12T04:51:24.021Z,1494564684.021 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:51:24.091Z,1494564684.092 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:51:24.092Z,1494564684.092 [MassServo](INFO): Powering down
2017-05-12T04:51:24.099Z,1494564684.099 [CBIT](INFO): Critical error at 20170512T045123
2017-05-12T04:51:24.939Z,1494564684.939 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:51:24.940Z,1494564684.940 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:51:44.827Z,1494564704.827 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:51:44.827Z,1494564704.827 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:51:44.827Z,1494564704.827 [NAL9602](ERROR): Communications Fault
2017-05-12T04:51:44.927Z,1494564704.927 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:51:45.227Z,1494564705.227 [NAL9602](INFO): Powering down
2017-05-12T04:51:46.098Z,1494564706.098 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:51:46.098Z,1494564706.098 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:51:50.761Z,1494564710.761 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:51:57.086Z,1494564717.086 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:51:57.087Z,1494564717.087 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-05-12T04:51:57.087Z,1494564717.087 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:51:57.348Z,1494564717.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:51:57.389Z,1494564717.389 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:51:57.489Z,1494564717.490 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:51:57.590Z,1494564717.590 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:51:57.750Z,1494564717.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:51:58.144Z,1494564718.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:51:58.548Z,1494564718.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:51:58.945Z,1494564718.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:51:58.958Z,1494564718.958 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:51:58.958Z,1494564718.958 [Rowe_600LCM] No Fault, FailCount= 1
2017-05-12T04:51:59.205Z,1494564719.205 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:51:59.206Z,1494564719.206 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:51:59.206Z,1494564719.206 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:51:59.206Z,1494564719.206 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:51:59.345Z,1494564719.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:51:59.744Z,1494564719.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:52:00.152Z,1494564720.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:52:00.547Z,1494564720.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:52:00.946Z,1494564720.946 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:52:01.357Z,1494564721.357 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:52:01.747Z,1494564721.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:52:02.146Z,1494564722.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:52:02.546Z,1494564722.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:52:02.968Z,1494564722.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:52:03.405Z,1494564723.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-05-12T04:52:03.412Z,1494564723.412 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:52:03.535Z,1494564723.535 [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-12T04:52:03.537Z,1494564723.537 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:52:03.538Z,1494564723.538 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:52:03.539Z,1494564723.539 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:52:03.846Z,1494564723.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-05-12T04:52:11.712Z,1494564731.712 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:52:11.712Z,1494564731.712 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:52:11.712Z,1494564731.712 [NAL9602](ERROR): Communications Fault
2017-05-12T04:52:11.795Z,1494564731.795 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:52:11.795Z,1494564731.795 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:52:12.103Z,1494564732.103 [NAL9602](INFO): Powering down
2017-05-12T04:52:12.187Z,1494564732.187 [CBIT](INFO): Critical error at 20170512T045211
2017-05-12T04:53:12.151Z,1494564792.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.387800 seconds old.
2017-05-12T04:53:12.153Z,1494564792.153 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.388300 seconds old.
2017-05-12T04:53:12.549Z,1494564792.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.785400 seconds old.
2017-05-12T04:53:12.550Z,1494564792.550 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.786000 seconds old.
2017-05-12T04:53:12.945Z,1494564792.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.181600 seconds old.
2017-05-12T04:53:12.947Z,1494564792.947 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.182100 seconds old.
2017-05-12T04:53:13.354Z,1494564793.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.590600 seconds old.
2017-05-12T04:53:13.355Z,1494564793.355 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.591100 seconds old.
2017-05-12T04:53:21.421Z,1494564801.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:53:21.421Z,1494564801.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2017-05-12T04:53:21.421Z,1494564801.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:53:21.423Z,1494564801.423 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:53:21.423Z,1494564801.423 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2017-05-12T04:53:21.423Z,1494564801.423 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:53:21.460Z,1494564801.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:53:21.460Z,1494564801.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:53:21.772Z,1494564801.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:53:21.773Z,1494564801.773 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2017-05-12T04:53:21.773Z,1494564801.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:53:21.773Z,1494564801.773 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2017-05-12T04:53:22.191Z,1494564802.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:53:22.192Z,1494564802.192 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:53:22.192Z,1494564802.192 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:53:22.192Z,1494564802.192 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:53:22.193Z,1494564802.193 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:53:22.193Z,1494564802.193 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:53:46.558Z,1494564826.559 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-05-12T04:53:46.559Z,1494564826.559 [Default:CheckIn:C.Wait] Stopped
2017-05-12T04:53:46.559Z,1494564826.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-05-12T04:53:46.559Z,1494564826.559 [Default:CheckIn:D] Running Loop=1
2017-05-12T04:53:46.951Z,1494564826.951 [Default:CheckIn:D] Stopped
2017-05-12T04:53:46.951Z,1494564826.951 [Default:CheckIn:E] Running Loop=1
2017-05-12T04:53:47.418Z,1494564827.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.136938 min 
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn:E] Stopped
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn] Stopped
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn](INFO): Running loop #3
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn] Running Loop=3
2017-05-12T04:53:47.419Z,1494564827.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-05-12T04:53:47.419Z,1494564827.420 [Default:CheckIn:Read_GPS] Running Loop=1
2017-05-12T04:53:56.874Z,1494564836.874 [PNI_TCM](ERROR): CRC does not match. Expected:0x52063 got:0x64467
2017-05-12T04:53:59.489Z,1494564839.489 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:53:59.489Z,1494564839.489 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-05-12T04:53:59.489Z,1494564839.489 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:53:59.736Z,1494564839.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2017-05-12T04:53:59.883Z,1494564839.883 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:53:59.893Z,1494564839.893 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:54:00.106Z,1494564840.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:54:00.121Z,1494564840.121 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:54:00.499Z,1494564840.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:54:00.881Z,1494564840.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:54:01.282Z,1494564841.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:54:01.687Z,1494564841.687 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:54:01.701Z,1494564841.701 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:54:01.701Z,1494564841.701 [Rowe_600LCM] No Fault, FailCount= 2
2017-05-12T04:54:01.738Z,1494564841.738 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:54:01.738Z,1494564841.738 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:54:01.738Z,1494564841.739 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:54:01.739Z,1494564841.739 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:54:02.099Z,1494564842.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:54:02.489Z,1494564842.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:54:02.894Z,1494564842.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:54:03.264Z,1494564843.264 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41
2017-05-12T04:54:03.303Z,1494564843.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:54:03.696Z,1494564843.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:54:04.090Z,1494564844.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:54:04.479Z,1494564844.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:54:04.878Z,1494564844.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:54:05.331Z,1494564845.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2017-05-12T04:54:05.727Z,1494564845.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-05-12T04:54:05.877Z,1494564845.877 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:54:05.941Z,1494564845.941 [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-12T04:54:05.943Z,1494564845.943 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:54:05.944Z,1494564845.944 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:54:05.945Z,1494564845.945 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:54:06.104Z,1494564846.104 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:54:12.086Z,1494564852.086 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:54:12.086Z,1494564852.086 [NAL9602] No Fault, FailCount= 5
2017-05-12T04:54:12.431Z,1494564852.431 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:54:33.235Z,1494564873.235 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:54:33.235Z,1494564873.235 [NAL9602] Communications Fault, FailCount= 1
2017-05-12T04:54:33.235Z,1494564873.235 [NAL9602](ERROR): Communications Fault
2017-05-12T04:54:33.261Z,1494564873.261 [BPC1](ERROR): Failed to parse bank A battery data
2017-05-12T04:54:33.262Z,1494564873.261 [BPC1] Data Fault, FailCount= 1
2017-05-12T04:54:33.262Z,1494564873.262 [BPC1](ERROR): Data Fault
2017-05-12T04:54:33.315Z,1494564873.315 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:54:33.315Z,1494564873.315 [CBIT](ERROR): Data Fault in component: BPC1
2017-05-12T04:54:33.638Z,1494564873.638 [NAL9602](INFO): Powering down
2017-05-12T04:54:34.556Z,1494564874.556 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:54:34.556Z,1494564874.556 [NAL9602] No Fault, FailCount= 1
2017-05-12T04:54:34.556Z,1494564874.556 [CBIT](INFO): Clearing failed state for component BPC1
2017-05-12T04:54:34.556Z,1494564874.556 [BPC1] No Fault, FailCount= 1
2017-05-12T04:54:38.840Z,1494564878.840 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:55:01.985Z,1494564901.985 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:55:01.985Z,1494564901.985 [NAL9602] Communications Fault, FailCount= 2
2017-05-12T04:55:01.985Z,1494564901.985 [NAL9602](ERROR): Communications Fault
2017-05-12T04:55:02.054Z,1494564902.054 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:55:02.389Z,1494564902.389 [NAL9602](INFO): Powering down
2017-05-12T04:55:03.234Z,1494564903.234 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:55:03.234Z,1494564903.234 [NAL9602] No Fault, FailCount= 2
2017-05-12T04:55:07.571Z,1494564907.571 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:55:28.379Z,1494564928.379 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:55:28.379Z,1494564928.379 [NAL9602] Communications Fault, FailCount= 3
2017-05-12T04:55:28.379Z,1494564928.379 [NAL9602](ERROR): Communications Fault
2017-05-12T04:55:28.456Z,1494564928.456 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:55:28.777Z,1494564928.777 [NAL9602](INFO): Powering down
2017-05-12T04:55:29.655Z,1494564929.655 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:55:29.655Z,1494564929.655 [NAL9602] No Fault, FailCount= 3
2017-05-12T04:55:33.971Z,1494564933.971 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:55:53.900Z,1494564953.900 [PNI_TCM](ERROR): CRC does not match. Expected:0x13275 got:0x64037
2017-05-12T04:55:55.057Z,1494564955.057 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:55:55.057Z,1494564955.057 [NAL9602] Communications Fault, FailCount= 4
2017-05-12T04:55:55.057Z,1494564955.057 [NAL9602](ERROR): Communications Fault
2017-05-12T04:55:55.143Z,1494564955.143 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:55:55.455Z,1494564955.455 [NAL9602](INFO): Powering down
2017-05-12T04:55:56.339Z,1494564956.339 [CBIT](INFO): Clearing failed state for component NAL9602
2017-05-12T04:55:56.339Z,1494564956.339 [NAL9602] No Fault, FailCount= 4
2017-05-12T04:55:59.892Z,1494564959.892 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-05-12T04:56:00.689Z,1494564960.689 [NAL9602](INFO): Powering up NAL9602
2017-05-12T04:56:01.481Z,1494564961.481 [PNI_TCM](ERROR): CRC does not match. Expected:0x8846 got:0x23836
2017-05-12T04:56:02.361Z,1494564962.361 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:56:02.361Z,1494564962.361 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-05-12T04:56:02.361Z,1494564962.361 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:56:02.749Z,1494564962.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:56:02.765Z,1494564962.765 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:56:02.815Z,1494564962.815 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:56:02.918Z,1494564962.918 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:56:03.123Z,1494564963.123 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:56:03.492Z,1494564963.492 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:56:03.899Z,1494564963.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:56:04.301Z,1494564964.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-05-12T04:56:04.338Z,1494564964.339 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:56:04.339Z,1494564964.339 [Rowe_600LCM] No Fault, FailCount= 3
2017-05-12T04:56:04.533Z,1494564964.533 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:56:04.534Z,1494564964.534 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:56:04.534Z,1494564964.534 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:56:04.534Z,1494564964.534 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:56:04.779Z,1494564964.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2017-05-12T04:56:05.101Z,1494564965.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-05-12T04:56:05.528Z,1494564965.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-05-12T04:56:05.907Z,1494564965.907 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-05-12T04:56:06.339Z,1494564966.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-05-12T04:56:06.700Z,1494564966.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-05-12T04:56:07.130Z,1494564967.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-05-12T04:56:07.508Z,1494564967.508 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-05-12T04:56:07.899Z,1494564967.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-05-12T04:56:08.300Z,1494564968.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-05-12T04:56:08.715Z,1494564968.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:56:08.768Z,1494564968.768 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-05-12T04:56:08.861Z,1494564968.861 [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-12T04:56:08.863Z,1494564968.863 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-05-12T04:56:08.864Z,1494564968.864 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-05-12T04:56:08.865Z,1494564968.865 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-05-12T04:56:09.139Z,1494564969.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-05-12T04:56:21.869Z,1494564981.869 [NAL9602](ERROR): NAL9602 initialization error.
2017-05-12T04:56:21.870Z,1494564981.870 [NAL9602] Communications Fault, FailCount= 5
2017-05-12T04:56:21.870Z,1494564981.870 [NAL9602](ERROR): Communications Fault
2017-05-12T04:56:21.965Z,1494564981.965 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-05-12T04:56:21.966Z,1494564981.966 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2017-05-12T04:56:22.259Z,1494564982.259 [NAL9602](INFO): Powering down
2017-05-12T04:56:22.364Z,1494564982.364 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:56:22.364Z,1494564982.365 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2017-05-12T04:56:22.365Z,1494564982.365 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-05-12T04:56:22.375Z,1494564982.375 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-05-12T04:56:22.375Z,1494564982.375 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2017-05-12T04:56:22.375Z,1494564982.375 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-05-12T04:56:22.394Z,1494564982.394 [CBIT](INFO): Critical error at 20170512T045621
2017-05-12T04:56:22.397Z,1494564982.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-05-12T04:56:22.397Z,1494564982.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-05-12T04:56:22.724Z,1494564982.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-05-12T04:56:22.724Z,1494564982.724 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2017-05-12T04:56:22.724Z,1494564982.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-05-12T04:56:22.724Z,1494564982.724 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2017-05-12T04:56:23.130Z,1494564983.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-05-12T04:56:23.131Z,1494564983.131 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:56:23.131Z,1494564983.131 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:56:23.131Z,1494564983.131 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-05-12T04:56:23.132Z,1494564983.132 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-05-12T04:56:23.132Z,1494564983.132 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-05-12T04:56:23.947Z,1494564983.947 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:56:23.947Z,1494564983.947 [MassServo] No Fault, FailCount= 5
2017-05-12T04:56:27.545Z,1494564987.545 [MassServo](FAULT): Overload Error
2017-05-12T04:56:27.545Z,1494564987.546 [MassServo] Hardware Fault, FailCount= 1
2017-05-12T04:56:27.546Z,1494564987.546 [MassServo](ERROR): Hardware Fault
2017-05-12T04:56:27.550Z,1494564987.550 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:56:27.920Z,1494564987.920 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:56:27.920Z,1494564987.920 [MassServo](INFO): Powering down
2017-05-12T04:56:28.720Z,1494564988.720 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:56:28.720Z,1494564988.720 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:56:28.738Z,1494564988.738 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:56:28.738Z,1494564988.738 [MassServo] No Fault, FailCount= 1
2017-05-12T04:56:32.774Z,1494564992.773 [MassServo](FAULT): Overload Error
2017-05-12T04:56:32.774Z,1494564992.774 [MassServo] Hardware Fault, FailCount= 2
2017-05-12T04:56:32.774Z,1494564992.774 [MassServo](ERROR): Hardware Fault
2017-05-12T04:56:32.862Z,1494564992.862 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:56:33.126Z,1494564993.126 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:56:33.126Z,1494564993.126 [MassServo](INFO): Powering down
2017-05-12T04:56:33.901Z,1494564993.901 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:56:33.909Z,1494564993.909 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:56:33.918Z,1494564993.918 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:56:33.918Z,1494564993.918 [MassServo] No Fault, FailCount= 2
2017-05-12T04:56:38.330Z,1494564998.330 [MassServo](FAULT): Overload Error
2017-05-12T04:56:38.330Z,1494564998.330 [MassServo] Hardware Fault, FailCount= 3
2017-05-12T04:56:38.330Z,1494564998.330 [MassServo](ERROR): Hardware Fault
2017-05-12T04:56:38.335Z,1494564998.335 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:56:38.738Z,1494564998.738 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:56:38.738Z,1494564998.738 [MassServo](INFO): Powering down
2017-05-12T04:56:39.512Z,1494564999.512 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:56:39.512Z,1494564999.512 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:56:39.517Z,1494564999.517 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:56:39.517Z,1494564999.517 [MassServo] No Fault, FailCount= 3
2017-05-12T04:56:43.526Z,1494565003.526 [MassServo](FAULT): Overload Error
2017-05-12T04:56:43.526Z,1494565003.526 [MassServo] Hardware Fault, FailCount= 4
2017-05-12T04:56:43.526Z,1494565003.526 [MassServo](ERROR): Hardware Fault
2017-05-12T04:56:43.614Z,1494565003.614 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:56:43.916Z,1494565003.916 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:56:43.916Z,1494565003.916 [MassServo](INFO): Powering down
2017-05-12T04:56:44.721Z,1494565004.721 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:56:44.725Z,1494565004.725 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:56:44.734Z,1494565004.734 [CBIT](INFO): Clearing failed state for component MassServo
2017-05-12T04:56:44.734Z,1494565004.734 [MassServo] No Fault, FailCount= 4
2017-05-12T04:56:48.785Z,1494565008.785 [MassServo](FAULT): Overload Error
2017-05-12T04:56:48.785Z,1494565008.786 [MassServo] Hardware Fault, FailCount= 5
2017-05-12T04:56:48.786Z,1494565008.786 [MassServo](ERROR): Hardware Fault
2017-05-12T04:56:48.831Z,1494565008.831 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-05-12T04:56:48.831Z,1494565008.831 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2017-05-12T04:56:52.124Z,1494565012.124 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-05-12T04:56:52.124Z,1494565012.124 [MassServo](INFO): Powering down
2017-05-12T04:56:52.127Z,1494565012.128 [CBIT](INFO): Critical error at 20170512T045648
2017-05-12T04:56:52.693Z,1494565012.693 [MassServo](DEBUG): Initializing EZServoServo.
2017-05-12T04:56:52.693Z,1494565012.693 [MassServo](DEBUG): Initializing MassServo.
2017-05-12T04:56:58.991Z,1494565018.991 [PNI_TCM](ERROR): CRC does not match. Expected:0x54926 got:0x17573
2017-05-12T04:58:05.145Z,1494565085.145 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-05-12T04:58:05.146Z,1494565085.145 [Rowe_600LCM] Communications Fault, FailCount= 4
2017-05-12T04:58:05.146Z,1494565085.146 [Rowe_600LCM](ERROR): Communications Fault
2017-05-12T04:58:05.366Z,1494565085.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-05-12T04:58:05.379Z,1494565085.379 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-05-12T04:58:05.549Z,1494565085.549 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-05-12T04:58:05.639Z,1494565085.639 [Rowe_600LCM](INFO): Powering down
2017-05-12T04:58:05.781Z,1494565085.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-05-12T04:58:06.163Z,1494565086.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-05-12T04:58:06.572Z,1494565086.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-05-12T04:58:06.958Z,1494565086.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.9 s old, using for 20.0 s.
2017-05-12T04:58:06.973Z,1494565086.973 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-05-12T04:58:06.973Z,1494565086.973 [Rowe_600LCM] No Fault, FailCount= 4
2017-05-12T04:58:07.253Z,1494565087.253 [Rowe_600LCM](INFO): Initializing
2017-05-12T04:58:07.254Z,1494565087.254 [Rowe_600LCM](INFO): Checking LCM
2017-05-12T04:58:07.254Z,1494565087.254 [Rowe_600LCM](INFO): LCM OK
2017-05-12T04:58:07.254Z,1494565087.254 [Rowe_600LCM](INFO): Powering up
2017-05-12T04:58:07.402Z,1494565087.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-05-12T04:58:07.749Z,1494565087.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.7 s old, using for 20.0 s.
2017-05-12T04:58:08.144Z,1494565088.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.1 s old, using for 20.0 s.
2017-05-12T04:58:08.550Z,1494565088.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s.
2017-05-12T04:58:08.955Z,1494565088.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.9 s old, using for 20.0 s.
2017-05-12T04:58:09.353Z,1494565089.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2017-05-12T04:58:09.748Z,1494565089.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s.
2017-05-12T04:58:10.156Z,1494565090.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2017-05-12T04:58:10.553Z,1494565090.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2017-05-12T04:58:10.948Z,1494565090.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2017-05-12T04:58:11.441Z,1494565091.441 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-05-12T04:58:11.543Z,1494565091.543 [Rowe_600LCM](INFO): Stopping potential previ