2016-06-23T21:33:01.943Z,1466717581.943 [Supervisor](DEBUG): Initializing supervisor.
2016-06-23T21:33:01.945Z,1466717581.945 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2016-06-23T21:33:01.946Z,1466717581.946 [SyncHandler](INFO): Protected caller Thread ID is 775
2016-06-23T21:33:01.946Z,1466717581.946 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2016-06-23T21:33:01.947Z,1466717581.947 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2016-06-23T21:33:01.948Z,1466717581.948 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 776
2016-06-23T21:33:01.951Z,1466717581.951 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2016-06-23T21:33:01.962Z,1466717581.962 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2016-06-23T21:33:01.963Z,1466717581.963 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2016-06-23T21:33:01.964Z,1466717581.964 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 777
2016-06-23T21:33:01.964Z,1466717581.964 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2016-06-23T21:33:01.965Z,1466717581.965 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2016-06-23T21:33:01.966Z,1466717581.966 [logger ThreadHandler](INFO): Protected caller Thread ID is 778
2016-06-23T21:33:01.967Z,1466717581.967 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2016-06-23T21:33:01.968Z,1466717581.968 [Supervisor](INFO): Looking for Config files in directory: Config/
2016-06-23T21:33:01.972Z,1466717581.972 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2016-06-23T21:33:02.058Z,1466717582.059 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2016-06-23T21:33:02.380Z,1466717582.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2016-06-23T21:33:02.380Z,1466717582.381 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2016-06-23T21:33:02.504Z,1466717582.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2016-06-23T21:33:02.505Z,1466717582.505 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2016-06-23T21:33:02.692Z,1466717582.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2016-06-23T21:33:02.694Z,1466717582.694 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2016-06-23T21:33:02.795Z,1466717582.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2016-06-23T21:33:02.796Z,1466717582.796 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2016-06-23T21:33:02.970Z,1466717582.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2016-06-23T21:33:02.972Z,1466717582.972 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2016-06-23T21:33:03.433Z,1466717583.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2016-06-23T21:33:03.433Z,1466717583.433 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2016-06-23T21:33:03.712Z,1466717583.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2016-06-23T21:33:03.712Z,1466717583.713 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2016-06-23T21:33:03.799Z,1466717583.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2016-06-23T21:33:03.801Z,1466717583.801 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2016-06-23T21:33:03.932Z,1466717583.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2016-06-23T21:33:03.933Z,1466717583.933 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2016-06-23T21:33:04.126Z,1466717584.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2016-06-23T21:33:04.126Z,1466717584.126 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2016-06-23T21:33:04.570Z,1466717584.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2016-06-23T21:33:04.570Z,1466717584.570 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2016-06-23T21:33:05.058Z,1466717585.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2016-06-23T21:33:05.058Z,1466717585.058 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2016-06-23T21:33:09.536Z,1466717589.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2016-06-23T21:33:09.537Z,1466717589.537 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2016-06-23T21:33:09.647Z,1466717589.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2016-06-23T21:33:09.649Z,1466717589.649 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2016-06-23T21:33:09.653Z,1466717589.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2016-06-23T21:33:09.754Z,1466717589.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2016-06-23T21:33:12.499Z,1466717592.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2016-06-23T21:33:12.601Z,1466717592.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2016-06-23T21:33:12.690Z,1466717592.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2016-06-23T21:33:12.834Z,1466717592.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2016-06-23T21:33:12.934Z,1466717592.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2016-06-23T21:33:13.102Z,1466717593.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2016-06-23T21:33:13.333Z,1466717593.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2016-06-23T21:33:13.334Z,1466717593.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2016-06-23T21:33:13.506Z,1466717593.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2016-06-23T21:33:13.611Z,1466717593.611 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2016-06-23T21:33:13.614Z,1466717593.614 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2016-06-23T21:33:13.655Z,1466717593.655 [DepthRateCalculator] Loaded
2016-06-23T21:33:13.655Z,1466717593.655 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2016-06-23T21:33:13.662Z,1466717593.662 [PitchRateCalculator] Loaded
2016-06-23T21:33:13.662Z,1466717593.662 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2016-06-23T21:33:13.673Z,1466717593.673 [SpeedCalculator] Loaded
2016-06-23T21:33:13.674Z,1466717593.674 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2016-06-23T21:33:13.691Z,1466717593.691 [TempGradientCalculator] Loaded
2016-06-23T21:33:13.691Z,1466717593.691 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2016-06-23T21:33:13.707Z,1466717593.707 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2016-06-23T21:33:13.707Z,1466717593.707 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2016-06-23T21:33:13.713Z,1466717593.713 [YawRateCalculator] Loaded
2016-06-23T21:33:13.714Z,1466717593.714 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2016-06-23T21:33:13.714Z,1466717593.714 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2016-06-23T21:33:13.715Z,1466717593.715 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2016-06-23T21:33:13.837Z,1466717593.837 [DeadReckonUsingMultipleVelocitySources] Loaded
2016-06-23T21:33:13.837Z,1466717593.837 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2016-06-23T21:33:13.892Z,1466717593.892 [DeadReckonUsingSpeedCalculator] Loaded
2016-06-23T21:33:13.892Z,1466717593.892 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2016-06-23T21:33:13.909Z,1466717593.909 [NavChart] Loaded
2016-06-23T21:33:13.909Z,1466717593.909 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2016-06-23T21:33:13.913Z,1466717593.913 [UniversalFixResidualReporter] Loaded
2016-06-23T21:33:13.914Z,1466717593.914 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2016-06-23T21:33:13.914Z,1466717593.914 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2016-06-23T21:33:13.915Z,1466717593.915 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2016-06-23T21:33:14.054Z,1466717594.054 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2016-06-23T21:33:14.054Z,1466717594.054 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2016-06-23T21:33:14.095Z,1466717594.095 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2016-06-23T21:33:14.096Z,1466717594.096 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2016-06-23T21:33:14.105Z,1466717594.105 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2016-06-23T21:33:14.106Z,1466717594.106 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2016-06-23T21:33:14.378Z,1466717594.378 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2016-06-23T21:33:14.379Z,1466717594.379 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2016-06-23T21:33:14.525Z,1466717594.525 [SBIT](DEBUG): Construct Startup Built In Test.
2016-06-23T21:33:14.558Z,1466717594.558 [SBIT] Loaded
2016-06-23T21:33:14.558Z,1466717594.559 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2016-06-23T21:33:14.559Z,1466717594.559 [IBIT](DEBUG): Construct Initiated Built In Test.
2016-06-23T21:33:14.588Z,1466717594.588 [IBIT] Loaded
2016-06-23T21:33:14.588Z,1466717594.588 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2016-06-23T21:33:14.592Z,1466717594.592 [CBIT](DEBUG): Construct Continuous Built In Test.
2016-06-23T21:33:14.744Z,1466717594.744 [CBIT] Loaded
2016-06-23T21:33:14.744Z,1466717594.744 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2016-06-23T21:33:14.744Z,1466717594.744 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2016-06-23T21:33:14.745Z,1466717594.745 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2016-06-23T21:33:17.091Z,1466717597.091 [DataOverHttps] Loaded
2016-06-23T21:33:17.091Z,1466717597.091 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2016-06-23T21:33:17.119Z,1466717597.118 [Depth_Keller] Loaded
2016-06-23T21:33:17.119Z,1466717597.119 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2016-06-23T21:33:17.211Z,1466717597.211 [NAL9602] Loaded
2016-06-23T21:33:17.211Z,1466717597.211 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2016-06-23T21:33:17.221Z,1466717597.221 [Onboard] Loaded
2016-06-23T21:33:17.222Z,1466717597.222 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2016-06-23T21:33:17.229Z,1466717597.229 [Radio_Surface] Loaded
2016-06-23T21:33:17.229Z,1466717597.230 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2016-06-23T21:33:17.231Z,1466717597.231 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2016-06-23T21:33:17.231Z,1466717597.231 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 855
2016-06-23T21:33:17.425Z,1466717597.425 [PNI_TCM] Loaded
2016-06-23T21:33:17.425Z,1466717597.425 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2016-06-23T21:33:17.784Z,1466717597.784 [Rowe_600LCM] Loaded
2016-06-23T21:33:17.784Z,1466717597.784 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2016-06-23T21:33:17.785Z,1466717597.785 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409534E0
2016-06-23T21:33:17.785Z,1466717597.786 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 856
2016-06-23T21:33:19.596Z,1466717599.596 [BPC1] Loaded
2016-06-23T21:33:19.596Z,1466717599.596 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2016-06-23T21:33:19.596Z,1466717599.596 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2016-06-23T21:33:19.597Z,1466717599.597 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2016-06-23T21:33:19.724Z,1466717599.724 [BuoyancyServo] Loaded
2016-06-23T21:33:19.725Z,1466717599.725 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2016-06-23T21:33:19.741Z,1466717599.741 [ElevatorServo] Loaded
2016-06-23T21:33:19.741Z,1466717599.741 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2016-06-23T21:33:19.757Z,1466717599.757 [MassServo] Loaded
2016-06-23T21:33:19.757Z,1466717599.757 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2016-06-23T21:33:19.774Z,1466717599.774 [RudderServo] Loaded
2016-06-23T21:33:19.774Z,1466717599.774 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2016-06-23T21:33:19.789Z,1466717599.789 [ThrusterServo] Loaded
2016-06-23T21:33:19.790Z,1466717599.790 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2016-06-23T21:33:19.790Z,1466717599.790 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2016-06-23T21:33:19.791Z,1466717599.791 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2016-06-23T21:33:19.860Z,1466717599.860 [VerticalControl](DEBUG): Construct VerticalControl.
2016-06-23T21:33:19.955Z,1466717599.955 [VerticalControl] Loaded
2016-06-23T21:33:19.955Z,1466717599.955 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2016-06-23T21:33:19.956Z,1466717599.956 [HorizontalControl](DEBUG): Construct HorizontalControl.
2016-06-23T21:33:20.016Z,1466717600.016 [HorizontalControl] Loaded
2016-06-23T21:33:20.017Z,1466717600.017 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2016-06-23T21:33:20.018Z,1466717600.017 [SpeedControl](DEBUG): Construct SpeedControl.
2016-06-23T21:33:20.020Z,1466717600.020 [SpeedControl] Loaded
2016-06-23T21:33:20.020Z,1466717600.020 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2016-06-23T21:33:20.021Z,1466717600.020 [LoopControl](DEBUG): Construct LoopControl.
2016-06-23T21:33:20.021Z,1466717600.021 [LoopControl] Loaded
2016-06-23T21:33:20.021Z,1466717600.021 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2016-06-23T21:33:20.022Z,1466717600.022 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2016-06-23T21:33:20.022Z,1466717600.022 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2016-06-23T21:33:20.139Z,1466717600.139 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2016-06-23T21:33:20.139Z,1466717600.139 [StratificationFrontDetector](DEBUG): (re)initializing
2016-06-23T21:33:20.139Z,1466717600.139 [StratificationFrontDetector] Loaded
2016-06-23T21:33:20.140Z,1466717600.139 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2016-06-23T21:33:20.140Z,1466717600.140 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2016-06-23T21:33:20.141Z,1466717600.141 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2016-06-23T21:33:20.272Z,1466717600.272 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2016-06-23T21:33:20.276Z,1466717600.276 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2016-06-23T21:33:20.277Z,1466717600.277 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2016-06-23T21:33:20.283Z,1466717600.283 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2016-06-23T21:33:20.285Z,1466717600.284 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A5D4E0
2016-06-23T21:33:20.285Z,1466717600.285 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 857
2016-06-23T21:33:20.290Z,1466717600.290 [Supervisor](INFO): Main Thread ID is 770
2016-06-23T21:33:20.290Z,1466717600.290 [Supervisor](DEBUG): Running supervisor.
2016-06-23T21:33:20.290Z,1466717600.290 [CommandLine ThreadHandler](INFO): Handler Thread ID is 858
2016-06-23T21:33:20.293Z,1466717600.293 [controlThread ThreadHandler](INFO): Handler Thread ID is 859
2016-06-23T21:33:20.294Z,1466717600.294 [controlThread](DEBUG): Initializing ControlThread
2016-06-23T21:33:20.295Z,1466717600.295 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-06-23T21:33:20.295Z,1466717600.295 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2016-06-23T21:33:20.296Z,1466717600.296 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2016-06-23T21:33:20.296Z,1466717600.296 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2016-06-23T21:33:20.296Z,1466717600.296 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2016-06-23T21:33:20.297Z,1466717600.297 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2016-06-23T21:33:20.298Z,1466717600.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T21:33:20.299Z,1466717600.299 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T21:33:20.299Z,1466717600.299 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T21:33:20.299Z,1466717600.299 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T21:33:20.300Z,1466717600.300 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T21:33:20.300Z,1466717600.300 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T21:33:20.301Z,1466717600.301 [NavChart](DEBUG): Initialize NavChart Navigation.
2016-06-23T21:33:20.301Z,1466717600.301 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2016-06-23T21:33:20.301Z,1466717600.301 [SBIT](INFO): Initialize SBIT Component.
2016-06-23T21:33:20.302Z,1466717600.302 [SBIT](IMPORTANT): git: 2015-12-17-109-g257a154
2016-06-23T21:33:20.302Z,1466717600.302 [SBIT](INFO): git hash: 257a1547f8111cca91b00dd2487919c4d9436f7c
2016-06-23T21:33:20.304Z,1466717600.304 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2016-06-23T21:33:20.304Z,1466717600.304 [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
2016-06-23T21:33:20.305Z,1466717600.305 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2016-06-23T21:33:20.306Z,1466717600.306 [IBIT](INFO): Initialize IBIT Component.
2016-06-23T21:33:20.306Z,1466717600.306 [CBIT](DEBUG): Initialize CBIT Component.
2016-06-23T21:33:20.307Z,1466717600.307 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2016-06-23T21:33:20.307Z,1466717600.307 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2016-06-23T21:33:20.308Z,1466717600.308 [logger ThreadHandler](INFO): Handler Thread ID is 860
2016-06-23T21:33:20.336Z,1466717600.336 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 861
2016-06-23T21:33:20.342Z,1466717600.342 [Radio_Surface](INFO): Powering up
2016-06-23T21:33:20.359Z,1466717600.359 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 862
2016-06-23T21:33:20.360Z,1466717600.360 [Rowe_600LCM](INFO): Initializing
2016-06-23T21:33:20.361Z,1466717600.361 [Rowe_600LCM](INFO): Checking LCM
2016-06-23T21:33:20.361Z,1466717600.361 [Rowe_600LCM](INFO): LCM OK
2016-06-23T21:33:20.361Z,1466717600.361 [Rowe_600LCM](INFO): Powering up
2016-06-23T21:33:20.380Z,1466717600.380 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863
2016-06-23T21:33:20.388Z,1466717600.388 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2016-06-23T21:33:20.405Z,1466717600.405 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2016-06-23T21:33:20.409Z,1466717600.409 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2016-06-23T21:33:20.411Z,1466717600.411 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2016-06-23T21:33:20.412Z,1466717600.412 [LoopControl](DEBUG): Initialize LoopControlComponent.
2016-06-23T21:33:20.416Z,1466717600.416 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2016-06-23T21:33:20.438Z,1466717600.438 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts
2016-06-23T21:33:20.449Z,1466717600.449 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2016-06-23T21:33:20.451Z,1466717600.451 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI01M.000
2016-06-23T21:33:20.452Z,1466717600.452 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI80M.000
2016-06-23T21:33:20.454Z,1466717600.454 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2016-06-23T21:33:20.456Z,1466717600.456 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2016-06-23T21:33:20.457Z,1466717600.457 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2016-06-23T21:33:20.459Z,1466717600.459 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4IN01M.000
2016-06-23T21:33:20.461Z,1466717600.461 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI56M.000
2016-06-23T21:33:20.463Z,1466717600.463 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI57M.000
2016-06-23T21:33:20.468Z,1466717600.468 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2016-06-23T21:33:20.483Z,1466717600.483 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI59M.000
2016-06-23T21:33:20.485Z,1466717600.485 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI89M.000
2016-06-23T21:33:20.487Z,1466717600.487 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI90M.000
2016-06-23T21:33:20.489Z,1466717600.489 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI03M.000
2016-06-23T21:33:20.491Z,1466717600.491 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI06M.000
2016-06-23T21:33:20.498Z,1466717600.498 [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>


2016-06-23T21:33:20.509Z,1466717600.509 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI33M.000
2016-06-23T21:33:20.511Z,1466717600.511 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI34M.000
2016-06-23T21:33:20.513Z,1466717600.513 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2016-06-23T21:33:20.515Z,1466717600.515 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2016-06-23T21:33:20.515Z,1466717600.515 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2016-06-23T21:33:20.529Z,1466717600.529 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2016-06-23T21:33:20.531Z,1466717600.531 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2016-06-23T21:33:20.533Z,1466717600.533 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2AK20M.000
2016-06-23T21:33:20.551Z,1466717600.551 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3AK12M.000
2016-06-23T21:33:20.553Z,1466717600.553 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3AK1DM.000
2016-06-23T21:33:20.555Z,1466717600.556 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3AK5KM.000
2016-06-23T21:33:20.565Z,1466717600.565 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2016-06-23T21:33:20.566Z,1466717600.566 [Default:A.Wait](DEBUG): Construct Wait.
2016-06-23T21:33:20.573Z,1466717600.573 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2016-06-23T21:33:20.588Z,1466717600.588 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T21:33:20.594Z,1466717600.593 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2016-06-23T21:33:20.617Z,1466717600.617 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2016-06-23T21:33:20.629Z,1466717600.629 [Default:E.Execute](DEBUG): Construct Execute.
2016-06-23T21:33:20.633Z,1466717600.633 [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>


2016-06-23T21:33:20.642Z,1466717600.642 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2016-06-23T21:33:20.716Z,1466717600.716 [Depth_Keller](ERROR): Pressure reading out of range: 1895.584351 decibar
2016-06-23T21:33:20.924Z,1466717600.924 [DepthRateCalculator](ERROR): Depth measurement is not active
2016-06-23T21:33:21.004Z,1466717601.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:21.190Z,1466717601.190 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:21.289Z,1466717601.289 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-06-23T21:33:21.311Z,1466717601.311 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-06-23T21:33:21.326Z,1466717601.326 [ElevatorServo](DEBUG): Initializing EZServoServo.
2016-06-23T21:33:21.332Z,1466717601.332 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2016-06-23T21:33:21.346Z,1466717601.346 [MassServo](DEBUG): Initializing EZServoServo.
2016-06-23T21:33:21.352Z,1466717601.352 [MassServo](DEBUG): Initializing MassServo.
2016-06-23T21:33:21.357Z,1466717601.357 [RudderServo](DEBUG): Initializing EZServoServo.
2016-06-23T21:33:21.363Z,1466717601.364 [RudderServo](DEBUG): Initializing RudderServo.
2016-06-23T21:33:21.369Z,1466717601.369 [ThrusterServo](DEBUG): Initializing EZServoServo.
2016-06-23T21:33:21.375Z,1466717601.375 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2016-06-23T21:33:21.718Z,1466717601.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:21.735Z,1466717601.735 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:21.923Z,1466717601.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:21.924Z,1466717601.924 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:22.259Z,1466717602.259 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2016-06-23T21:33:22.259Z,1466717602.259 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2016-06-23T21:33:22.259Z,1466717602.260 [BuoyancyServo] Communications Fault, FailCount= 1
2016-06-23T21:33:22.259Z,1466717602.260 [BuoyancyServo](ERROR): Communications Fault
2016-06-23T21:33:22.479Z,1466717602.479 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2016-06-23T21:33:22.489Z,1466717602.489 [NAL9602](INFO): Powering up NAL9602
2016-06-23T21:33:22.543Z,1466717602.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:22.544Z,1466717602.545 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:22.550Z,1466717602.550 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-06-23T21:33:22.559Z,1466717602.559 [BuoyancyServo](INFO): Powering down
2016-06-23T21:33:22.930Z,1466717602.930 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:22.932Z,1466717602.932 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:23.319Z,1466717603.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:23.328Z,1466717603.328 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:23.727Z,1466717603.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:23.740Z,1466717603.740 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:24.140Z,1466717604.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:24.141Z,1466717604.141 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T21:33:24.718Z,1466717604.718 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2016-06-23T21:33:24.718Z,1466717604.718 [BuoyancyServo] No Fault, FailCount= 1
2016-06-23T21:33:24.736Z,1466717604.736 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2016-06-23T21:33:24.855Z,1466717604.855 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyB4 -b 38400 >& /dev/null &
2016-06-23T21:33:25.016Z,1466717605.016 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-06-23T21:33:25.017Z,1466717605.017 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-06-23T21:33:25.663Z,1466717605.663 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2016-06-23T21:33:25.664Z,1466717605.664 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2016-06-23T21:33:30.194Z,1466717610.194 [PNI_TCM](ERROR): CRC does not match. Expected:0x47546 got:0x4491
2016-06-23T21:33:30.613Z,1466717610.613 [PNI_TCM](ERROR): CRC does not match. Expected:0x41172 got:0x15303
2016-06-23T21:33:31.359Z,1466717611.359 [PNI_TCM](ERROR): CRC does not match. Expected:0x52954 got:0x46475
2016-06-23T21:33:31.885Z,1466717611.885 [PNI_TCM](ERROR): CRC does not match. Expected:0x30879 got:0x24732
2016-06-23T21:33:31.886Z,1466717611.886 [PNI_TCM](ERROR): CRC does not match. Expected:0x61439 got:0x48201
2016-06-23T21:33:32.307Z,1466717612.307 [PNI_TCM](ERROR): CRC does not match. Expected:0x21302 got:0x27766
2016-06-23T21:33:32.646Z,1466717612.646 [PNI_TCM](ERROR): CRC does not match. Expected:0x55786 got:0x35955
2016-06-23T21:33:32.972Z,1466717612.972 [PNI_TCM](ERROR): CRC does not match. Expected:0x16318 got:0x37303
2016-06-23T21:33:33.333Z,1466717613.333 [NAL9602](INFO): NAL9602 initialized
2016-06-23T21:33:33.362Z,1466717613.362 [PNI_TCM](ERROR): CRC does not match. Expected:0x39341 got:0x19671
2016-06-23T21:33:33.842Z,1466717613.842 [PNI_TCM](ERROR): CRC does not match. Expected:0x33994 got:0x64007
2016-06-23T21:33:34.228Z,1466717614.228 [PNI_TCM](ERROR): CRC does not match. Expected:0x21658 got:0x33719
2016-06-23T21:33:34.638Z,1466717614.638 [PNI_TCM](ERROR): CRC does not match. Expected:0x19946 got:0x900
2016-06-23T21:33:34.972Z,1466717614.972 [PNI_TCM](ERROR): CRC does not match. Expected:0x4993 got:0x32001
2016-06-23T21:33:35.892Z,1466717615.892 [PNI_TCM](ERROR): CRC does not match. Expected:0x59428 got:0x32598
2016-06-23T21:33:35.893Z,1466717615.893 [PNI_TCM](ERROR): CRC does not match. Expected:0x38173 got:0x16930
2016-06-23T21:33:36.276Z,1466717616.276 [PNI_TCM](ERROR): CRC does not match. Expected:0x4024 got:0x16930
2016-06-23T21:33:36.661Z,1466717616.661 [PNI_TCM](ERROR): CRC does not match. Expected:0x20737 got:0x16930
2016-06-23T21:33:37.383Z,1466717617.383 [PNI_TCM](ERROR): CRC does not match. Expected:0x61777 got:0x16930
2016-06-23T21:33:37.384Z,1466717617.384 [PNI_TCM](ERROR): CRC does not match. Expected:0x13621 got:0x16929
2016-06-23T21:33:37.916Z,1466717617.916 [PNI_TCM](ERROR): CRC does not match. Expected:0x53239 got:0x16929
2016-06-23T21:33:38.313Z,1466717618.313 [PNI_TCM](ERROR): CRC does not match. Expected:0x50540 got:0x16930
2016-06-23T21:33:38.718Z,1466717618.718 [PNI_TCM](ERROR): CRC does not match. Expected:0x19103 got:0x16930
2016-06-23T21:33:38.985Z,1466717618.985 [PNI_TCM](ERROR): CRC does not match. Expected:0x9942 got:0x16930
2016-06-23T21:33:39.385Z,1466717619.385 [PNI_TCM](ERROR): CRC does not match. Expected:0x10313 got:0x16930
2016-06-23T21:33:39.856Z,1466717619.856 [PNI_TCM](ERROR): CRC does not match. Expected:0x36492 got:0x16930
2016-06-23T21:33:39.857Z,1466717619.857 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-06-23T21:33:39.857Z,1466717619.857 [PNI_TCM] Data Fault, FailCount= 1
2016-06-23T21:33:39.857Z,1466717619.857 [PNI_TCM](ERROR): Data Fault
2016-06-23T21:33:39.888Z,1466717619.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.475200 seconds old.
2016-06-23T21:33:39.892Z,1466717619.892 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.477500 seconds old.
2016-06-23T21:33:39.975Z,1466717619.974 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-06-23T21:33:40.196Z,1466717620.195 [PNI_TCM](INFO): Powering down
2016-06-23T21:33:40.233Z,1466717620.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.820400 seconds old.
2016-06-23T21:33:40.246Z,1466717620.246 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.831900 seconds old.
2016-06-23T21:33:40.674Z,1466717620.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.260900 seconds old.
2016-06-23T21:33:40.697Z,1466717620.697 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.283800 seconds old.
2016-06-23T21:33:40.786Z,1466717620.786 [SBIT](IMPORTANT): Beginning Startup BIT
2016-06-23T21:33:40.807Z,1466717620.807 [CBIT](IMPORTANT): Beginning GF scan
2016-06-23T21:33:40.979Z,1466717620.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.566800 seconds old.
2016-06-23T21:33:40.981Z,1466717620.981 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.567300 seconds old.
2016-06-23T21:33:41.176Z,1466717621.176 [CBIT](IMPORTANT): Open Volts:2.501111
Open Current:0.000716
2016-06-23T21:33:41.379Z,1466717621.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.967000 seconds old.
2016-06-23T21:33:41.381Z,1466717621.381 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.967500 seconds old.
2016-06-23T21:33:41.418Z,1466717621.418 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-06-23T21:33:41.418Z,1466717621.418 [PNI_TCM] No Fault, FailCount= 1
2016-06-23T21:33:41.779Z,1466717621.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.366800 seconds old.
2016-06-23T21:33:41.781Z,1466717621.781 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.367400 seconds old.
2016-06-23T21:33:42.266Z,1466717622.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.852700 seconds old.
2016-06-23T21:33:42.280Z,1466717622.280 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.866400 seconds old.
2016-06-23T21:33:42.681Z,1466717622.681 [CBIT](IMPORTANT): A0 Volts:2.669122
A0 Current:0.106796
2016-06-23T21:33:42.802Z,1466717622.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.389400 seconds old.
2016-06-23T21:33:42.810Z,1466717622.810 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.396500 seconds old.
2016-06-23T21:33:43.498Z,1466717623.498 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.085700 seconds old.
2016-06-23T21:33:43.504Z,1466717623.504 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.090300 seconds old.
2016-06-23T21:33:43.996Z,1466717623.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.561800 seconds old.
2016-06-23T21:33:44.000Z,1466717624.000 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.586400 seconds old.
2016-06-23T21:33:44.513Z,1466717624.513 [CBIT](IMPORTANT): A1 Volts:2.037356
A1 Current:-0.294970
2016-06-23T21:33:44.711Z,1466717624.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.297700 seconds old.
2016-06-23T21:33:44.736Z,1466717624.736 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.322200 seconds old.
2016-06-23T21:33:44.979Z,1466717624.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.567100 seconds old.
2016-06-23T21:33:44.981Z,1466717624.981 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.567600 seconds old.
2016-06-23T21:33:45.351Z,1466717625.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.938800 seconds old.
2016-06-23T21:33:45.353Z,1466717625.353 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.939300 seconds old.
2016-06-23T21:33:45.374Z,1466717625.374 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T21:33:46.169Z,1466717626.169 [CBIT](IMPORTANT): A2 Volts:2.668211
A2 Current:0.109164
2016-06-23T21:33:47.712Z,1466717627.713 [CBIT](IMPORTANT): A3 Volts:2.618537
A3 Current:0.076120
2016-06-23T21:33:48.970Z,1466717628.970 [CBIT](IMPORTANT): B0 Volts:2.389779
B0 Current:-0.069908
2016-06-23T21:33:50.245Z,1466717630.245 [CBIT](IMPORTANT): B1 Volts:2.283690
B1 Current:-0.137369
2016-06-23T21:33:50.353Z,1466717630.353 [NAL9602](INFO): SBD MO Status=0, MOMSN=504, MT Status=0, MTMSN=0
2016-06-23T21:33:50.354Z,1466717630.354 [NAL9602](INFO): No messages in MT queue
2016-06-23T21:33:51.777Z,1466717631.777 [CBIT](IMPORTANT): B2 Volts:2.283347
B2 Current:-0.138234
2016-06-23T21:33:52.996Z,1466717632.996 [CBIT](IMPORTANT): B3 Volts:1.771502
B3 Current:-0.462730
2016-06-23T21:33:53.194Z,1466717633.194 [CBIT](FAULT): Low side GF detected
mA:
CHAN A0 (Batt): 0.106796
CHAN A1 (24V): -0.294970
CHAN A2 (12V): 0.109164
CHAN A3 (5V): 0.076120
CHAN B0 (3.3V): -0.069908
CHAN B1 (3.15aV): -0.137369
CHAN B2 (3.15bV): -0.138234
CHAN B3 (GND): -0.462730
OPEN: 0.000716
Full Scale Calc: 4.765 mA, -1.589 mA
2016-06-23T21:34:01.374Z,1466717641.374 [NAL9602](INFO): GPS fix at 20160623T213359: (36.802652, -121.787080)
2016-06-23T21:34:02.023Z,1466717642.023 [DeadReckonUsingMultipleVelocitySources](FAULT): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T21:34:02.023Z,1466717642.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2016-06-23T21:34:02.023Z,1466717642.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T21:34:02.242Z,1466717642.242 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T21:34:02.429Z,1466717642.429 [PNI_TCM](ERROR): CRC does not match. Expected:0x24681 got:0x41998
2016-06-23T21:34:02.653Z,1466717642.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T21:34:02.654Z,1466717642.654 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2016-06-23T21:34:02.736Z,1466717642.736 [PNI_TCM](ERROR): CRC does not match. Expected:0x21665 got:0x41952
2016-06-23T21:34:02.750Z,1466717642.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T21:34:02.751Z,1466717642.750 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T21:34:02.751Z,1466717642.751 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T21:34:03.121Z,1466717643.121 [PNI_TCM](ERROR): CRC does not match. Expected:0x26987 got:0x41955
2016-06-23T21:34:03.512Z,1466717643.512 [PNI_TCM](ERROR): CRC does not match. Expected:0x19110 got:0x42009
2016-06-23T21:34:03.992Z,1466717643.992 [PNI_TCM](ERROR): CRC does not match. Expected:0x62993 got:0x42030
2016-06-23T21:34:04.404Z,1466717644.404 [PNI_TCM](ERROR): CRC does not match. Expected:0x17341 got:0x42028
2016-06-23T21:34:04.754Z,1466717644.754 [PNI_TCM](ERROR): CRC does not match. Expected:0x36564 got:0x41990
2016-06-23T21:34:05.129Z,1466717645.129 [PNI_TCM](ERROR): CRC does not match. Expected:0x20838 got:0x41959
2016-06-23T21:34:05.511Z,1466717645.511 [PNI_TCM](ERROR): CRC does not match. Expected:0x49237 got:0x42008
2016-06-23T21:34:05.911Z,1466717645.911 [PNI_TCM](ERROR): CRC does not match. Expected:0x6325 got:0x42146
2016-06-23T21:34:06.416Z,1466717646.416 [PNI_TCM](ERROR): CRC does not match. Expected:0x37113 got:0x42162
2016-06-23T21:34:06.809Z,1466717646.809 [PNI_TCM](ERROR): CRC does not match. Expected:0x39799 got:0x42029
2016-06-23T21:34:07.170Z,1466717647.170 [PNI_TCM](ERROR): CRC does not match. Expected:0x24688 got:0x42002
2016-06-23T21:34:07.524Z,1466717647.524 [PNI_TCM](ERROR): CRC does not match. Expected:0x53275 got:0x41975
2016-06-23T21:34:07.991Z,1466717647.991 [PNI_TCM](ERROR): CRC does not match. Expected:0x52187 got:0x41996
2016-06-23T21:34:08.346Z,1466717648.346 [PNI_TCM](ERROR): CRC does not match. Expected:0x39368 got:0x42060
2016-06-23T21:34:08.700Z,1466717648.700 [NAL9602](INFO): Powering down
2016-06-23T21:34:08.726Z,1466717648.726 [PNI_TCM](ERROR): CRC does not match. Expected:0x47088 got:0x42002
2016-06-23T21:34:09.116Z,1466717649.116 [PNI_TCM](ERROR): CRC does not match. Expected:0x53275 got:0x41895
2016-06-23T21:34:09.523Z,1466717649.523 [PNI_TCM](ERROR): CRC does not match. Expected:0x61037 got:0x42007
2016-06-23T21:34:10.143Z,1466717650.143 [PNI_TCM](ERROR): CRC does not match. Expected:0x46156 got:0x41936
2016-06-23T21:34:10.568Z,1466717650.568 [PNI_TCM](ERROR): CRC does not match. Expected:0x30201 got:0x41903
2016-06-23T21:34:10.797Z,1466717650.797 [PNI_TCM](ERROR): CRC does not match. Expected:0x60330 got:0x41895
2016-06-23T21:34:11.208Z,1466717651.208 [PNI_TCM](ERROR): CRC does not match. Expected:0x61037 got:0x41929
2016-06-23T21:34:11.619Z,1466717651.619 [PNI_TCM](ERROR): CRC does not match. Expected:0x38164 got:0x41956
2016-06-23T21:34:12.041Z,1466717652.041 [PNI_TCM](ERROR): CRC does not match. Expected:0x58264 got:0x41956
2016-06-23T21:34:12.042Z,1466717652.042 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-06-23T21:34:12.042Z,1466717652.042 [PNI_TCM] Data Fault, FailCount= 1
2016-06-23T21:34:12.042Z,1466717652.042 [PNI_TCM](ERROR): Data Fault
2016-06-23T21:34:12.098Z,1466717652.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.392800 seconds old.
2016-06-23T21:34:12.107Z,1466717652.107 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.398900 seconds old.
2016-06-23T21:34:12.256Z,1466717652.256 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-06-23T21:34:12.443Z,1466717652.443 [PNI_TCM](INFO): Powering down
2016-06-23T21:34:12.477Z,1466717652.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.771700 seconds old.
2016-06-23T21:34:12.489Z,1466717652.489 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.781400 seconds old.
2016-06-23T21:34:12.789Z,1466717652.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.083500 seconds old.
2016-06-23T21:34:12.790Z,1466717652.790 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.082900 seconds old.
2016-06-23T21:34:13.195Z,1466717653.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.490000 seconds old.
2016-06-23T21:34:13.197Z,1466717653.197 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.489400 seconds old.
2016-06-23T21:34:13.673Z,1466717653.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.967600 seconds old.
2016-06-23T21:34:13.688Z,1466717653.688 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.981000 seconds old.
2016-06-23T21:34:13.776Z,1466717653.776 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-06-23T21:34:13.776Z,1466717653.776 [PNI_TCM] No Fault, FailCount= 1
2016-06-23T21:34:14.027Z,1466717654.027 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.321300 seconds old.
2016-06-23T21:34:14.031Z,1466717654.031 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.322800 seconds old.
2016-06-23T21:34:14.434Z,1466717654.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.728200 seconds old.
2016-06-23T21:34:14.441Z,1466717654.441 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.732900 seconds old.
2016-06-23T21:34:14.831Z,1466717654.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.126400 seconds old.
2016-06-23T21:34:14.833Z,1466717654.833 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.125600 seconds old.
2016-06-23T21:34:15.187Z,1466717655.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.482100 seconds old.
2016-06-23T21:34:15.189Z,1466717655.188 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.481300 seconds old.
2016-06-23T21:34:15.631Z,1466717655.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.925100 seconds old.
2016-06-23T21:34:15.641Z,1466717655.641 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.932900 seconds old.
2016-06-23T21:34:16.079Z,1466717656.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.369500 seconds old.
2016-06-23T21:34:16.087Z,1466717656.087 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.375200 seconds old.
2016-06-23T21:34:16.450Z,1466717656.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.744600 seconds old.
2016-06-23T21:34:16.467Z,1466717656.467 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.759700 seconds old.
2016-06-23T21:34:16.824Z,1466717656.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.119200 seconds old.
2016-06-23T21:34:16.826Z,1466717656.826 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.118400 seconds old.
2016-06-23T21:34:34.649Z,1466717674.649 [SBIT](IMPORTANT): SBIT PASSED
2016-06-23T21:34:34.909Z,1466717674.908 [MissionManager](IMPORTANT): Started mission Startup
2016-06-23T21:34:34.909Z,1466717674.909 [Startup] Running Loop=1
2016-06-23T21:34:34.909Z,1466717674.909 [Startup](DEBUG): Aggregate::initialize Startup
2016-06-23T21:34:34.909Z,1466717674.909 [Startup:A.GoToSurface] Running Loop=1
2016-06-23T21:34:34.909Z,1466717674.909 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2016-06-23T21:34:34.910Z,1466717674.910 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2016-06-23T21:34:34.910Z,1466717674.910 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2016-06-23T21:34:34.910Z,1466717674.911 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2016-06-23T21:34:34.911Z,1466717674.911 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2016-06-23T21:34:34.911Z,1466717674.911 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2016-06-23T21:34:34.917Z,1466717674.917 [Startup:StartupSatComms] Running Loop=1
2016-06-23T21:34:34.917Z,1466717674.917 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2016-06-23T21:34:34.917Z,1466717674.917 [Startup:StartupSatComms:A] Running Loop=1
2016-06-23T21:34:35.326Z,1466717675.326 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2016-06-23T21:34:35.653Z,1466717675.653 [NAL9602](INFO): Powering up
2016-06-23T21:34:36.788Z,1466717676.787 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T21:34:46.608Z,1466717686.608 [NAL9602](INFO): NAL9602 initialized
2016-06-23T21:35:09.129Z,1466717709.129 [PNI_TCM](ERROR): CRC does not match. Expected:0x11678 got:0x6461
2016-06-23T21:35:09.668Z,1466717709.668 [PNI_TCM](ERROR): CRC does not match. Expected:0x7533 got:0x6462
2016-06-23T21:35:09.670Z,1466717709.670 [PNI_TCM](ERROR): CRC does not match. Expected:0x36824 got:0x6462
2016-06-23T21:35:10.016Z,1466717710.016 [PNI_TCM](ERROR): CRC does not match. Expected:0x64035 got:0x6462
2016-06-23T21:35:10.398Z,1466717710.398 [PNI_TCM](ERROR): CRC does not match. Expected:0x23922 got:0x48393
2016-06-23T21:35:11.149Z,1466717711.149 [PNI_TCM](ERROR): CRC does not match. Expected:0x30770 got:0x25972
2016-06-23T21:35:11.610Z,1466717711.610 [PNI_TCM](ERROR): CRC does not match. Expected:0x35149 got:0x3498
2016-06-23T21:35:11.620Z,1466717711.620 [PNI_TCM](ERROR): CRC does not match. Expected:0x3381 got:0x62208
2016-06-23T21:35:12.081Z,1466717712.081 [NAL9602](INFO): SBD MO Status=0, MOMSN=505, MT Status=0, MTMSN=0
2016-06-23T21:35:12.082Z,1466717712.082 [NAL9602](INFO): No messages in MT queue
2016-06-23T21:35:12.220Z,1466717712.220 [PNI_TCM](ERROR): CRC does not match. Expected:0x65519 got:0x30244
2016-06-23T21:35:12.595Z,1466717712.595 [PNI_TCM](ERROR): CRC does not match. Expected:0x24842 got:0x31927
2016-06-23T21:35:12.878Z,1466717712.878 [PNI_TCM](ERROR): CRC does not match. Expected:0x58146 got:0x41393
2016-06-23T21:35:13.285Z,1466717713.285 [PNI_TCM](ERROR): CRC does not match. Expected:0x5159 got:0x2021
2016-06-23T21:35:13.777Z,1466717713.777 [PNI_TCM](ERROR): CRC does not match. Expected:0x52482 got:0x58832
2016-06-23T21:35:14.156Z,1466717714.156 [PNI_TCM](ERROR): CRC does not match. Expected:0x10406 got:0x40348
2016-06-23T21:35:14.546Z,1466717714.546 [PNI_TCM](ERROR): CRC does not match. Expected:0x4384 got:0x36212
2016-06-23T21:35:14.902Z,1466717714.902 [PNI_TCM](ERROR): CRC does not match. Expected:0x27474 got:0x8311
2016-06-23T21:35:15.293Z,1466717715.293 [PNI_TCM](ERROR): CRC does not match. Expected:0x1799 got:0x26896
2016-06-23T21:35:15.790Z,1466717715.790 [PNI_TCM](ERROR): CRC does not match. Expected:0x44949 got:0x29215
2016-06-23T21:35:16.248Z,1466717716.248 [PNI_TCM](ERROR): CRC does not match. Expected:0x57220 got:0x43996
2016-06-23T21:35:16.650Z,1466717716.650 [PNI_TCM](ERROR): CRC does not match. Expected:0x32418 got:0x58788
2016-06-23T21:35:16.916Z,1466717716.916 [PNI_TCM](ERROR): CRC does not match. Expected:0x24240 got:0x39309
2016-06-23T21:35:17.317Z,1466717717.316 [PNI_TCM](ERROR): CRC does not match. Expected:0x37632 got:0x13035
2016-06-23T21:35:17.721Z,1466717717.721 [PNI_TCM](ERROR): CRC does not match. Expected:0x444 got:0x33116
2016-06-23T21:35:18.206Z,1466717718.206 [PNI_TCM](ERROR): CRC does not match. Expected:0x48282 got:0x40143
2016-06-23T21:35:18.207Z,1466717718.207 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-06-23T21:35:18.207Z,1466717718.207 [PNI_TCM] Data Fault, FailCount= 1
2016-06-23T21:35:18.208Z,1466717718.208 [PNI_TCM](ERROR): Data Fault
2016-06-23T21:35:18.238Z,1466717718.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.469400 seconds old.
2016-06-23T21:35:18.247Z,1466717718.247 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.477100 seconds old.
2016-06-23T21:35:18.397Z,1466717718.397 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-06-23T21:35:18.561Z,1466717718.561 [PNI_TCM](INFO): Powering down
2016-06-23T21:35:18.603Z,1466717718.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.834300 seconds old.
2016-06-23T21:35:18.606Z,1466717718.606 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.836600 seconds old.
2016-06-23T21:35:18.988Z,1466717718.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.215000 seconds old.
2016-06-23T21:35:18.996Z,1466717718.996 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.222000 seconds old.
2016-06-23T21:35:19.332Z,1466717719.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.564000 seconds old.
2016-06-23T21:35:19.333Z,1466717719.333 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.564400 seconds old.
2016-06-23T21:35:19.812Z,1466717719.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.044000 seconds old.
2016-06-23T21:35:19.824Z,1466717719.824 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.054700 seconds old.
2016-06-23T21:35:19.980Z,1466717719.980 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-06-23T21:35:19.980Z,1466717719.980 [PNI_TCM] No Fault, FailCount= 1
2016-06-23T21:35:20.213Z,1466717720.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.444000 seconds old.
2016-06-23T21:35:20.248Z,1466717720.248 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.457300 seconds old.
2016-06-23T21:35:20.582Z,1466717720.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.813600 seconds old.
2016-06-23T21:35:20.591Z,1466717720.591 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.814000 seconds old.
2016-06-23T21:35:20.938Z,1466717720.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.170100 seconds old.
2016-06-23T21:35:20.958Z,1466717720.958 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.189200 seconds old.
2016-06-23T21:35:21.328Z,1466717721.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.559600 seconds old.
2016-06-23T21:35:21.329Z,1466717721.329 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.560000 seconds old.
2016-06-23T21:35:21.794Z,1466717721.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.025700 seconds old.
2016-06-23T21:35:21.808Z,1466717721.808 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.038700 seconds old.
2016-06-23T21:35:22.198Z,1466717722.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.429700 seconds old.
2016-06-23T21:35:22.202Z,1466717722.202 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.432400 seconds old.
2016-06-23T21:35:22.572Z,1466717722.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.803400 seconds old.
2016-06-23T21:35:22.573Z,1466717722.573 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.803800 seconds old.
2016-06-23T21:35:22.910Z,1466717722.910 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.141600 seconds old.
2016-06-23T21:35:22.911Z,1466717722.911 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.142000 seconds old.
2016-06-23T21:35:28.189Z,1466717728.189 [PNI_TCM](ERROR): CRC does not match. Expected:0x50633 got:0x41842
2016-06-23T21:35:28.602Z,1466717728.602 [PNI_TCM](ERROR): CRC does not match. Expected:0x62460 got:0x41756
2016-06-23T21:35:29.311Z,1466717729.311 [PNI_TCM](ERROR): CRC does not match. Expected:0x34949 got:0x41704
2016-06-23T21:35:29.714Z,1466717729.714 [PNI_TCM](ERROR): CRC does not match. Expected:0x54348 got:0x41744
2016-06-23T21:35:30.214Z,1466717730.214 [PNI_TCM](ERROR): CRC does not match. Expected:0x1969 got:0x41807
2016-06-23T21:35:30.243Z,1466717730.243 [PNI_TCM](ERROR): CRC does not match. Expected:0x36606 got:0x41896
2016-06-23T21:35:30.676Z,1466717730.676 [PNI_TCM](ERROR): CRC does not match. Expected:0x17044 got:0x41714
2016-06-23T21:35:31.005Z,1466717731.005 [NAL9602](INFO): GPS fix at 20160623T213529: (36.802660, -121.787103)
2016-06-23T21:35:31.064Z,1466717731.064 [PNI_TCM](ERROR): CRC does not match. Expected:0x5996 got:0x41627
2016-06-23T21:35:31.117Z,1466717731.117 [Startup:StartupSatComms:A] Stopped
2016-06-23T21:35:31.117Z,1466717731.117 [Startup:StartupSatComms:B] Running Loop=1
2016-06-23T21:35:31.384Z,1466717731.384 [PNI_TCM](ERROR): CRC does not match. Expected:0x50475 got:0x41691
2016-06-23T21:35:31.401Z,1466717731.401 [DeadReckonUsingMultipleVelocitySources](FAULT): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T21:35:31.401Z,1466717731.401 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2016-06-23T21:35:31.401Z,1466717731.401 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T21:35:31.426Z,1466717731.426 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2016-06-23T21:35:31.464Z,1466717731.464 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T21:35:32.767Z,1466717732.767 [PNI_TCM](ERROR): CRC does not match. Expected:0x60179 got:0x41735
2016-06-23T21:35:32.768Z,1466717732.768 [PNI_TCM](ERROR): CRC does not match. Expected:0x42241 got:0x41683
2016-06-23T21:35:32.768Z,1466717732.768 [PNI_TCM](ERROR): CRC does not match. Expected:0x61140 got:0x41634
2016-06-23T21:35:32.845Z,1466717732.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T21:35:32.845Z,1466717732.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2016-06-23T21:35:33.161Z,1466717733.161 [PNI_TCM](ERROR): CRC does not match. Expected:0x13018 got:0x41641
2016-06-23T21:35:33.172Z,1466717733.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T21:35:33.172Z,1466717733.172 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T21:35:33.172Z,1466717733.172 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T21:35:33.689Z,1466717733.689 [PNI_TCM](ERROR): CRC does not match. Expected:0x5328 got:0x41648
2016-06-23T21:35:34.080Z,1466717734.080 [PNI_TCM](ERROR): CRC does not match. Expected:0x62525 got:0x41616
2016-06-23T21:35:34.451Z,1466717734.451 [PNI_TCM](ERROR): CRC does not match. Expected:0x58145 got:0x41615
2016-06-23T21:35:34.778Z,1466717734.778 [PNI_TCM](ERROR): CRC does not match. Expected:0x17494 got:0x41635
2016-06-23T21:35:35.175Z,1466717735.175 [PNI_TCM](ERROR): CRC does not match. Expected:0x56446 got:0x41613
2016-06-23T21:35:35.669Z,1466717735.669 [PNI_TCM](ERROR): CRC does not match. Expected:0x35135 got:0x41616
2016-06-23T21:35:36.053Z,1466717736.053 [PNI_TCM](ERROR): CRC does not match. Expected:0x58145 got:0x41611
2016-06-23T21:35:36.502Z,1466717736.502 [PNI_TCM](ERROR): CRC does not match. Expected:0x52901 got:0x41599
2016-06-23T21:35:36.761Z,1466717736.761 [PNI_TCM](ERROR): CRC does not match. Expected:0x10892 got:0x41661
2016-06-23T21:35:37.186Z,1466717737.187 [PNI_TCM](ERROR): CRC does not match. Expected:0x38317 got:0x41774
2016-06-23T21:35:37.621Z,1466717737.621 [PNI_TCM](ERROR): CRC does not match. Expected:0x22910 got:0x41813
2016-06-23T21:35:38.073Z,1466717738.073 [PNI_TCM](ERROR): CRC does not match. Expected:0x19934 got:0x41765
2016-06-23T21:35:38.074Z,1466717738.074 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-06-23T21:35:38.075Z,1466717738.075 [PNI_TCM] Data Fault, FailCount= 1
2016-06-23T21:35:38.083Z,1466717738.083 [PNI_TCM](ERROR): Data Fault
2016-06-23T21:35:38.145Z,1466717738.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.462300 seconds old.
2016-06-23T21:35:38.159Z,1466717738.158 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.467100 seconds old.
2016-06-23T21:35:38.318Z,1466717738.318 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-06-23T21:35:38.432Z,1466717738.432 [PNI_TCM](INFO): Powering down
2016-06-23T21:35:38.497Z,1466717738.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.814800 seconds old.
2016-06-23T21:35:38.512Z,1466717738.513 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.816400 seconds old.
2016-06-23T21:35:38.775Z,1466717738.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.093300 seconds old.
2016-06-23T21:35:38.777Z,1466717738.777 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.085900 seconds old.
2016-06-23T21:35:39.188Z,1466717739.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.505600 seconds old.
2016-06-23T21:35:39.189Z,1466717739.189 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.498200 seconds old.
2016-06-23T21:35:39.699Z,1466717739.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.016000 seconds old.
2016-06-23T21:35:39.736Z,1466717739.736 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.035900 seconds old.
2016-06-23T21:35:39.911Z,1466717739.911 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-06-23T21:35:39.912Z,1466717739.912 [PNI_TCM] No Fault, FailCount= 1
2016-06-23T21:35:40.081Z,1466717740.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.398800 seconds old.
2016-06-23T21:35:40.093Z,1466717740.092 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.401100 seconds old.
2016-06-23T21:35:40.424Z,1466717740.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.742100 seconds old.
2016-06-23T21:35:40.441Z,1466717740.441 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.749600 seconds old.
2016-06-23T21:35:40.823Z,1466717740.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.141500 seconds old.
2016-06-23T21:35:40.825Z,1466717740.825 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.134100 seconds old.
2016-06-23T21:35:41.553Z,1466717741.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.871100 seconds old.
2016-06-23T21:35:41.554Z,1466717741.554 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.863700 seconds old.
2016-06-23T21:35:41.679Z,1466717741.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.997600 seconds old.
2016-06-23T21:35:41.681Z,1466717741.681 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.990200 seconds old.
2016-06-23T21:35:44.151Z,1466717744.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.468800 seconds old.
2016-06-23T21:35:44.154Z,1466717744.154 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.463400 seconds old.
2016-06-23T21:35:44.382Z,1466717744.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.699700 seconds old.
2016-06-23T21:35:44.393Z,1466717744.393 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.701600 seconds old.
2016-06-23T21:35:44.700Z,1466717744.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.017700 seconds old.
2016-06-23T21:35:44.701Z,1466717744.701 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.010300 seconds old.
2016-06-23T21:35:55.246Z,1466717755.246 [NAL9602](INFO): SBD MO Status=1, MOMSN=506, MT Status=0, MTMSN=0
2016-06-23T21:35:55.296Z,1466717755.296 [NAL9602](INFO): Sent 18 bytes from file Logs/20160623T211243/Courier0012.lzma
2016-06-23T21:35:55.296Z,1466717755.296 [NAL9602](INFO): Packets left to send: 0
2016-06-23T21:35:55.297Z,1466717755.297 [NAL9602](DEBUG): Stored copy of sent data in Logs/20160623T211243/Courier0012.lzma.parts/0000.sbd
2016-06-23T21:35:55.298Z,1466717755.298 [NAL9602](DEBUG): Completed sending Logs/20160623T211243/Courier0012.lzma
2016-06-23T21:35:57.404Z,1466717757.404 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T21:36:02.950Z,1466717762.950 [NAL9602](INFO): SBD MO Status=1, MOMSN=507, MT Status=0, MTMSN=0
2016-06-23T21:36:03.013Z,1466717763.013 [NAL9602](INFO): Sent 212 bytes from file Logs/20160623T213301/Courier0000.lzma
2016-06-23T21:36:03.014Z,1466717763.013 [NAL9602](INFO): Packets left to send: 0
2016-06-23T21:36:03.015Z,1466717763.015 [NAL9602](DEBUG): Stored copy of sent data in Logs/20160623T213301/Courier0000.lzma.parts/0000.sbd
2016-06-23T21:36:03.015Z,1466717763.015 [NAL9602](DEBUG): Completed sending Logs/20160623T213301/Courier0000.lzma
2016-06-23T21:36:10.787Z,1466717770.787 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T21:36:15.486Z,1466717775.486 [NAL9602](INFO): SBD MO Status=1, MOMSN=508, MT Status=0, MTMSN=0
2016-06-23T21:36:15.536Z,1466717775.536 [NAL9602](INFO): Sent 332 bytes from file Logs/20160623T205846/Express0001.lzma
2016-06-23T21:36:15.537Z,1466717775.537 [NAL9602](INFO): Packets left to send: 2
2016-06-23T21:36:15.541Z,1466717775.541 [NAL9602](DEBUG): Stored copy of sent data in Logs/20160623T205846/Express0001.lzma.parts/0002.sbd
2016-06-23T21:36:17.755Z,1466717777.755 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T21:36:22.728Z,1466717782.728 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T21:36:31.474Z,1466717791.474 [Startup:StartupSatComms:B](INFO): Timed out from 2016-06-23T21:35:31.1Z
2016-06-23T21:36:31.475Z,1466717791.475 [Startup:StartupSatComms:B] Stopped
2016-06-23T21:36:31.475Z,1466717791.475 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2016-06-23T21:36:31.476Z,1466717791.476 [Startup:StartupSatComms] Stopped
2016-06-23T21:36:31.476Z,1466717791.476 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2016-06-23T21:36:31.478Z,1466717791.478 [Startup](INFO): Completed Startup
2016-06-23T21:36:31.478Z,1466717791.478 [Startup] Stopped
2016-06-23T21:36:31.479Z,1466717791.479 [Startup](DEBUG): Aggregate::uninitialize Startup
2016-06-23T21:36:31.479Z,1466717791.479 [Startup:A.GoToSurface] Stopped
2016-06-23T21:36:31.479Z,1466717791.479 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2016-06-23T21:36:32.082Z,1466717792.082 [MissionManager](IMPORTANT): Started mission Default
2016-06-23T21:36:32.087Z,1466717792.087 [Default] Running Loop=1
2016-06-23T21:36:32.088Z,1466717792.088 [Default](DEBUG): Aggregate::initialize Default
2016-06-23T21:36:32.088Z,1466717792.088 [Default:B.GoToSurface] Running Loop=1
2016-06-23T21:36:32.088Z,1466717792.088 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2016-06-23T21:36:32.089Z,1466717792.089 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2016-06-23T21:36:32.089Z,1466717792.089 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2016-06-23T21:36:32.090Z,1466717792.090 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2016-06-23T21:36:32.112Z,1466717792.112 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2016-06-23T21:36:32.113Z,1466717792.113 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2016-06-23T21:36:32.113Z,1466717792.113 [Default:A.Wait] Running Loop=1
2016-06-23T21:36:32.113Z,1466717792.114 [Default:A.Wait](DEBUG): Initialize Wait Component.
2016-06-23T21:36:38.741Z,1466717798.741 [NAL9602](INFO): SBD MO Status=1, MOMSN=509, MT Status=0, MTMSN=0
2016-06-23T21:36:38.791Z,1466717798.791 [NAL9602](INFO): Sent 332 bytes from file Logs/20160623T205846/Express0001.lzma
2016-06-23T21:36:38.792Z,1466717798.792 [NAL9602](INFO): Packets left to send: 1
2016-06-23T21:36:38.793Z,1466717798.793 [NAL9602](DEBUG): Stored copy of sent data in Logs/20160623T205846/Express0001.lzma.parts/0001.sbd
2016-06-23T21:36:44.362Z,1466717804.362 [NAL9602](INFO): Powering down
2016-06-23T21:36:45.201Z,1466717805.201 [Default:A.Wait](INFO): Done Waiting.
2016-06-23T21:36:45.201Z,1466717805.201 [Default:A.Wait] Stopped
2016-06-23T21:36:45.201Z,1466717805.201 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2016-06-23T21:36:45.732Z,1466717805.732 [Default:CheckIn] Running Loop=1
2016-06-23T21:36:45.733Z,1466717805.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-06-23T21:36:45.733Z,1466717805.733 [Default:CheckIn:Read_GPS] Running Loop=1
2016-06-23T21:36:46.220Z,1466717806.220 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2016-06-23T21:36:46.449Z,1466717806.449 [NAL9602](INFO): Powering up
2016-06-23T21:36:57.316Z,1466717817.316 [NAL9602](INFO): NAL9602 initialized
2016-06-23T21:37:01.813Z,1466717821.813 [BPC1](ERROR): Failed to parse bank A battery data
2016-06-23T21:37:01.814Z,1466717821.814 [BPC1] Data Fault, FailCount= 1
2016-06-23T21:37:01.814Z,1466717821.814 [BPC1](ERROR): Data Fault
2016-06-23T21:37:02.009Z,1466717822.009 [CBIT](ERROR): Data Fault in component: BPC1
2016-06-23T21:37:02.992Z,1466717822.992 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T21:37:02.992Z,1466717822.992 [BPC1] No Fault, FailCount= 1
2016-06-23T21:37:04.275Z,1466717824.275 [PNI_TCM](ERROR): CRC does not match. Expected:0x14144 got:0x6462
2016-06-23T21:37:04.988Z,1466717824.988 [PNI_TCM](ERROR): CRC does not match. Expected:0x52562 got:0x6462
2016-06-23T21:37:05.391Z,1466717825.391 [PNI_TCM](ERROR): CRC does not match. Expected:0x25448 got:0x6462
2016-06-23T21:37:05.817Z,1466717825.817 [PNI_TCM](ERROR): CRC does not match. Expected:0x33841 got:0x6462
2016-06-23T21:37:06.266Z,1466717826.266 [PNI_TCM](ERROR): CRC does not match. Expected:0x45287 got:0x6462
2016-06-23T21:37:06.268Z,1466717826.268 [PNI_TCM](ERROR): CRC does not match. Expected:0x7641 got:0x6462
2016-06-23T21:37:06.692Z,1466717826.692 [PNI_TCM](ERROR): CRC does not match. Expected:0x45327 got:0x11082
2016-06-23T21:37:07.484Z,1466717827.484 [PNI_TCM](ERROR): CRC does not match. Expected:0x41582 got:0x7997
2016-06-23T21:37:07.494Z,1466717827.494 [PNI_TCM](ERROR): CRC does not match. Expected:0x8760 got:0x10593
2016-06-23T21:37:07.863Z,1466717827.863 [PNI_TCM](ERROR): CRC does not match. Expected:0x17302 got:0x5380
2016-06-23T21:37:08.302Z,1466717828.302 [PNI_TCM](ERROR): CRC does not match. Expected:0x47120 got:0x57390
2016-06-23T21:37:08.993Z,1466717828.993 [PNI_TCM](ERROR): CRC does not match. Expected:0x59497 got:0x15428
2016-06-23T21:37:09.483Z,1466717829.483 [PNI_TCM](ERROR): CRC does not match. Expected:0x31293 got:0x56588
2016-06-23T21:37:09.485Z,1466717829.485 [PNI_TCM](ERROR): CRC does not match. Expected:0x7158 got:0x15060
2016-06-23T21:37:09.889Z,1466717829.889 [PNI_TCM](ERROR): CRC does not match. Expected:0x26861 got:0x59203
2016-06-23T21:37:10.261Z,1466717830.261 [PNI_TCM](ERROR): CRC does not match. Expected:0x52060 got:0x37609
2016-06-23T21:37:10.993Z,1466717830.993 [PNI_TCM](ERROR): CRC does not match. Expected:0x44531 got:0x44836
2016-06-23T21:37:11.523Z,1466717831.523 [PNI_TCM](ERROR): CRC does not match. Expected:0x30358 got:0x65341
2016-06-23T21:37:11.530Z,1466717831.530 [PNI_TCM](ERROR): CRC does not match. Expected:0x5812 got:0x16800
2016-06-23T21:37:11.911Z,1466717831.911 [PNI_TCM](ERROR): CRC does not match. Expected:0x31763 got:0x14955
2016-06-23T21:37:12.260Z,1466717832.260 [PNI_TCM](ERROR): CRC does not match. Expected:0x62844 got:0x16930
2016-06-23T21:37:12.617Z,1466717832.617 [PNI_TCM](ERROR): CRC does not match. Expected:0x2370 got:0x16930
2016-06-23T21:37:13.519Z,1466717833.519 [PNI_TCM](ERROR): CRC does not match. Expected:0x572 got:0x16930
2016-06-23T21:37:13.533Z,1466717833.533 [PNI_TCM](ERROR): CRC does not match. Expected:0x4427 got:0x16930
2016-06-23T21:37:13.878Z,1466717833.878 [PNI_TCM](ERROR): CRC does not match. Expected:0x3294 got:0x16930
2016-06-23T21:37:14.276Z,1466717834.276 [PNI_TCM](ERROR): CRC does not match. Expected:0x33190 got:0x43
2016-06-23T21:37:14.276Z,1466717834.276 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-06-23T21:37:14.277Z,1466717834.277 [PNI_TCM] Data Fault, FailCount= 1
2016-06-23T21:37:14.277Z,1466717834.277 [PNI_TCM](ERROR): Data Fault
2016-06-23T21:37:14.354Z,1466717834.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.407600 seconds old.
2016-06-23T21:37:14.362Z,1466717834.362 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.408500 seconds old.
2016-06-23T21:37:14.428Z,1466717834.428 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-06-23T21:37:14.578Z,1466717834.578 [PNI_TCM](INFO): Powering down
2016-06-23T21:37:14.600Z,1466717834.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.653800 seconds old.
2016-06-23T21:37:14.601Z,1466717834.601 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.647900 seconds old.
2016-06-23T21:37:14.983Z,1466717834.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.037600 seconds old.
2016-06-23T21:37:14.985Z,1466717834.985 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.031600 seconds old.
2016-06-23T21:37:15.501Z,1466717835.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.554700 seconds old.
2016-06-23T21:37:15.509Z,1466717835.509 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.555900 seconds old.
2016-06-23T21:37:15.804Z,1466717835.805 [NAL9602](INFO): SBD MO Status=0, MOMSN=510, MT Status=0, MTMSN=0
2016-06-23T21:37:15.805Z,1466717835.805 [NAL9602](INFO): No messages in MT queue
2016-06-23T21:37:15.973Z,1466717835.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.026300 seconds old.
2016-06-23T21:37:15.984Z,1466717835.984 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.022100 seconds old.
2016-06-23T21:37:16.084Z,1466717836.084 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-06-23T21:37:16.084Z,1466717836.084 [PNI_TCM] No Fault, FailCount= 1
2016-06-23T21:37:16.266Z,1466717836.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.319100 seconds old.
2016-06-23T21:37:16.278Z,1466717836.279 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.325000 seconds old.
2016-06-23T21:37:16.647Z,1466717836.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.673500 seconds old.
2016-06-23T21:37:16.649Z,1466717836.649 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.695700 seconds old.
2016-06-23T21:37:17.020Z,1466717837.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.074800 seconds old.
2016-06-23T21:37:17.022Z,1466717837.022 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.068700 seconds old.
2016-06-23T21:37:17.440Z,1466717837.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.494300 seconds old.
2016-06-23T21:37:17.441Z,1466717837.441 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.488300 seconds old.
2016-06-23T21:37:17.842Z,1466717837.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.895700 seconds old.
2016-06-23T21:37:17.859Z,1466717837.859 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.905100 seconds old.
2016-06-23T21:37:18.296Z,1466717838.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.345000 seconds old.
2016-06-23T21:37:18.304Z,1466717838.304 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.349700 seconds old.
2016-06-23T21:37:18.674Z,1466717838.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.727500 seconds old.
2016-06-23T21:37:18.678Z,1466717838.678 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.724400 seconds old.
2016-06-23T21:37:18.988Z,1466717838.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.041800 seconds old.
2016-06-23T21:37:18.989Z,1466717838.989 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.035800 seconds old.
2016-06-23T21:37:29.012Z,1466717849.012 [NAL9602](INFO): GPS fix at 20160623T213727: (36.802623, -121.787019)
2016-06-23T21:37:29.080Z,1466717849.080 [Default:CheckIn:Read_GPS] Stopped
2016-06-23T21:37:29.081Z,1466717849.081 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-06-23T21:37:29.451Z,1466717849.451 [DeadReckonUsingMultipleVelocitySources](FAULT): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T21:37:29.451Z,1466717849.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2016-06-23T21:37:29.475Z,1466717849.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T21:37:29.491Z,1466717849.491 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2016-06-23T21:37:29.518Z,1466717849.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T21:37:30.842Z,1466717850.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T21:37:30.842Z,1466717850.842 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2016-06-23T21:37:30.951Z,1466717850.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T21:37:30.952Z,1466717850.952 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T21:37:30.952Z,1466717850.952 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T21:37:46.075Z,1466717866.075 [NAL9602](INFO): SBD MO Status=1, MOMSN=511, MT Status=0, MTMSN=0
2016-06-23T21:37:46.124Z,1466717866.125 [NAL9602](INFO): Sent 62 bytes from file Logs/20160623T213301/Courier0004.lzma
2016-06-23T21:37:46.125Z,1466717866.125 [NAL9602](INFO): Packets left to send: 0
2016-06-23T21:37:46.129Z,1466717866.129 [NAL9602](DEBUG): Stored copy of sent data in Logs/20160623T213301/Courier0004.lzma.parts/0000.sbd
2016-06-23T21:37:46.130Z,1466717866.130 [NAL9602](DEBUG): Completed sending Logs/20160623T213301/Courier0004.lzma
2016-06-23T21:37:47.932Z,1466717867.932 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T21:37:47.966Z,1466717867.966 [NavChartDb](INFO): # of records loaded: 19732
2016-06-23T21:37:48.021Z,1466717868.021 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2016-06-23T21:37:48.033Z,1466717868.033 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T21:38:02.455Z,1466717882.455 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T21:38:07.856Z,1466717887.856 [NAL9602](INFO): SBD MO Status=1, MOMSN=512, MT Status=0, MTMSN=0
2016-06-23T21:38:07.904Z,1466717887.904 [NAL9602](INFO): Sent 101 bytes from file Logs/20160623T205846/Express0001.lzma
2016-06-23T21:38:07.905Z,1466717887.905 [NAL9602](INFO): Packets left to send: 0
2016-06-23T21:38:07.917Z,1466717887.917 [NAL9602](DEBUG): Stored copy of sent data in Logs/20160623T205846/Express0001.lzma.parts/0000.sbd
2016-06-23T21:38:07.917Z,1466717887.917 [NAL9602](DEBUG): Completed sending Logs/20160623T205846/Express0001.lzma
2016-06-23T21:38:28.177Z,1466717908.178 [NAL9602](INFO): SBD MO Status=2, MOMSN=513, MT Status=2, MTMSN=0
2016-06-23T21:38:28.178Z,1466717908.178 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2016-06-23T21:38:33.141Z,1466717913.141 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T21:38:41.701Z,1466717921.701 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.045300
2016-06-23T21:38:44.872Z,1466717924.872 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:45.669Z,1466717925.669 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:46.464Z,1466717926.464 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:47.262Z,1466717927.261 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:47.673Z,1466717927.673 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:48.085Z,1466717928.085 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:48.880Z,1466717928.880 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:49.605Z,1466717929.605 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:50.013Z,1466717930.013 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:50.808Z,1466717930.808 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:51.606Z,1466717931.606 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:52.017Z,1466717932.017 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:52.424Z,1466717932.424 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:52.454Z,1466717932.454 [NAL9602](INFO): Powering down
2016-06-23T21:38:52.936Z,1466717932.935 [DataOverHttps](INFO): Sending 687 bytes from file Logs/20160623T205846/Express0005.lzma
2016-06-23T21:38:53.220Z,1466717933.220 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:54.018Z,1466717934.017 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:54.603Z,1466717934.603 [DataOverHttps](INFO): Moved sent file to Logs/20160623T205846/Express0005.lzma.bak
2016-06-23T21:38:54.604Z,1466717934.604 [DataOverHttps](INFO): SBD MOMSN=4285464
2016-06-23T21:38:54.820Z,1466717934.820 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:55.225Z,1466717935.225 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:55.637Z,1466717935.637 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:56.045Z,1466717936.045 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:56.456Z,1466717936.456 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:57.253Z,1466717937.253 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:57.661Z,1466717937.661 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:58.077Z,1466717938.077 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:58.872Z,1466717938.872 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:59.581Z,1466717939.581 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:38:59.989Z,1466717939.989 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:00.784Z,1466717940.784 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:01.581Z,1466717941.581 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:01.993Z,1466717941.993 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:02.788Z,1466717942.788 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:03.593Z,1466717943.593 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:04.001Z,1466717944.001 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:04.408Z,1466717944.408 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:05.204Z,1466717945.204 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:05.235Z,1466717945.234 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T21:39:06.001Z,1466717946.001 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:06.409Z,1466717946.409 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:06.816Z,1466717946.816 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:07.569Z,1466717947.569 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:07.977Z,1466717947.977 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:08.772Z,1466717948.772 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:09.541Z,1466717949.541 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:09.953Z,1466717949.953 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:10.748Z,1466717950.748 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:11.545Z,1466717951.545 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:11.954Z,1466717951.954 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:12.372Z,1466717952.372 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:13.170Z,1466717953.170 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:13.581Z,1466717953.581 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:13.989Z,1466717953.989 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:14.784Z,1466717954.784 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:15.581Z,1466717955.581 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:15.989Z,1466717955.989 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:16.396Z,1466717956.396 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:17.193Z,1466717957.193 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:17.989Z,1466717957.989 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:18.388Z,1466717958.388 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20160623T205846/Express0009.lzma
2016-06-23T21:39:18.405Z,1466717958.405 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:19.135Z,1466717959.135 [DataOverHttps](INFO): Moved sent file to Logs/20160623T205846/Express0009.lzma.bak
2016-06-23T21:39:19.156Z,1466717959.156 [DataOverHttps](INFO): SBD MOMSN=4285467
2016-06-23T21:39:20.756Z,1466717960.756 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:21.557Z,1466717961.557 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:21.969Z,1466717961.969 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:22.764Z,1466717962.764 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:23.545Z,1466717963.545 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:23.965Z,1466717963.965 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:24.761Z,1466717964.761 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:25.553Z,1466717965.553 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:25.961Z,1466717965.961 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:26.756Z,1466717966.756 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:27.553Z,1466717967.553 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:27.985Z,1466717967.985 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:28.392Z,1466717968.392 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:29.188Z,1466717969.188 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:29.986Z,1466717969.986 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:30.397Z,1466717970.397 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:30.813Z,1466717970.813 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:31.542Z,1466717971.542 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:32.336Z,1466717972.336 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:33.133Z,1466717973.133 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:33.930Z,1466717973.930 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:34.065Z,1466717974.066 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T21:39:34.724Z,1466717974.724 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:35.525Z,1466717975.525 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:35.937Z,1466717975.937 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:36.732Z,1466717976.732 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:37.521Z,1466717977.522 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:37.933Z,1466717977.933 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:38.728Z,1466717978.728 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:39.525Z,1466717979.525 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:39.626Z,1466717979.626 [DataOverHttps](INFO): Sending 1317 bytes from file Logs/20160623T211243/Express0001.lzma
2016-06-23T21:39:39.935Z,1466717979.935 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:40.284Z,1466717980.284 [DataOverHttps](INFO): Moved sent file to Logs/20160623T211243/Express0001.lzma.bak
2016-06-23T21:39:40.284Z,1466717980.284 [DataOverHttps](INFO): SBD MOMSN=4285506
2016-06-23T21:39:40.344Z,1466717980.344 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:41.148Z,1466717981.148 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:41.945Z,1466717981.945 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:42.354Z,1466717982.354 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:43.149Z,1466717983.149 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:43.945Z,1466717983.945 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:44.741Z,1466717984.741 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:45.530Z,1466717985.530 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:45.941Z,1466717985.941 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:46.736Z,1466717986.736 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:47.485Z,1466717987.485 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:47.898Z,1466717987.898 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:48.692Z,1466717988.692 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:49.485Z,1466717989.485 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:49.897Z,1466717989.897 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:50.692Z,1466717990.692 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:51.356Z,1466717991.356 [BPC1](ERROR): Failed to receive battery data
2016-06-23T21:39:51.357Z,1466717991.357 [BPC1] Communications Fault, FailCount= 1
2016-06-23T21:39:51.357Z,1466717991.357 [BPC1](ERROR): Communications Fault
2016-06-23T21:39:51.489Z,1466717991.489 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:51.534Z,1466717991.534 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T21:39:51.901Z,1466717991.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:52.312Z,1466717992.312 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:52.430Z,1466717992.430 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T21:39:52.431Z,1466717992.431 [BPC1] No Fault, FailCount= 1
2016-06-23T21:39:53.109Z,1466717993.109 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:53.905Z,1466717993.905 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:54.313Z,1466717994.313 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:55.109Z,1466717995.109 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:55.905Z,1466717995.905 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:56.700Z,1466717996.700 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:57.498Z,1466717997.498 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:57.909Z,1466717997.909 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:58.316Z,1466717998.316 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:59.113Z,1466717999.113 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:59.525Z,1466717999.525 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:39:59.933Z,1466717999.933 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:00.728Z,1466718000.728 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:01.494Z,1466718001.494 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:01.902Z,1466718001.902 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:02.696Z,1466718002.696 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:03.493Z,1466718003.493 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:03.905Z,1466718003.905 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:04.312Z,1466718004.312 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:04.657Z,1466718004.657 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T21:40:04.981Z,1466718004.981 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20160623T211243/Express0005.lzma
2016-06-23T21:40:05.108Z,1466718005.108 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:05.792Z,1466718005.792 [DataOverHttps](INFO): Moved sent file to Logs/20160623T211243/Express0005.lzma.bak
2016-06-23T21:40:05.792Z,1466718005.792 [DataOverHttps](INFO): SBD MOMSN=4285556
2016-06-23T21:40:05.906Z,1466718005.906 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:06.318Z,1466718006.318 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:06.728Z,1466718006.728 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:07.485Z,1466718007.485 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:07.893Z,1466718007.893 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:08.688Z,1466718008.688 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:09.482Z,1466718009.482 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:09.901Z,1466718009.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:10.696Z,1466718010.696 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:11.470Z,1466718011.470 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:11.894Z,1466718011.894 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:12.689Z,1466718012.689 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:13.473Z,1466718013.473 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:13.885Z,1466718013.885 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:14.680Z,1466718014.680 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:15.477Z,1466718015.477 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:15.889Z,1466718015.889 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:16.296Z,1466718016.296 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:17.092Z,1466718017.092 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:17.889Z,1466718017.889 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:18.298Z,1466718018.297 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:18.708Z,1466718018.708 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:19.461Z,1466718019.461 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:20.256Z,1466718020.256 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:21.054Z,1466718021.053 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:21.850Z,1466718021.849 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:22.644Z,1466718022.644 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:23.441Z,1466718023.441 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:23.850Z,1466718023.849 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:24.256Z,1466718024.256 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:25.054Z,1466718025.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:25.465Z,1466718025.465 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:25.878Z,1466718025.878 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:26.672Z,1466718026.672 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:27.469Z,1466718027.469 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:27.881Z,1466718027.881 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:28.292Z,1466718028.292 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:28.845Z,1466718028.845 [DataOverHttps](INFO): Sending 400 bytes from file Logs/20160623T211243/Express0009.lzma
2016-06-23T21:40:29.088Z,1466718029.088 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:29.633Z,1466718029.633 [DataOverHttps](INFO): Moved sent file to Logs/20160623T211243/Express0009.lzma.bak
2016-06-23T21:40:29.634Z,1466718029.634 [DataOverHttps](INFO): SBD MOMSN=4285560
2016-06-23T21:40:29.877Z,1466718029.878 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:30.289Z,1466718030.289 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:31.117Z,1466718031.117 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:31.538Z,1466718031.538 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:31.958Z,1466718031.958 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:32.752Z,1466718032.752 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:33.449Z,1466718033.450 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:33.861Z,1466718033.861 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:34.656Z,1466718034.656 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:35.453Z,1466718035.453 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:35.861Z,1466718035.861 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:36.268Z,1466718036.268 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:37.066Z,1466718037.066 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:37.477Z,1466718037.477 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:37.885Z,1466718037.885 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:38.680Z,1466718038.680 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:39.473Z,1466718039.473 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:39.743Z,1466718039.743 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T21:40:39.881Z,1466718039.881 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:40.364Z,1466718040.364 [PNI_TCM](ERROR): CRC does not match. Expected:0x10950 got:0x41714
2016-06-23T21:40:40.365Z,1466718040.365 [PNI_TCM](ERROR): CRC does not match. Expected:0x5996 got:0x41768
2016-06-23T21:40:40.365Z,1466718040.365 [PNI_TCM](ERROR): CRC does not match. Expected:0x7908 got:0x41661
2016-06-23T21:40:40.366Z,1466718040.366 [BPC1](ERROR): Failed to parse bank A battery data
2016-06-23T21:40:40.366Z,1466718040.366 [BPC1] Data Fault, FailCount= 1
2016-06-23T21:40:40.366Z,1466718040.366 [BPC1](ERROR): Data Fault
2016-06-23T21:40:40.447Z,1466718040.447 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T21:40:40.447Z,1466718040.447 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2016-06-23T21:40:40.448Z,1466718040.448 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T21:40:40.487Z,1466718040.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T21:40:40.488Z,1466718040.488 [CBIT](ERROR): Data Fault in component: BPC1
2016-06-23T21:40:40.556Z,1466718040.556 [PNI_TCM](ERROR): CRC does not match. Expected:0x38317 got:0x41731
2016-06-23T21:40:40.632Z,1466718040.632 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T21:40:40.632Z,1466718040.632 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2016-06-23T21:40:40.676Z,1466718040.676 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:40.940Z,1466718040.940 [PNI_TCM](ERROR): CRC does not match. Expected:0x12274 got:0x41819
2016-06-23T21:40:40.940Z,1466718040.940 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-06-23T21:40:40.940Z,1466718040.940 [PNI_TCM] Data Fault, FailCount= 1
2016-06-23T21:40:40.940Z,1466718040.940 [PNI_TCM](ERROR): Data Fault
2016-06-23T21:40:40.947Z,1466718040.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T21:40:40.947Z,1466718040.947 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T21:40:40.948Z,1466718040.948 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T21:40:40.960Z,1466718040.960 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.388500 seconds old.
2016-06-23T21:40:41.005Z,1466718041.005 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-06-23T21:40:41.324Z,1466718041.324 [PNI_TCM](INFO): Powering down
2016-06-23T21:40:41.364Z,1466718041.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.931700 seconds old.
2016-06-23T21:40:41.365Z,1466718041.365 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.793900 seconds old.
2016-06-23T21:40:41.402Z,1466718041.402 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T21:40:41.402Z,1466718041.402 [BPC1] No Fault, FailCount= 1
2016-06-23T21:40:41.473Z,1466718041.473 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:41.905Z,1466718041.905 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:42.040Z,1466718042.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.607400 seconds old.
2016-06-23T21:40:42.053Z,1466718042.053 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.481000 seconds old.
2016-06-23T21:40:42.313Z,1466718042.313 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:42.325Z,1466718042.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.892700 seconds old.
2016-06-23T21:40:42.334Z,1466718042.334 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.762100 seconds old.
2016-06-23T21:40:43.110Z,1466718043.110 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:43.521Z,1466718043.521 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:43.933Z,1466718043.933 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:44.728Z,1466718044.728 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:45.462Z,1466718045.462 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:45.881Z,1466718045.881 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:46.676Z,1466718046.676 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:47.438Z,1466718047.438 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:47.850Z,1466718047.851 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:48.645Z,1466718048.645 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:49.430Z,1466718049.430 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:49.841Z,1466718049.841 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:50.636Z,1466718050.636 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:50.752Z,1466718050.752 [CommandLine](IMPORTANT): got command quit
2016-06-23T21:40:51.433Z,1466718051.434 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:51.775Z,1466718051.775 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T21:40:51.776Z,1466718051.776 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:51.845Z,1466718051.845 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2016-06-23T21:40:51.875Z,1466718051.875 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2016-06-23T21:40:51.876Z,1466718051.876 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:51.877Z,1466718051.877 [CommandLine](INFO): Join timeout helper Thread ID is 896
2016-06-23T21:40:51.904Z,1466718051.904 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2016-06-23T21:40:51.904Z,1466718051.904 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:51.905Z,1466718051.905 [NavChartDb](INFO): Join timeout helper Thread ID is 897
2016-06-23T21:40:51.920Z,1466718051.920 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T21:40:51.921Z,1466718051.921 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:51.922Z,1466718051.922 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2016-06-23T21:40:51.922Z,1466718051.922 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:51.924Z,1466718051.924 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 898
2016-06-23T21:40:52.252Z,1466718052.252 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T21:40:52.252Z,1466718052.252 [Rowe_600LCM](INFO): Powering down
2016-06-23T21:40:52.253Z,1466718052.253 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.259Z,1466718052.260 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2016-06-23T21:40:52.260Z,1466718052.260 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.260Z,1466718052.260 [Radio_Surface](INFO): Join timeout helper Thread ID is 899
2016-06-23T21:40:52.273Z,1466718052.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.841100 seconds old.
2016-06-23T21:40:52.274Z,1466718052.274 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.703100 seconds old.
2016-06-23T21:40:52.320Z,1466718052.320 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-06-23T21:40:52.320Z,1466718052.320 [PNI_TCM] No Fault, FailCount= 1
2016-06-23T21:40:52.382Z,1466718052.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 11.950300 seconds old.
2016-06-23T21:40:52.384Z,1466718052.384 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.812800 seconds old.
2016-06-23T21:40:52.539Z,1466718052.539 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T21:40:52.540Z,1466718052.540 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.543Z,1466718052.543 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2016-06-23T21:40:52.543Z,1466718052.543 [logger ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.544Z,1466718052.544 [logger](INFO): Join timeout helper Thread ID is 900
2016-06-23T21:40:52.587Z,1466718052.587 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T21:40:52.587Z,1466718052.588 [logger ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.604Z,1466718052.604 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2016-06-23T21:40:52.604Z,1466718052.604 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.604Z,1466718052.604 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2016-06-23T21:40:52.604Z,1466718052.604 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.605Z,1466718052.605 [controlThread](INFO): Join timeout helper Thread ID is 901
2016-06-23T21:40:52.723Z,1466718052.723 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T21:40:52.724Z,1466718052.724 [controlThread](DEBUG): Uninitializing ControlThread
2016-06-23T21:40:52.727Z,1466718052.727 [NAL9602](INFO): Powering down
2016-06-23T21:40:52.728Z,1466718052.728 [PNI_TCM](INFO): Powering down
2016-06-23T21:40:52.729Z,1466718052.729 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-06-23T21:40:52.730Z,1466718052.730 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2016-06-23T21:40:52.730Z,1466718052.730 [Default] Stopped
2016-06-23T21:40:52.731Z,1466718052.731 [Default](DEBUG): Aggregate::uninitialize Default
2016-06-23T21:40:52.731Z,1466718052.731 [Default:B.GoToSurface] Stopped
2016-06-23T21:40:52.731Z,1466718052.731 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2016-06-23T21:40:52.731Z,1466718052.731 [Default:CheckIn] Stopped
2016-06-23T21:40:52.731Z,1466718052.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-06-23T21:40:52.731Z,1466718052.731 [Default:CheckIn:Read_Iridium] Stopped
2016-06-23T21:40:52.733Z,1466718052.733 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2016-06-23T21:40:52.734Z,1466718052.734 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2016-06-23T21:40:52.734Z,1466718052.734 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2016-06-23T21:40:52.734Z,1466718052.734 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2016-06-23T21:40:52.734Z,1466718052.734 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-06-23T21:40:52.734Z,1466718052.734 [BuoyancyServo](INFO): Powering down
2016-06-23T21:40:52.747Z,1466718052.747 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2016-06-23T21:40:52.747Z,1466718052.747 [ElevatorServo](INFO): Powering down
2016-06-23T21:40:52.748Z,1466718052.748 [MassServo](DEBUG): Uninitialize Mass Servo.
2016-06-23T21:40:52.748Z,1466718052.748 [MassServo](INFO): Powering down
2016-06-23T21:40:52.749Z,1466718052.749 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2016-06-23T21:40:52.749Z,1466718052.749 [RudderServo](INFO): Powering down
2016-06-23T21:40:52.750Z,1466718052.750 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2016-06-23T21:40:52.750Z,1466718052.750 [ThrusterServo](INFO): Powering down
2016-06-23T21:40:52.751Z,1466718052.751 [SBIT](DEBUG): Uninitialize SBIT Component.
2016-06-23T21:40:52.751Z,1466718052.751 [IBIT](DEBUG): Uninitialize IBIT Component.
2016-06-23T21:40:52.752Z,1466718052.752 [CBIT](DEBUG): Uninitialize CBIT Component.
2016-06-23T21:40:52.752Z,1466718052.752 [CBIT](CRITICAL): Backplane failed to power down
2016-06-23T21:40:52.753Z,1466718052.753 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.862Z,1466718052.862 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.902Z,1466718052.902 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:52.968Z,1466718052.968 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-06-23T21:40:53.008Z,1466718053.008 [logger ThreadHandler](INFO): Thread cancelled.