2016-06-23T22:34:27.187Z,1466721267.187 [Supervisor](DEBUG): Initializing supervisor.
2016-06-23T22:34:27.190Z,1466721267.190 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2016-06-23T22:34:27.190Z,1466721267.190 [SyncHandler](INFO): Protected caller Thread ID is 6579
2016-06-23T22:34:27.191Z,1466721267.191 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2016-06-23T22:34:27.192Z,1466721267.192 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2016-06-23T22:34:27.192Z,1466721267.192 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6580
2016-06-23T22:34:27.195Z,1466721267.195 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2016-06-23T22:34:27.207Z,1466721267.207 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2016-06-23T22:34:27.208Z,1466721267.208 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2016-06-23T22:34:27.209Z,1466721267.209 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6581
2016-06-23T22:34:27.209Z,1466721267.210 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2016-06-23T22:34:27.211Z,1466721267.211 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2016-06-23T22:34:27.211Z,1466721267.211 [logger ThreadHandler](INFO): Protected caller Thread ID is 6582
2016-06-23T22:34:27.213Z,1466721267.213 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2016-06-23T22:34:27.214Z,1466721267.214 [Supervisor](INFO): Looking for Config files in directory: Config/
2016-06-23T22:34:27.215Z,1466721267.215 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2016-06-23T22:34:27.299Z,1466721267.299 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2016-06-23T22:34:27.632Z,1466721267.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2016-06-23T22:34:27.633Z,1466721267.633 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2016-06-23T22:34:27.758Z,1466721267.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2016-06-23T22:34:27.759Z,1466721267.759 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2016-06-23T22:34:27.950Z,1466721267.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2016-06-23T22:34:27.950Z,1466721267.950 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2016-06-23T22:34:28.122Z,1466721268.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2016-06-23T22:34:28.123Z,1466721268.123 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2016-06-23T22:34:28.356Z,1466721268.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2016-06-23T22:34:28.356Z,1466721268.356 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2016-06-23T22:34:28.889Z,1466721268.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2016-06-23T22:34:28.890Z,1466721268.890 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2016-06-23T22:34:29.220Z,1466721269.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2016-06-23T22:34:29.221Z,1466721269.221 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2016-06-23T22:34:29.306Z,1466721269.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2016-06-23T22:34:29.307Z,1466721269.307 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2016-06-23T22:34:29.439Z,1466721269.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2016-06-23T22:34:29.440Z,1466721269.440 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2016-06-23T22:34:29.640Z,1466721269.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2016-06-23T22:34:29.640Z,1466721269.640 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2016-06-23T22:34:30.102Z,1466721270.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2016-06-23T22:34:30.102Z,1466721270.102 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2016-06-23T22:34:30.467Z,1466721270.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2016-06-23T22:34:30.468Z,1466721270.467 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2016-06-23T22:34:30.780Z,1466721270.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2016-06-23T22:34:30.781Z,1466721270.781 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2016-06-23T22:34:30.891Z,1466721270.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2016-06-23T22:34:30.893Z,1466721270.893 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2016-06-23T22:34:30.894Z,1466721270.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2016-06-23T22:34:30.992Z,1466721270.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2016-06-23T22:34:31.078Z,1466721271.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2016-06-23T22:34:31.179Z,1466721271.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2016-06-23T22:34:31.267Z,1466721271.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2016-06-23T22:34:31.413Z,1466721271.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2016-06-23T22:34:31.513Z,1466721271.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2016-06-23T22:34:31.685Z,1466721271.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2016-06-23T22:34:31.970Z,1466721271.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2016-06-23T22:34:31.970Z,1466721271.970 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2016-06-23T22:34:32.151Z,1466721272.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2016-06-23T22:34:32.263Z,1466721272.263 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2016-06-23T22:34:32.266Z,1466721272.266 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2016-06-23T22:34:32.293Z,1466721272.293 [DepthRateCalculator] Loaded
2016-06-23T22:34:32.293Z,1466721272.293 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2016-06-23T22:34:32.300Z,1466721272.300 [PitchRateCalculator] Loaded
2016-06-23T22:34:32.300Z,1466721272.300 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2016-06-23T22:34:32.313Z,1466721272.313 [SpeedCalculator] Loaded
2016-06-23T22:34:32.313Z,1466721272.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2016-06-23T22:34:32.331Z,1466721272.331 [TempGradientCalculator] Loaded
2016-06-23T22:34:32.331Z,1466721272.331 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2016-06-23T22:34:32.349Z,1466721272.349 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2016-06-23T22:34:32.349Z,1466721272.349 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2016-06-23T22:34:32.356Z,1466721272.356 [YawRateCalculator] Loaded
2016-06-23T22:34:32.356Z,1466721272.356 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2016-06-23T22:34:32.356Z,1466721272.356 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2016-06-23T22:34:32.357Z,1466721272.357 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2016-06-23T22:34:32.465Z,1466721272.466 [DeadReckonUsingMultipleVelocitySources] Loaded
2016-06-23T22:34:32.466Z,1466721272.466 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2016-06-23T22:34:32.526Z,1466721272.526 [DeadReckonUsingSpeedCalculator] Loaded
2016-06-23T22:34:32.526Z,1466721272.526 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2016-06-23T22:34:32.531Z,1466721272.531 [UniversalFixResidualReporter] Loaded
2016-06-23T22:34:32.531Z,1466721272.532 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2016-06-23T22:34:32.532Z,1466721272.532 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2016-06-23T22:34:32.533Z,1466721272.533 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2016-06-23T22:34:32.637Z,1466721272.637 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2016-06-23T22:34:32.638Z,1466721272.638 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2016-06-23T22:34:32.664Z,1466721272.664 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2016-06-23T22:34:32.665Z,1466721272.665 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2016-06-23T22:34:32.671Z,1466721272.671 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2016-06-23T22:34:32.672Z,1466721272.672 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2016-06-23T22:34:32.921Z,1466721272.921 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2016-06-23T22:34:32.922Z,1466721272.921 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2016-06-23T22:34:33.050Z,1466721273.050 [SBIT](DEBUG): Construct Startup Built In Test.
2016-06-23T22:34:33.084Z,1466721273.084 [SBIT] Loaded
2016-06-23T22:34:33.085Z,1466721273.085 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2016-06-23T22:34:33.086Z,1466721273.086 [IBIT](DEBUG): Construct Initiated Built In Test.
2016-06-23T22:34:33.117Z,1466721273.117 [IBIT] Loaded
2016-06-23T22:34:33.117Z,1466721273.117 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2016-06-23T22:34:33.121Z,1466721273.121 [CBIT](DEBUG): Construct Continuous Built In Test.
2016-06-23T22:34:33.285Z,1466721273.285 [CBIT] Loaded
2016-06-23T22:34:33.286Z,1466721273.286 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2016-06-23T22:34:33.286Z,1466721273.286 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2016-06-23T22:34:33.287Z,1466721273.287 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2016-06-23T22:34:33.570Z,1466721273.570 [DataOverHttps] Loaded
2016-06-23T22:34:33.571Z,1466721273.571 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2016-06-23T22:34:33.600Z,1466721273.600 [Depth_Keller] Loaded
2016-06-23T22:34:33.600Z,1466721273.600 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2016-06-23T22:34:33.702Z,1466721273.702 [NAL9602] Loaded
2016-06-23T22:34:33.703Z,1466721273.703 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2016-06-23T22:34:33.713Z,1466721273.713 [Onboard] Loaded
2016-06-23T22:34:33.713Z,1466721273.713 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2016-06-23T22:34:33.721Z,1466721273.721 [Radio_Surface] Loaded
2016-06-23T22:34:33.722Z,1466721273.722 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2016-06-23T22:34:33.723Z,1466721273.723 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2016-06-23T22:34:33.723Z,1466721273.723 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6659
2016-06-23T22:34:33.776Z,1466721273.776 [PNI_TCM] Loaded
2016-06-23T22:34:33.777Z,1466721273.777 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2016-06-23T22:34:35.842Z,1466721275.842 [BPC1] Loaded
2016-06-23T22:34:35.842Z,1466721275.842 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2016-06-23T22:34:35.843Z,1466721275.843 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2016-06-23T22:34:35.843Z,1466721275.843 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2016-06-23T22:34:35.953Z,1466721275.953 [BuoyancyServo] Loaded
2016-06-23T22:34:35.954Z,1466721275.954 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2016-06-23T22:34:35.971Z,1466721275.971 [ElevatorServo] Loaded
2016-06-23T22:34:35.971Z,1466721275.971 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2016-06-23T22:34:35.988Z,1466721275.988 [MassServo] Loaded
2016-06-23T22:34:35.988Z,1466721275.988 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2016-06-23T22:34:36.005Z,1466721276.005 [RudderServo] Loaded
2016-06-23T22:34:36.005Z,1466721276.005 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2016-06-23T22:34:36.022Z,1466721276.022 [ThrusterServo] Loaded
2016-06-23T22:34:36.022Z,1466721276.022 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2016-06-23T22:34:36.023Z,1466721276.023 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2016-06-23T22:34:36.023Z,1466721276.023 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2016-06-23T22:34:36.076Z,1466721276.076 [VerticalControl](DEBUG): Construct VerticalControl.
2016-06-23T22:34:36.177Z,1466721276.177 [VerticalControl] Loaded
2016-06-23T22:34:36.177Z,1466721276.177 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2016-06-23T22:34:36.178Z,1466721276.178 [HorizontalControl](DEBUG): Construct HorizontalControl.
2016-06-23T22:34:36.243Z,1466721276.243 [HorizontalControl] Loaded
2016-06-23T22:34:36.243Z,1466721276.243 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2016-06-23T22:34:36.244Z,1466721276.244 [SpeedControl](DEBUG): Construct SpeedControl.
2016-06-23T22:34:36.246Z,1466721276.246 [SpeedControl] Loaded
2016-06-23T22:34:36.246Z,1466721276.246 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2016-06-23T22:34:36.247Z,1466721276.247 [LoopControl](DEBUG): Construct LoopControl.
2016-06-23T22:34:36.248Z,1466721276.247 [LoopControl] Loaded
2016-06-23T22:34:36.248Z,1466721276.248 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2016-06-23T22:34:36.248Z,1466721276.248 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2016-06-23T22:34:36.249Z,1466721276.249 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2016-06-23T22:34:36.330Z,1466721276.330 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2016-06-23T22:34:36.330Z,1466721276.330 [StratificationFrontDetector](DEBUG): (re)initializing
2016-06-23T22:34:36.330Z,1466721276.330 [StratificationFrontDetector] Loaded
2016-06-23T22:34:36.331Z,1466721276.331 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2016-06-23T22:34:36.331Z,1466721276.331 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2016-06-23T22:34:36.332Z,1466721276.332 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2016-06-23T22:34:36.484Z,1466721276.484 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2016-06-23T22:34:36.488Z,1466721276.488 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2016-06-23T22:34:36.489Z,1466721276.489 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2016-06-23T22:34:36.495Z,1466721276.496 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2016-06-23T22:34:36.497Z,1466721276.497 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A2D4E0
2016-06-23T22:34:36.497Z,1466721276.497 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6660
2016-06-23T22:34:36.502Z,1466721276.502 [Supervisor](INFO): Main Thread ID is 6578
2016-06-23T22:34:36.502Z,1466721276.502 [Supervisor](DEBUG): Running supervisor.
2016-06-23T22:34:36.503Z,1466721276.503 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6661
2016-06-23T22:34:36.506Z,1466721276.506 [controlThread ThreadHandler](INFO): Handler Thread ID is 6662
2016-06-23T22:34:36.507Z,1466721276.507 [controlThread](DEBUG): Initializing ControlThread
2016-06-23T22:34:36.507Z,1466721276.507 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-06-23T22:34:36.508Z,1466721276.508 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2016-06-23T22:34:36.508Z,1466721276.508 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2016-06-23T22:34:36.508Z,1466721276.509 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2016-06-23T22:34:36.509Z,1466721276.509 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2016-06-23T22:34:36.510Z,1466721276.510 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2016-06-23T22:34:36.510Z,1466721276.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:34:36.511Z,1466721276.511 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:34:36.512Z,1466721276.512 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:34:36.512Z,1466721276.512 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:34:36.512Z,1466721276.512 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:34:36.513Z,1466721276.513 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:34:36.513Z,1466721276.513 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2016-06-23T22:34:36.513Z,1466721276.513 [SBIT](INFO): Initialize SBIT Component.
2016-06-23T22:34:36.514Z,1466721276.514 [SBIT](IMPORTANT): git: 2015-12-17-109-g257a154
2016-06-23T22:34:36.514Z,1466721276.514 [SBIT](INFO): git hash: 257a1547f8111cca91b00dd2487919c4d9436f7c
2016-06-23T22:34:36.516Z,1466721276.516 [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-23T22:34:36.516Z,1466721276.516 [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-23T22:34:36.517Z,1466721276.517 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2016-06-23T22:34:36.518Z,1466721276.518 [IBIT](INFO): Initialize IBIT Component.
2016-06-23T22:34:36.518Z,1466721276.519 [CBIT](DEBUG): Initialize CBIT Component.
2016-06-23T22:34:36.519Z,1466721276.519 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2016-06-23T22:34:36.520Z,1466721276.520 [logger ThreadHandler](INFO): Handler Thread ID is 6663
2016-06-23T22:34:36.548Z,1466721276.548 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6664
2016-06-23T22:34:36.554Z,1466721276.554 [Radio_Surface](INFO): Powering up
2016-06-23T22:34:36.572Z,1466721276.572 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6665
2016-06-23T22:34:36.576Z,1466721276.576 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2016-06-23T22:34:36.610Z,1466721276.610 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2016-06-23T22:34:36.612Z,1466721276.612 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2016-06-23T22:34:36.613Z,1466721276.612 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2016-06-23T22:34:36.613Z,1466721276.613 [LoopControl](DEBUG): Initialize LoopControlComponent.
2016-06-23T22:34:36.614Z,1466721276.614 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2016-06-23T22:34:36.645Z,1466721276.645 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2016-06-23T22:34:36.664Z,1466721276.664 [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-23T22:34:36.665Z,1466721276.665 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2016-06-23T22:34:36.715Z,1466721276.715 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts
2016-06-23T22:34:36.717Z,1466721276.717 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2016-06-23T22:34:36.719Z,1466721276.719 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI01M.000
2016-06-23T22:34:36.737Z,1466721276.737 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI80M.000
2016-06-23T22:34:36.738Z,1466721276.738 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2016-06-23T22:34:36.740Z,1466721276.740 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2016-06-23T22:34:36.742Z,1466721276.742 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2016-06-23T22:34:36.748Z,1466721276.747 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2016-06-23T22:34:36.749Z,1466721276.749 [Default:A.Wait](DEBUG): Construct Wait.
2016-06-23T22:34:36.750Z,1466721276.750 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2016-06-23T22:34:36.760Z,1466721276.760 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4IN01M.000
2016-06-23T22:34:36.761Z,1466721276.761 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI56M.000
2016-06-23T22:34:36.763Z,1466721276.763 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI57M.000
2016-06-23T22:34:36.765Z,1466721276.765 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI59M.000
2016-06-23T22:34:36.780Z,1466721276.780 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI89M.000
2016-06-23T22:34:36.782Z,1466721276.782 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI90M.000
2016-06-23T22:34:36.784Z,1466721276.784 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI03M.000
2016-06-23T22:34:36.786Z,1466721276.786 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI06M.000
2016-06-23T22:34:36.792Z,1466721276.792 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2016-06-23T22:34:36.803Z,1466721276.803 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2016-06-23T22:34:36.809Z,1466721276.809 [Default:E.Execute](DEBUG): Construct Execute.
2016-06-23T22:34:36.812Z,1466721276.812 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI33M.000
2016-06-23T22:34:36.814Z,1466721276.814 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4WI34M.000
2016-06-23T22:34:36.816Z,1466721276.816 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2016-06-23T22:34:36.818Z,1466721276.818 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2016-06-23T22:34:36.829Z,1466721276.829 [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-23T22:34:36.834Z,1466721276.834 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2016-06-23T22:34:36.841Z,1466721276.841 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2016-06-23T22:34:36.843Z,1466721276.843 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2016-06-23T22:34:36.845Z,1466721276.845 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2AK20M.000
2016-06-23T22:34:36.868Z,1466721276.868 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3AK12M.000
2016-06-23T22:34:36.870Z,1466721276.870 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3AK1DM.000
2016-06-23T22:34:36.872Z,1466721276.872 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3AK5KM.000
2016-06-23T22:34:36.896Z,1466721276.896 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T22:34:37.186Z,1466721277.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:37.222Z,1466721277.222 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:37.340Z,1466721277.339 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-06-23T22:34:37.347Z,1466721277.347 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-06-23T22:34:37.362Z,1466721277.362 [ElevatorServo](DEBUG): Initializing EZServoServo.
2016-06-23T22:34:37.368Z,1466721277.368 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2016-06-23T22:34:37.385Z,1466721277.385 [MassServo](DEBUG): Initializing EZServoServo.
2016-06-23T22:34:37.391Z,1466721277.391 [MassServo](DEBUG): Initializing MassServo.
2016-06-23T22:34:37.397Z,1466721277.397 [RudderServo](DEBUG): Initializing EZServoServo.
2016-06-23T22:34:37.423Z,1466721277.423 [RudderServo](DEBUG): Initializing RudderServo.
2016-06-23T22:34:37.429Z,1466721277.429 [ThrusterServo](DEBUG): Initializing EZServoServo.
2016-06-23T22:34:37.435Z,1466721277.435 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2016-06-23T22:34:37.768Z,1466721277.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:37.769Z,1466721277.769 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:46.266Z,1466721286.266 [NAL9602](INFO): Powering up NAL9602
2016-06-23T22:34:46.347Z,1466721286.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:46.348Z,1466721286.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:46.692Z,1466721286.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:46.693Z,1466721286.693 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:47.119Z,1466721287.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:47.128Z,1466721287.128 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:47.455Z,1466721287.455 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003900
2016-06-23T22:34:47.483Z,1466721287.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:47.484Z,1466721287.484 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:47.879Z,1466721287.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:47.880Z,1466721287.880 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:48.292Z,1466721288.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:48.293Z,1466721288.293 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-06-23T22:34:54.208Z,1466721294.208 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T22:34:57.165Z,1466721297.165 [SBIT](IMPORTANT): Beginning Startup BIT
2016-06-23T22:34:57.173Z,1466721297.173 [CBIT](IMPORTANT): Beginning GF scan
2016-06-23T22:34:57.457Z,1466721297.457 [NAL9602](INFO): NAL9602 initialized
2016-06-23T22:34:57.728Z,1466721297.728 [CBIT](IMPORTANT): Open Volts:2.500625
Open Current:0.000947
2016-06-23T22:34:59.057Z,1466721299.057 [CBIT](IMPORTANT): A0 Volts:2.668304
A0 Current:0.107600
2016-06-23T22:35:00.376Z,1466721300.376 [CBIT](IMPORTANT): A1 Volts:2.022631
A1 Current:-0.304355
2016-06-23T22:35:01.560Z,1466721301.560 [CBIT](IMPORTANT): A2 Volts:2.669902
A2 Current:0.107720
2016-06-23T22:35:02.804Z,1466721302.804 [CBIT](IMPORTANT): A3 Volts:2.618048
A3 Current:0.075008
2016-06-23T22:35:03.980Z,1466721303.980 [CBIT](IMPORTANT): B0 Volts:2.390473
B0 Current:-0.069970
2016-06-23T22:35:05.204Z,1466721305.204 [CBIT](IMPORTANT): B1 Volts:2.285311
B1 Current:-0.136720
2016-06-23T22:35:06.508Z,1466721306.508 [CBIT](IMPORTANT): B2 Volts:2.282591
B2 Current:-0.138239
2016-06-23T22:35:08.119Z,1466721308.119 [CBIT](IMPORTANT): B3 Volts:1.777134
B3 Current:-0.458788
2016-06-23T22:35:08.235Z,1466721308.235 [CBIT](FAULT): Low side GF detected
mA:
CHAN A0 (Batt): 0.107600
CHAN A1 (24V): -0.304355
CHAN A2 (12V): 0.107720
CHAN A3 (5V): 0.075008
CHAN B0 (3.3V): -0.069970
CHAN B1 (3.15aV): -0.136720
CHAN B2 (3.15bV): -0.138239
CHAN B3 (GND): -0.458788
OPEN: 0.000947
Full Scale Calc: 4.765 mA, -1.589 mA
2016-06-23T22:35:24.878Z,1466721324.878 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:35:50.998Z,1466721350.997 [SBIT](IMPORTANT): SBIT PASSED
2016-06-23T22:35:51.326Z,1466721351.326 [MissionManager](IMPORTANT): Started mission Startup
2016-06-23T22:35:51.326Z,1466721351.326 [Startup] Running Loop=1
2016-06-23T22:35:51.326Z,1466721351.326 [Startup](DEBUG): Aggregate::initialize Startup
2016-06-23T22:35:51.327Z,1466721351.327 [Startup:A.GoToSurface] Running Loop=1
2016-06-23T22:35:51.327Z,1466721351.327 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2016-06-23T22:35:51.327Z,1466721351.328 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2016-06-23T22:35:51.328Z,1466721351.328 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2016-06-23T22:35:51.328Z,1466721351.328 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2016-06-23T22:35:51.329Z,1466721351.329 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2016-06-23T22:35:51.329Z,1466721351.329 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2016-06-23T22:35:51.347Z,1466721351.347 [Startup:StartupSatComms] Running Loop=1
2016-06-23T22:35:51.348Z,1466721351.348 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2016-06-23T22:35:51.348Z,1466721351.348 [Startup:StartupSatComms:A] Running Loop=1
2016-06-23T22:35:51.761Z,1466721351.761 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2016-06-23T22:36:09.481Z,1466721369.481 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:36:16.624Z,1466721376.624 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T22:36:20.707Z,1466721380.707 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:36:23.890Z,1466721383.890 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T22:36:51.391Z,1466721411.391 [Startup:StartupSatComms:A](INFO): Timed out from 2016-06-23T22:35:51.3Z
2016-06-23T22:36:51.391Z,1466721411.391 [Startup:StartupSatComms:A] Stopped
2016-06-23T22:36:51.391Z,1466721411.391 [Startup:StartupSatComms:B] Running Loop=1
2016-06-23T22:36:51.805Z,1466721411.805 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2016-06-23T22:36:57.523Z,1466721417.523 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20160623T214344/Courier0016.lzma
2016-06-23T22:36:58.041Z,1466721418.040 [DataOverHttps](INFO): Moved sent file to Logs/20160623T214344/Courier0016.lzma.bak
2016-06-23T22:36:58.041Z,1466721418.041 [DataOverHttps](INFO): SBD MOMSN=4285849
2016-06-23T22:37:05.757Z,1466721425.757 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:37:05.757Z,1466721425.757 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:37:05.757Z,1466721425.757 [BPC1](ERROR): Communications Fault
2016-06-23T22:37:05.864Z,1466721425.864 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:37:06.808Z,1466721426.808 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:37:06.808Z,1466721426.808 [BPC1] No Fault, FailCount= 1
2016-06-23T22:37:15.467Z,1466721435.467 [DataOverHttps](INFO): Sending 448 bytes from file Logs/20160623T214344/Express0017.lzma
2016-06-23T22:37:16.071Z,1466721436.071 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2016-06-23T22:37:16.093Z,1466721436.093 [NavChartDb](INFO): # of records loaded: 19732
2016-06-23T22:37:16.102Z,1466721436.102 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2016-06-23T22:37:16.135Z,1466721436.135 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T22:37:16.268Z,1466721436.268 [DataOverHttps](INFO): Moved sent file to Logs/20160623T214344/Express0017.lzma.bak
2016-06-23T22:37:16.268Z,1466721436.268 [DataOverHttps](INFO): SBD MOMSN=4285852
2016-06-23T22:37:25.564Z,1466721445.564 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T22:37:29.305Z,1466721449.305 [DataOverHttps](INFO): Sending 912 bytes from file Logs/20160623T223427/Express0001.lzma
2016-06-23T22:37:30.088Z,1466721450.088 [DataOverHttps](INFO): Moved sent file to Logs/20160623T223427/Express0001.lzma.bak
2016-06-23T22:37:30.088Z,1466721450.088 [DataOverHttps](INFO): SBD MOMSN=4285873
2016-06-23T22:37:30.573Z,1466721450.573 [Startup:StartupSatComms:B] Stopped
2016-06-23T22:37:30.573Z,1466721450.573 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2016-06-23T22:37:30.573Z,1466721450.573 [Startup:StartupSatComms] Stopped
2016-06-23T22:37:30.573Z,1466721450.573 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2016-06-23T22:37:30.574Z,1466721450.574 [Startup](INFO): Completed Startup
2016-06-23T22:37:30.574Z,1466721450.574 [Startup] Stopped
2016-06-23T22:37:30.574Z,1466721450.574 [Startup](DEBUG): Aggregate::uninitialize Startup
2016-06-23T22:37:30.574Z,1466721450.574 [Startup:A.GoToSurface] Stopped
2016-06-23T22:37:30.575Z,1466721450.575 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2016-06-23T22:37:30.964Z,1466721450.964 [MissionManager](IMPORTANT): Started mission Default
2016-06-23T22:37:30.964Z,1466721450.964 [Default] Running Loop=1
2016-06-23T22:37:30.964Z,1466721450.964 [Default](DEBUG): Aggregate::initialize Default
2016-06-23T22:37:30.964Z,1466721450.964 [Default:B.GoToSurface] Running Loop=1
2016-06-23T22:37:30.964Z,1466721450.964 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2016-06-23T22:37:30.965Z,1466721450.965 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2016-06-23T22:37:30.965Z,1466721450.965 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2016-06-23T22:37:30.965Z,1466721450.965 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2016-06-23T22:37:30.966Z,1466721450.966 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2016-06-23T22:37:30.966Z,1466721450.966 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2016-06-23T22:37:30.966Z,1466721450.966 [Default:A.Wait] Running Loop=1
2016-06-23T22:37:30.966Z,1466721450.966 [Default:A.Wait](DEBUG): Initialize Wait Component.
2016-06-23T22:37:36.874Z,1466721456.874 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:37:36.874Z,1466721456.874 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2016-06-23T22:37:36.874Z,1466721456.874 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:37:36.876Z,1466721456.876 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:37:36.877Z,1466721456.876 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2016-06-23T22:37:36.877Z,1466721456.877 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:37:36.905Z,1466721456.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:37:36.905Z,1466721456.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:37:37.070Z,1466721457.070 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:37:37.070Z,1466721457.070 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2016-06-23T22:37:37.071Z,1466721457.071 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:37:37.071Z,1466721457.071 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2016-06-23T22:37:37.382Z,1466721457.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:37:37.387Z,1466721457.387 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:37:37.388Z,1466721457.388 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:37:37.388Z,1466721457.388 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:37:37.389Z,1466721457.389 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:37:37.389Z,1466721457.389 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:37:37.844Z,1466721457.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.0 s old, using for 20.0 s.
2016-06-23T22:37:38.193Z,1466721458.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2016-06-23T22:37:38.609Z,1466721458.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2016-06-23T22:37:39.004Z,1466721459.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2016-06-23T22:37:39.397Z,1466721459.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2016-06-23T22:37:39.797Z,1466721459.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2016-06-23T22:37:40.193Z,1466721460.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2016-06-23T22:37:40.597Z,1466721460.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2016-06-23T22:37:40.970Z,1466721460.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2016-06-23T22:37:41.404Z,1466721461.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2016-06-23T22:37:41.804Z,1466721461.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2016-06-23T22:37:42.204Z,1466721462.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2016-06-23T22:37:42.612Z,1466721462.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2016-06-23T22:37:42.974Z,1466721462.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2016-06-23T22:37:43.376Z,1466721463.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2016-06-23T22:37:43.776Z,1466721463.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2016-06-23T22:37:44.181Z,1466721464.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2016-06-23T22:37:44.188Z,1466721464.188 [Default:A.Wait](INFO): Done Waiting.
2016-06-23T22:37:44.188Z,1466721464.188 [Default:A.Wait] Stopped
2016-06-23T22:37:44.188Z,1466721464.188 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2016-06-23T22:37:44.569Z,1466721464.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2016-06-23T22:37:44.572Z,1466721464.572 [Default:CheckIn] Running Loop=1
2016-06-23T22:37:44.572Z,1466721464.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-06-23T22:37:44.572Z,1466721464.572 [Default:CheckIn:Read_GPS] Running Loop=1
2016-06-23T22:37:44.972Z,1466721464.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2016-06-23T22:37:44.975Z,1466721464.975 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2016-06-23T22:37:45.389Z,1466721465.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2016-06-23T22:37:45.694Z,1466721465.694 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:37:45.773Z,1466721465.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2016-06-23T22:37:46.176Z,1466721466.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2016-06-23T22:37:46.589Z,1466721466.589 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2016-06-23T22:37:47.023Z,1466721467.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2016-06-23T22:37:47.404Z,1466721467.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2016-06-23T22:37:47.805Z,1466721467.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2016-06-23T22:37:48.187Z,1466721468.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2016-06-23T22:37:48.593Z,1466721468.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2016-06-23T22:37:48.981Z,1466721468.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2016-06-23T22:37:49.389Z,1466721469.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2016-06-23T22:37:49.760Z,1466721469.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2016-06-23T22:37:50.164Z,1466721470.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2016-06-23T22:37:50.567Z,1466721470.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2016-06-23T22:37:50.981Z,1466721470.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2016-06-23T22:37:51.449Z,1466721471.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2016-06-23T22:37:51.792Z,1466721471.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2016-06-23T22:37:52.216Z,1466721472.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2016-06-23T22:37:52.578Z,1466721472.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2016-06-23T22:37:53.009Z,1466721473.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2016-06-23T22:37:53.393Z,1466721473.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2016-06-23T22:37:53.781Z,1466721473.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2016-06-23T22:37:54.206Z,1466721474.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2016-06-23T22:37:54.567Z,1466721474.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2016-06-23T22:37:54.968Z,1466721474.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2016-06-23T22:37:55.401Z,1466721475.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2016-06-23T22:37:55.762Z,1466721475.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2016-06-23T22:37:56.204Z,1466721476.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2016-06-23T22:37:56.581Z,1466721476.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2016-06-23T22:38:01.295Z,1466721481.295 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:38:15.462Z,1466721495.462 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:38:22.960Z,1466721502.960 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:38:22.960Z,1466721502.960 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:38:22.960Z,1466721502.960 [BPC1](ERROR): Communications Fault
2016-06-23T22:38:23.029Z,1466721503.029 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:38:24.238Z,1466721504.238 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:38:24.238Z,1466721504.238 [BPC1] No Fault, FailCount= 1
2016-06-23T22:38:30.293Z,1466721510.293 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:38:50.039Z,1466721530.039 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:38:59.633Z,1466721539.633 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T22:39:06.433Z,1466721546.433 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T22:39:06.742Z,1466721546.742 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T22:39:30.222Z,1466721570.222 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T22:39:47.514Z,1466721587.514 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000
2016-06-23T22:39:47.540Z,1466721587.540 [NavChartDb](INFO): # of records loaded: 37376
2016-06-23T22:39:47.589Z,1466721587.589 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000
2016-06-23T22:39:47.590Z,1466721587.590 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000
2016-06-23T22:39:49.206Z,1466721589.206 [BPC1](ERROR): Failed to parse message.
2016-06-23T22:39:49.206Z,1466721589.206 [BPC1](ERROR): Failed to parse bank A battery data
2016-06-23T22:39:49.206Z,1466721589.206 [BPC1] Data Fault, FailCount= 1
2016-06-23T22:39:49.206Z,1466721589.206 [BPC1](ERROR): Data Fault
2016-06-23T22:39:49.283Z,1466721589.283 [CBIT](ERROR): Data Fault in component: BPC1
2016-06-23T22:39:50.459Z,1466721590.459 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:39:50.460Z,1466721590.460 [BPC1] No Fault, FailCount= 1
2016-06-23T22:39:54.402Z,1466721594.402 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000
2016-06-23T22:40:00.776Z,1466721600.776 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2016-06-23T22:40:13.872Z,1466721613.872 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:40:25.029Z,1466721625.029 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:40:37.141Z,1466721637.141 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000
2016-06-23T22:40:37.475Z,1466721637.475 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:40:37.475Z,1466721637.476 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:40:37.476Z,1466721637.476 [BPC1](ERROR): Communications Fault
2016-06-23T22:40:37.491Z,1466721637.491 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:40:37.491Z,1466721637.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2016-06-23T22:40:37.491Z,1466721637.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:40:37.493Z,1466721637.493 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:40:37.493Z,1466721637.493 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2016-06-23T22:40:37.493Z,1466721637.493 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:40:37.506Z,1466721637.506 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:40:37.506Z,1466721637.506 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:40:37.506Z,1466721637.506 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:40:37.648Z,1466721637.648 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:40:37.649Z,1466721637.648 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2016-06-23T22:40:37.649Z,1466721637.649 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:40:37.649Z,1466721637.649 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2016-06-23T22:40:37.986Z,1466721637.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:40:37.986Z,1466721637.986 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:40:37.986Z,1466721637.986 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:40:37.990Z,1466721637.990 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:40:37.990Z,1466721637.990 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:40:37.990Z,1466721637.990 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:40:38.389Z,1466721638.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2016-06-23T22:40:38.403Z,1466721638.403 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:40:38.403Z,1466721638.403 [BPC1] No Fault, FailCount= 1
2016-06-23T22:40:38.860Z,1466721638.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2016-06-23T22:40:39.196Z,1466721639.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2016-06-23T22:40:39.487Z,1466721639.487 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA61M.000
2016-06-23T22:40:39.593Z,1466721639.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2016-06-23T22:40:39.971Z,1466721639.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2016-06-23T22:40:40.392Z,1466721640.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2016-06-23T22:40:40.769Z,1466721640.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2016-06-23T22:40:41.173Z,1466721641.173 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2016-06-23T22:40:41.392Z,1466721641.392 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:40:41.560Z,1466721641.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2016-06-23T22:40:41.969Z,1466721641.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2016-06-23T22:40:42.378Z,1466721642.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2016-06-23T22:40:42.808Z,1466721642.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2016-06-23T22:40:43.182Z,1466721643.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2016-06-23T22:40:43.566Z,1466721643.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2016-06-23T22:40:44.023Z,1466721644.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2016-06-23T22:40:44.375Z,1466721644.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2016-06-23T22:40:44.766Z,1466721644.766 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2016-06-23T22:40:45.182Z,1466721645.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2016-06-23T22:40:45.592Z,1466721645.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2016-06-23T22:40:45.978Z,1466721645.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2016-06-23T22:40:46.379Z,1466721646.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2016-06-23T22:40:46.752Z,1466721646.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2016-06-23T22:40:47.162Z,1466721647.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2016-06-23T22:40:47.605Z,1466721647.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2016-06-23T22:40:47.967Z,1466721647.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2016-06-23T22:40:48.361Z,1466721648.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2016-06-23T22:40:48.759Z,1466721648.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2016-06-23T22:40:49.170Z,1466721649.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2016-06-23T22:40:49.572Z,1466721649.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2016-06-23T22:40:49.970Z,1466721649.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2016-06-23T22:40:50.368Z,1466721650.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2016-06-23T22:40:50.773Z,1466721650.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2016-06-23T22:40:51.193Z,1466721651.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2016-06-23T22:40:51.572Z,1466721651.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2016-06-23T22:40:51.993Z,1466721651.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2016-06-23T22:40:52.405Z,1466721652.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2016-06-23T22:40:52.792Z,1466721652.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2016-06-23T22:40:53.197Z,1466721653.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2016-06-23T22:40:53.609Z,1466721653.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2016-06-23T22:40:53.979Z,1466721653.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2016-06-23T22:40:54.356Z,1466721654.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2016-06-23T22:40:54.769Z,1466721654.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2016-06-23T22:40:55.172Z,1466721655.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2016-06-23T22:40:55.573Z,1466721655.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2016-06-23T22:40:55.822Z,1466721655.822 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA61M.000
2016-06-23T22:40:55.985Z,1466721655.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2016-06-23T22:40:56.062Z,1466721656.062 [NavChartDb](INFO): # of records loaded: 16998
2016-06-23T22:40:56.077Z,1466721656.077 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA61M.000
2016-06-23T22:40:56.078Z,1466721656.078 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000
2016-06-23T22:40:56.372Z,1466721656.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2016-06-23T22:40:56.752Z,1466721656.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2016-06-23T22:40:57.188Z,1466721657.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2016-06-23T22:41:01.405Z,1466721661.405 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA50M.000
2016-06-23T22:41:17.499Z,1466721677.499 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:41:27.475Z,1466721687.475 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:41:37.766Z,1466721697.766 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:41:46.701Z,1466721706.701 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:41:55.316Z,1466721715.316 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:42:03.753Z,1466721723.753 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T22:42:11.169Z,1466721731.169 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:42:11.169Z,1466721731.170 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:42:11.170Z,1466721731.170 [BPC1](ERROR): Communications Fault
2016-06-23T22:42:11.259Z,1466721731.259 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:42:12.435Z,1466721732.435 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:42:12.443Z,1466721732.443 [BPC1] No Fault, FailCount= 1
2016-06-23T22:42:14.622Z,1466721734.622 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T22:42:25.908Z,1466721745.908 [NavChartDb](INFO): # of records loaded: 40000
2016-06-23T22:42:36.154Z,1466721756.154 [NavChartDb](INFO): # of records loaded: 45000
2016-06-23T22:42:44.815Z,1466721764.815 [Default:CheckIn:Read_GPS](INFO): Timed out from 2016-06-23T22:37:44.6Z
2016-06-23T22:42:44.815Z,1466721764.815 [Default:CheckIn:Read_GPS] Stopped
2016-06-23T22:42:44.815Z,1466721764.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-06-23T22:42:45.211Z,1466721765.211 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2016-06-23T22:42:45.933Z,1466721765.933 [NavChartDb](INFO): # of records loaded: 50000
2016-06-23T22:42:52.254Z,1466721772.254 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20160623T223427/Courier0004.lzma
2016-06-23T22:42:53.032Z,1466721773.032 [DataOverHttps](INFO): Moved sent file to Logs/20160623T223427/Courier0004.lzma.bak
2016-06-23T22:42:53.032Z,1466721773.032 [DataOverHttps](INFO): SBD MOMSN=4285918
2016-06-23T22:43:01.900Z,1466721781.900 [NavChartDb](INFO): # of records loaded: 55000
2016-06-23T22:43:04.421Z,1466721784.421 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA50M.000
2016-06-23T22:43:06.869Z,1466721786.869 [DataOverHttps](INFO): Sending 382 bytes from file Logs/20160623T223427/Express0005.lzma
2016-06-23T22:43:07.648Z,1466721787.648 [DataOverHttps](INFO): Moved sent file to Logs/20160623T223427/Express0005.lzma.bak
2016-06-23T22:43:07.648Z,1466721787.648 [DataOverHttps](INFO): SBD MOMSN=4285920
2016-06-23T22:43:08.160Z,1466721788.159 [Default:CheckIn:Read_Iridium] Stopped
2016-06-23T22:43:08.160Z,1466721788.160 [Default:CheckIn:C.Wait] Running Loop=1
2016-06-23T22:43:08.160Z,1466721788.160 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-06-23T22:43:11.316Z,1466721791.316 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA50M.000
2016-06-23T22:43:17.550Z,1466721797.550 [NavChartDb](INFO): # of records loaded: 60000
2016-06-23T22:43:32.990Z,1466721812.990 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA50M.000
2016-06-23T22:43:33.106Z,1466721813.106 [NavChartDb](INFO): # of records loaded: 62026
2016-06-23T22:43:33.113Z,1466721813.114 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA50M.000
2016-06-23T22:43:33.115Z,1466721813.115 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4WI34M.000
2016-06-23T22:43:38.020Z,1466721818.020 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:43:38.020Z,1466721818.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2016-06-23T22:43:38.020Z,1466721818.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:43:38.022Z,1466721818.022 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:43:38.022Z,1466721818.022 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2016-06-23T22:43:38.022Z,1466721818.022 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:43:38.038Z,1466721818.038 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4WI34M.000
2016-06-23T22:43:38.078Z,1466721818.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:43:38.078Z,1466721818.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:43:38.429Z,1466721818.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:43:38.429Z,1466721818.429 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2016-06-23T22:43:38.430Z,1466721818.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:43:38.430Z,1466721818.430 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2016-06-23T22:43:38.814Z,1466721818.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:43:38.815Z,1466721818.815 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:43:38.815Z,1466721818.815 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:43:38.816Z,1466721818.816 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:43:38.816Z,1466721818.816 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:43:38.817Z,1466721818.817 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:43:39.198Z,1466721819.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2016-06-23T22:43:39.609Z,1466721819.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2016-06-23T22:43:40.013Z,1466721820.013 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2016-06-23T22:43:40.419Z,1466721820.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2016-06-23T22:43:40.818Z,1466721820.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2016-06-23T22:43:41.218Z,1466721821.218 [BPC1](ERROR): Failed to parse bank A battery data
2016-06-23T22:43:41.218Z,1466721821.218 [BPC1] Data Fault, FailCount= 1
2016-06-23T22:43:41.219Z,1466721821.218 [BPC1](ERROR): Data Fault
2016-06-23T22:43:41.226Z,1466721821.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2016-06-23T22:43:41.284Z,1466721821.284 [CBIT](ERROR): Data Fault in component: BPC1
2016-06-23T22:43:41.629Z,1466721821.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2016-06-23T22:43:42.056Z,1466721822.056 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2016-06-23T22:43:42.415Z,1466721822.415 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2016-06-23T22:43:42.457Z,1466721822.457 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:43:42.457Z,1466721822.457 [BPC1] No Fault, FailCount= 1
2016-06-23T22:43:42.882Z,1466721822.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2016-06-23T22:43:43.233Z,1466721823.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2016-06-23T22:43:43.621Z,1466721823.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2016-06-23T22:43:44.031Z,1466721824.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2016-06-23T22:43:44.426Z,1466721824.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2016-06-23T22:43:44.810Z,1466721824.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T22:43:45.198Z,1466721825.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T22:43:45.599Z,1466721825.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2016-06-23T22:43:46.079Z,1466721826.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2016-06-23T22:43:46.412Z,1466721826.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T22:43:46.799Z,1466721826.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2016-06-23T22:43:47.235Z,1466721827.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T22:43:47.650Z,1466721827.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T22:43:48.036Z,1466721828.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T22:43:48.434Z,1466721828.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T22:43:48.841Z,1466721828.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T22:43:49.215Z,1466721829.215 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T22:43:49.637Z,1466721829.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T22:43:50.006Z,1466721830.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2016-06-23T22:43:50.441Z,1466721830.441 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T22:43:50.821Z,1466721830.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2016-06-23T22:43:51.220Z,1466721831.220 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2016-06-23T22:43:51.629Z,1466721831.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T22:43:52.032Z,1466721832.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2016-06-23T22:43:52.405Z,1466721832.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2016-06-23T22:43:52.835Z,1466721832.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2016-06-23T22:43:53.231Z,1466721833.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2016-06-23T22:43:53.636Z,1466721833.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2016-06-23T22:43:54.034Z,1466721834.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2016-06-23T22:43:54.429Z,1466721834.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2016-06-23T22:43:54.810Z,1466721834.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2016-06-23T22:43:55.121Z,1466721835.121 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:43:55.209Z,1466721835.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2016-06-23T22:43:55.661Z,1466721835.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2016-06-23T22:43:56.014Z,1466721836.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2016-06-23T22:43:56.422Z,1466721836.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2016-06-23T22:43:56.845Z,1466721836.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2016-06-23T22:43:57.221Z,1466721837.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2016-06-23T22:43:57.629Z,1466721837.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2016-06-23T22:44:05.769Z,1466721845.769 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:44:14.587Z,1466721854.587 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:44:14.587Z,1466721854.587 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:44:14.587Z,1466721854.587 [BPC1](ERROR): Communications Fault
2016-06-23T22:44:14.668Z,1466721854.668 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:44:15.607Z,1466721855.606 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:44:15.607Z,1466721855.607 [BPC1] No Fault, FailCount= 1
2016-06-23T22:44:19.634Z,1466721859.634 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:44:27.980Z,1466721867.980 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI34M.000
2016-06-23T22:44:30.414Z,1466721870.414 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:44:32.986Z,1466721872.986 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI34M.000
2016-06-23T22:44:34.606Z,1466721874.606 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4WI34M.000
2016-06-23T22:44:36.786Z,1466721876.786 [CBIT](DEBUG): EFC running - data check-sum false
2016-06-23T22:44:46.269Z,1466721886.269 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:44:51.615Z,1466721891.615 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4WI34M.000
2016-06-23T22:44:52.072Z,1466721892.072 [NavChartDb](INFO): # of records loaded: 26093
2016-06-23T22:44:52.078Z,1466721892.078 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4WI34M.000
2016-06-23T22:44:52.079Z,1466721892.079 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4WI33M.000
2016-06-23T22:44:54.108Z,1466721894.108 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4WI33M.000
2016-06-23T22:44:59.889Z,1466721899.889 [NAL9602](FAULT): GPS failed to acquire within timeout.
2016-06-23T22:44:59.889Z,1466721899.889 [NAL9602] Data Fault, FailCount= 1
2016-06-23T22:44:59.889Z,1466721899.889 [NAL9602](ERROR): Data Fault
2016-06-23T22:45:00.012Z,1466721900.012 [CBIT](ERROR): Data Fault in component: NAL9602
2016-06-23T22:45:00.288Z,1466721900.288 [NAL9602](INFO): Powering down
2016-06-23T22:45:01.192Z,1466721901.192 [CBIT](INFO): Clearing failed state for component NAL9602
2016-06-23T22:45:01.192Z,1466721901.192 [NAL9602] No Fault, FailCount= 1
2016-06-23T22:45:05.488Z,1466721905.489 [NAL9602](INFO): Powering up NAL9602
2016-06-23T22:45:06.629Z,1466721906.629 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:45:16.649Z,1466721916.648 [NAL9602](INFO): NAL9602 initialized
2016-06-23T22:45:19.190Z,1466721919.190 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:45:21.298Z,1466721921.298 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI33M.000
2016-06-23T22:45:22.821Z,1466721922.821 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4WI33M.000
2016-06-23T22:45:31.358Z,1466721931.358 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4WI33M.000
2016-06-23T22:45:31.636Z,1466721931.636 [NavChartDb](INFO): # of records loaded: 14663
2016-06-23T22:45:31.640Z,1466721931.640 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4WI33M.000
2016-06-23T22:45:31.641Z,1466721931.641 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4WI06M.000
2016-06-23T22:45:37.185Z,1466721937.185 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4WI06M.000
2016-06-23T22:45:50.594Z,1466721950.594 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:46:03.630Z,1466721963.630 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:46:16.284Z,1466721976.284 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:46:26.917Z,1466721986.917 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:46:37.322Z,1466721997.322 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:46:39.183Z,1466721999.183 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:46:39.187Z,1466721999.187 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2016-06-23T22:46:39.187Z,1466721999.187 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:46:39.189Z,1466721999.189 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:46:39.190Z,1466721999.190 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2016-06-23T22:46:39.190Z,1466721999.190 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:46:39.206Z,1466721999.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:46:39.206Z,1466721999.207 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:46:39.608Z,1466721999.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:46:39.608Z,1466721999.608 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2016-06-23T22:46:39.608Z,1466721999.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:46:39.608Z,1466721999.608 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2016-06-23T22:46:39.949Z,1466721999.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:46:39.950Z,1466721999.950 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:46:39.950Z,1466721999.950 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:46:39.950Z,1466721999.950 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:46:39.951Z,1466721999.951 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:46:39.956Z,1466721999.956 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:46:40.357Z,1466722000.357 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2016-06-23T22:46:40.737Z,1466722000.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2016-06-23T22:46:41.163Z,1466722001.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2016-06-23T22:46:41.568Z,1466722001.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2016-06-23T22:46:41.972Z,1466722001.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2016-06-23T22:46:42.360Z,1466722002.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2016-06-23T22:46:42.772Z,1466722002.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2016-06-23T22:46:43.142Z,1466722003.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2016-06-23T22:46:43.553Z,1466722003.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2016-06-23T22:46:44.012Z,1466722004.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2016-06-23T22:46:44.355Z,1466722004.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2016-06-23T22:46:44.780Z,1466722004.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2016-06-23T22:46:45.149Z,1466722005.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2016-06-23T22:46:45.593Z,1466722005.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2016-06-23T22:46:45.948Z,1466722005.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T22:46:46.368Z,1466722006.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T22:46:46.744Z,1466722006.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2016-06-23T22:46:47.155Z,1466722007.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2016-06-23T22:46:47.565Z,1466722007.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T22:46:47.770Z,1466722007.770 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T22:46:47.959Z,1466722007.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2016-06-23T22:46:48.356Z,1466722008.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T22:46:48.765Z,1466722008.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T22:46:49.153Z,1466722009.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T22:46:49.532Z,1466722009.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T22:46:49.963Z,1466722009.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T22:46:50.361Z,1466722010.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T22:46:50.768Z,1466722010.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T22:46:51.162Z,1466722011.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2016-06-23T22:46:51.561Z,1466722011.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T22:46:51.964Z,1466722011.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2016-06-23T22:46:52.354Z,1466722012.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2016-06-23T22:46:52.744Z,1466722012.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T22:46:53.160Z,1466722013.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2016-06-23T22:46:53.574Z,1466722013.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2016-06-23T22:46:53.951Z,1466722013.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2016-06-23T22:46:54.348Z,1466722014.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2016-06-23T22:46:54.772Z,1466722014.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2016-06-23T22:46:55.170Z,1466722015.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2016-06-23T22:46:55.572Z,1466722015.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2016-06-23T22:46:55.940Z,1466722015.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2016-06-23T22:46:56.349Z,1466722016.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2016-06-23T22:46:56.751Z,1466722016.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2016-06-23T22:46:57.182Z,1466722017.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2016-06-23T22:46:57.567Z,1466722017.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2016-06-23T22:46:57.997Z,1466722017.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2016-06-23T22:46:58.359Z,1466722018.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2016-06-23T22:46:58.544Z,1466722018.544 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T22:46:58.773Z,1466722018.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2016-06-23T22:46:59.170Z,1466722019.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2016-06-23T22:47:10.189Z,1466722030.189 [NavChartDb](INFO): # of records loaded: 40000
2016-06-23T22:47:22.855Z,1466722042.855 [NavChartDb](INFO): # of records loaded: 45000
2016-06-23T22:47:33.851Z,1466722053.851 [NavChartDb](INFO): # of records loaded: 50000
2016-06-23T22:47:43.714Z,1466722063.714 [NavChartDb](INFO): # of records loaded: 55000
2016-06-23T22:47:51.079Z,1466722071.079 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI06M.000
2016-06-23T22:47:53.115Z,1466722073.115 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4WI06M.000
2016-06-23T22:47:54.223Z,1466722074.223 [NavChartDb](INFO): # of records loaded: 60000
2016-06-23T22:48:01.491Z,1466722081.491 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:48:01.491Z,1466722081.491 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:48:01.491Z,1466722081.491 [BPC1](ERROR): Communications Fault
2016-06-23T22:48:01.574Z,1466722081.574 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:48:02.713Z,1466722082.713 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:48:02.713Z,1466722082.713 [BPC1] No Fault, FailCount= 1
2016-06-23T22:48:08.714Z,1466722088.714 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-06-23T22:48:08.714Z,1466722088.714 [Default:CheckIn:C.Wait] Stopped
2016-06-23T22:48:08.714Z,1466722088.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-06-23T22:48:08.715Z,1466722088.714 [Default:CheckIn:D] Running Loop=1
2016-06-23T22:48:09.113Z,1466722089.113 [Default:CheckIn:D] Stopped
2016-06-23T22:48:09.113Z,1466722089.113 [Default:CheckIn:E] Running Loop=1
2016-06-23T22:48:09.518Z,1466722089.518 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.635792 min 
2016-06-23T22:48:09.521Z,1466722089.520 [Default:CheckIn:E] Stopped
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn] Stopped
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn](INFO): Running loop #2
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn] Running Loop=2
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-06-23T22:48:09.521Z,1466722089.521 [Default:CheckIn:Read_GPS] Running Loop=1
2016-06-23T22:48:15.916Z,1466722095.916 [NavChartDb](INFO): # of records loaded: 65000
2016-06-23T22:48:24.091Z,1466722104.091 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4WI06M.000
2016-06-23T22:48:24.702Z,1466722104.702 [NavChartDb](INFO): # of records loaded: 66527
2016-06-23T22:48:24.725Z,1466722104.725 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4WI06M.000
2016-06-23T22:48:24.726Z,1466722104.726 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4WI03M.000
2016-06-23T22:48:28.356Z,1466722108.355 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4WI03M.000
2016-06-23T22:48:41.275Z,1466722121.275 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:48:50.711Z,1466722130.711 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:49:00.738Z,1466722140.738 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:49:12.088Z,1466722152.088 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:49:16.259Z,1466722156.259 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:49:16.259Z,1466722156.259 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:49:16.259Z,1466722156.259 [BPC1](ERROR): Communications Fault
2016-06-23T22:49:16.365Z,1466722156.365 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:49:17.269Z,1466722157.269 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:49:17.269Z,1466722157.269 [BPC1] No Fault, FailCount= 1
2016-06-23T22:49:24.017Z,1466722164.017 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:49:34.286Z,1466722174.286 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T22:49:40.040Z,1466722180.040 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:49:40.040Z,1466722180.040 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2016-06-23T22:49:40.040Z,1466722180.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:49:40.042Z,1466722180.042 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:49:40.042Z,1466722180.042 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2016-06-23T22:49:40.042Z,1466722180.042 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:49:40.107Z,1466722180.107 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:49:40.108Z,1466722180.108 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:49:40.463Z,1466722180.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:49:40.463Z,1466722180.463 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2016-06-23T22:49:40.463Z,1466722180.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:49:40.463Z,1466722180.463 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2016-06-23T22:49:40.821Z,1466722180.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:49:40.822Z,1466722180.822 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:49:40.822Z,1466722180.822 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:49:40.823Z,1466722180.823 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:49:40.823Z,1466722180.823 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:49:40.823Z,1466722180.823 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:49:41.225Z,1466722181.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2016-06-23T22:49:41.624Z,1466722181.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2016-06-23T22:49:42.036Z,1466722182.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2016-06-23T22:49:42.426Z,1466722182.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2016-06-23T22:49:42.892Z,1466722182.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2016-06-23T22:49:43.244Z,1466722183.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2016-06-23T22:49:43.637Z,1466722183.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2016-06-23T22:49:43.790Z,1466722183.790 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T22:49:44.041Z,1466722184.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2016-06-23T22:49:44.452Z,1466722184.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2016-06-23T22:49:44.825Z,1466722184.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2016-06-23T22:49:45.262Z,1466722185.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2016-06-23T22:49:45.640Z,1466722185.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2016-06-23T22:49:46.065Z,1466722186.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2016-06-23T22:49:46.473Z,1466722186.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2016-06-23T22:49:46.832Z,1466722186.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T22:49:47.229Z,1466722187.229 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T22:49:47.620Z,1466722187.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2016-06-23T22:49:48.043Z,1466722188.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2016-06-23T22:49:48.433Z,1466722188.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T22:49:48.837Z,1466722188.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2016-06-23T22:49:49.244Z,1466722189.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T22:49:49.648Z,1466722189.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T22:49:50.041Z,1466722190.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T22:49:50.447Z,1466722190.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T22:49:50.822Z,1466722190.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T22:49:51.220Z,1466722191.220 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T22:49:51.411Z,1466722191.411 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4WI03M.000
2016-06-23T22:49:51.634Z,1466722191.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T22:49:52.041Z,1466722192.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2016-06-23T22:49:52.439Z,1466722192.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T22:49:52.839Z,1466722192.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2016-06-23T22:49:53.029Z,1466722193.029 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4WI03M.000
2016-06-23T22:49:53.236Z,1466722193.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2016-06-23T22:49:53.520Z,1466722193.520 [NavChartDb](INFO): # of records loaded: 40000
2016-06-23T22:49:53.637Z,1466722193.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T22:49:54.032Z,1466722194.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2016-06-23T22:49:54.426Z,1466722194.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2016-06-23T22:49:54.828Z,1466722194.829 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2016-06-23T22:49:55.252Z,1466722195.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2016-06-23T22:49:55.637Z,1466722195.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2016-06-23T22:49:56.013Z,1466722196.013 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2016-06-23T22:49:56.450Z,1466722196.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2016-06-23T22:49:56.817Z,1466722196.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2016-06-23T22:49:57.271Z,1466722197.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2016-06-23T22:49:57.641Z,1466722197.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2016-06-23T22:49:58.045Z,1466722198.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2016-06-23T22:49:58.487Z,1466722198.487 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2016-06-23T22:49:58.836Z,1466722198.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2016-06-23T22:49:59.237Z,1466722199.237 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2016-06-23T22:49:59.618Z,1466722199.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2016-06-23T22:50:09.014Z,1466722209.013 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4WI03M.000
2016-06-23T22:50:13.279Z,1466722213.279 [NavChartDb](INFO): # of records loaded: 44415
2016-06-23T22:50:13.286Z,1466722213.286 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4WI03M.000
2016-06-23T22:50:13.287Z,1466722213.287 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI90M.000
2016-06-23T22:50:15.959Z,1466722215.959 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:50:15.959Z,1466722215.959 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:50:15.959Z,1466722215.959 [BPC1](ERROR): Communications Fault
2016-06-23T22:50:16.043Z,1466722216.043 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:50:16.954Z,1466722216.954 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:50:16.954Z,1466722216.954 [BPC1] No Fault, FailCount= 1
2016-06-23T22:50:17.922Z,1466722217.922 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI90M.000
2016-06-23T22:50:32.429Z,1466722232.429 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:50:43.357Z,1466722243.357 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:50:55.131Z,1466722255.131 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:51:06.526Z,1466722266.526 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:51:11.875Z,1466722271.875 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI90M.000
2016-06-23T22:51:16.178Z,1466722276.178 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI90M.000
2016-06-23T22:51:19.640Z,1466722279.640 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:51:34.279Z,1466722294.279 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI90M.000
2016-06-23T22:51:36.810Z,1466722296.810 [NavChartDb](INFO): # of records loaded: 27984
2016-06-23T22:51:36.816Z,1466722296.816 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI90M.000
2016-06-23T22:51:36.817Z,1466722296.818 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI89M.000
2016-06-23T22:51:37.961Z,1466722297.961 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI89M.000
2016-06-23T22:51:48.334Z,1466722308.334 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:51:53.713Z,1466722313.713 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:51:53.714Z,1466722313.714 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:51:53.714Z,1466722313.714 [BPC1](ERROR): Communications Fault
2016-06-23T22:51:53.782Z,1466722313.782 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:51:54.986Z,1466722314.986 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:51:54.986Z,1466722314.986 [BPC1] No Fault, FailCount= 1
2016-06-23T22:51:59.150Z,1466722319.150 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:52:04.431Z,1466722324.431 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI89M.000
2016-06-23T22:52:04.960Z,1466722324.960 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI89M.000
2016-06-23T22:52:10.461Z,1466722330.461 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI89M.000
2016-06-23T22:52:11.397Z,1466722331.397 [NavChartDb](INFO): # of records loaded: 14969
2016-06-23T22:52:11.401Z,1466722331.401 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI89M.000
2016-06-23T22:52:11.412Z,1466722331.411 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI59M.000
2016-06-23T22:52:15.322Z,1466722335.322 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI59M.000
2016-06-23T22:52:28.286Z,1466722348.286 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:52:38.316Z,1466722358.316 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:52:40.966Z,1466722360.966 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:52:40.966Z,1466722360.966 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2016-06-23T22:52:40.966Z,1466722360.966 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:52:40.972Z,1466722360.972 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:52:40.973Z,1466722360.973 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2016-06-23T22:52:40.973Z,1466722360.973 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:52:41.045Z,1466722361.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:52:41.045Z,1466722361.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:52:41.392Z,1466722361.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:52:41.392Z,1466722361.392 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2016-06-23T22:52:41.392Z,1466722361.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:52:41.392Z,1466722361.392 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2016-06-23T22:52:41.772Z,1466722361.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:52:41.773Z,1466722361.773 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:52:41.773Z,1466722361.773 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:52:41.774Z,1466722361.773 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:52:41.774Z,1466722361.774 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:52:41.774Z,1466722361.774 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:52:42.169Z,1466722362.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2016-06-23T22:52:42.577Z,1466722362.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2016-06-23T22:52:42.956Z,1466722362.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2016-06-23T22:52:43.380Z,1466722363.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2016-06-23T22:52:43.748Z,1466722363.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2016-06-23T22:52:44.154Z,1466722364.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2016-06-23T22:52:44.569Z,1466722364.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2016-06-23T22:52:44.989Z,1466722364.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2016-06-23T22:52:45.364Z,1466722365.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2016-06-23T22:52:45.773Z,1466722365.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2016-06-23T22:52:46.156Z,1466722366.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2016-06-23T22:52:46.568Z,1466722366.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2016-06-23T22:52:46.965Z,1466722366.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2016-06-23T22:52:47.352Z,1466722367.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2016-06-23T22:52:47.764Z,1466722367.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T22:52:48.164Z,1466722368.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T22:52:48.626Z,1466722368.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2016-06-23T22:52:48.885Z,1466722368.885 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:52:48.960Z,1466722368.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2016-06-23T22:52:49.394Z,1466722369.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T22:52:49.828Z,1466722369.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2016-06-23T22:52:50.168Z,1466722370.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T22:52:50.577Z,1466722370.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T22:52:50.957Z,1466722370.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T22:52:51.398Z,1466722371.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T22:52:51.809Z,1466722371.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T22:52:52.180Z,1466722372.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T22:52:52.564Z,1466722372.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T22:52:52.965Z,1466722372.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2016-06-23T22:52:53.361Z,1466722373.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T22:52:53.774Z,1466722373.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2016-06-23T22:52:54.208Z,1466722374.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2016-06-23T22:52:54.570Z,1466722374.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T22:52:54.978Z,1466722374.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2016-06-23T22:52:55.367Z,1466722375.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2016-06-23T22:52:55.767Z,1466722375.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2016-06-23T22:52:56.170Z,1466722376.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2016-06-23T22:52:56.545Z,1466722376.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2016-06-23T22:52:56.940Z,1466722376.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2016-06-23T22:52:57.376Z,1466722377.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2016-06-23T22:52:57.776Z,1466722377.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2016-06-23T22:52:58.182Z,1466722378.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2016-06-23T22:52:58.548Z,1466722378.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2016-06-23T22:52:58.950Z,1466722378.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2016-06-23T22:52:59.348Z,1466722379.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2016-06-23T22:52:59.786Z,1466722379.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2016-06-23T22:53:00.151Z,1466722380.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2016-06-23T22:53:00.568Z,1466722380.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2016-06-23T22:53:00.657Z,1466722380.657 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:53:09.762Z,1466722389.762 [Default:CheckIn:Read_GPS](INFO): Timed out from 2016-06-23T22:48:09.5Z
2016-06-23T22:53:09.762Z,1466722389.763 [Default:CheckIn:Read_GPS] Stopped
2016-06-23T22:53:09.763Z,1466722389.763 [Default:CheckIn:Read_Iridium] Running Loop=1
2016-06-23T22:53:11.664Z,1466722391.664 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:53:17.726Z,1466722397.726 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20160623T223427/Courier0008.lzma
2016-06-23T22:53:18.516Z,1466722398.516 [DataOverHttps](INFO): Moved sent file to Logs/20160623T223427/Courier0008.lzma.bak
2016-06-23T22:53:18.516Z,1466722398.516 [DataOverHttps](INFO): SBD MOMSN=4285931
2016-06-23T22:53:24.977Z,1466722404.977 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T22:53:33.259Z,1466722413.259 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20160623T223427/Express0009.lzma
2016-06-23T22:53:34.028Z,1466722414.028 [DataOverHttps](INFO): Moved sent file to Logs/20160623T223427/Express0009.lzma.bak
2016-06-23T22:53:34.029Z,1466722414.029 [DataOverHttps](INFO): SBD MOMSN=4285933
2016-06-23T22:53:34.561Z,1466722414.561 [Default:CheckIn:Read_Iridium] Stopped
2016-06-23T22:53:34.561Z,1466722414.561 [Default:CheckIn:C.Wait] Running Loop=1
2016-06-23T22:53:34.562Z,1466722414.562 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2016-06-23T22:53:37.779Z,1466722417.779 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T22:53:52.078Z,1466722432.078 [NavChartDb](INFO): # of records loaded: 40000
2016-06-23T22:54:02.882Z,1466722442.882 [NavChartDb](INFO): # of records loaded: 45000
2016-06-23T22:54:09.360Z,1466722449.360 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI59M.000
2016-06-23T22:54:10.918Z,1466722450.918 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI59M.000
2016-06-23T22:54:14.681Z,1466722454.681 [NavChartDb](INFO): # of records loaded: 50000
2016-06-23T22:54:30.346Z,1466722470.346 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI59M.000
2016-06-23T22:54:32.456Z,1466722472.456 [NavChartDb](INFO): # of records loaded: 53065
2016-06-23T22:54:32.467Z,1466722472.467 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI59M.000
2016-06-23T22:54:32.468Z,1466722472.468 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI57M.000
2016-06-23T22:54:39.375Z,1466722479.375 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI57M.000
2016-06-23T22:54:40.230Z,1466722480.230 [CBIT](DEBUG): EFC running - data check-sum false
2016-06-23T22:54:56.197Z,1466722496.197 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:55:09.209Z,1466722509.209 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:55:18.245Z,1466722518.245 [NAL9602](FAULT): GPS failed to acquire within timeout.
2016-06-23T22:55:18.245Z,1466722518.245 [NAL9602] Data Fault, FailCount= 2
2016-06-23T22:55:18.245Z,1466722518.245 [NAL9602](ERROR): Data Fault
2016-06-23T22:55:18.350Z,1466722518.350 [CBIT](ERROR): Data Fault in component: NAL9602
2016-06-23T22:55:18.644Z,1466722518.644 [NAL9602](INFO): Powering down
2016-06-23T22:55:19.554Z,1466722519.554 [CBIT](INFO): Clearing failed state for component NAL9602
2016-06-23T22:55:19.554Z,1466722519.554 [NAL9602] No Fault, FailCount= 2
2016-06-23T22:55:21.337Z,1466722521.337 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:55:23.844Z,1466722523.844 [NAL9602](INFO): Powering up NAL9602
2016-06-23T22:55:30.133Z,1466722530.133 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:55:35.045Z,1466722535.045 [NAL9602](INFO): NAL9602 initialized
2016-06-23T22:55:41.826Z,1466722541.826 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:55:41.826Z,1466722541.826 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2016-06-23T22:55:41.826Z,1466722541.826 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:55:41.836Z,1466722541.836 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:55:41.837Z,1466722541.837 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2016-06-23T22:55:41.837Z,1466722541.837 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:55:41.873Z,1466722541.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:55:41.874Z,1466722541.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:55:42.255Z,1466722542.255 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:55:42.255Z,1466722542.255 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2016-06-23T22:55:42.255Z,1466722542.255 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:55:42.255Z,1466722542.255 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2016-06-23T22:55:42.470Z,1466722542.470 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:55:42.616Z,1466722542.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:55:42.617Z,1466722542.616 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:55:42.617Z,1466722542.617 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:55:42.617Z,1466722542.617 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:55:42.618Z,1466722542.618 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:55:42.618Z,1466722542.618 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:55:43.021Z,1466722543.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2016-06-23T22:55:43.428Z,1466722543.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2016-06-23T22:55:43.821Z,1466722543.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2016-06-23T22:55:44.193Z,1466722544.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2016-06-23T22:55:44.633Z,1466722544.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2016-06-23T22:55:45.022Z,1466722545.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2016-06-23T22:55:45.409Z,1466722545.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2016-06-23T22:55:45.828Z,1466722545.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2016-06-23T22:55:46.198Z,1466722546.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2016-06-23T22:55:46.604Z,1466722546.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2016-06-23T22:55:47.008Z,1466722547.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2016-06-23T22:55:47.414Z,1466722547.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2016-06-23T22:55:47.825Z,1466722547.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2016-06-23T22:55:48.208Z,1466722548.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2016-06-23T22:55:48.621Z,1466722548.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T22:55:48.990Z,1466722548.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T22:55:49.417Z,1466722549.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2016-06-23T22:55:49.793Z,1466722549.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2016-06-23T22:55:50.205Z,1466722550.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T22:55:50.621Z,1466722550.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2016-06-23T22:55:51.013Z,1466722551.013 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T22:55:51.411Z,1466722551.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T22:55:51.828Z,1466722551.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T22:55:52.198Z,1466722552.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T22:55:52.604Z,1466722552.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T22:55:53.028Z,1466722553.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T22:55:53.425Z,1466722553.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T22:55:53.829Z,1466722553.829 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2016-06-23T22:55:54.016Z,1466722554.016 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T22:55:54.205Z,1466722554.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T22:55:54.605Z,1466722554.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2016-06-23T22:55:55.009Z,1466722555.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2016-06-23T22:55:55.464Z,1466722555.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T22:55:55.805Z,1466722555.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2016-06-23T22:55:56.253Z,1466722556.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2016-06-23T22:55:56.628Z,1466722556.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2016-06-23T22:55:57.049Z,1466722557.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2016-06-23T22:55:57.450Z,1466722557.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2016-06-23T22:55:57.811Z,1466722557.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2016-06-23T22:55:58.213Z,1466722558.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2016-06-23T22:55:58.613Z,1466722558.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2016-06-23T22:55:59.024Z,1466722559.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2016-06-23T22:55:59.423Z,1466722559.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2016-06-23T22:55:59.819Z,1466722559.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2016-06-23T22:56:00.223Z,1466722560.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2016-06-23T22:56:00.594Z,1466722560.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2016-06-23T22:56:00.996Z,1466722560.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2016-06-23T22:56:01.424Z,1466722561.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2016-06-23T22:56:01.823Z,1466722561.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2016-06-23T22:56:03.450Z,1466722563.450 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T22:56:12.352Z,1466722572.352 [NavChartDb](INFO): # of records loaded: 40000
2016-06-23T22:56:23.580Z,1466722583.580 [NavChartDb](INFO): # of records loaded: 45000
2016-06-23T22:56:35.655Z,1466722595.655 [NavChartDb](INFO): # of records loaded: 50000
2016-06-23T22:56:44.754Z,1466722604.754 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI57M.000
2016-06-23T22:56:46.738Z,1466722606.738 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI57M.000
2016-06-23T22:56:49.719Z,1466722609.718 [NavChartDb](INFO): # of records loaded: 55000
2016-06-23T22:57:15.396Z,1466722635.396 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI57M.000
2016-06-23T22:57:17.549Z,1466722637.549 [NavChartDb](INFO): # of records loaded: 59244
2016-06-23T22:57:17.558Z,1466722637.558 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI57M.000
2016-06-23T22:57:17.559Z,1466722637.559 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI56M.000
2016-06-23T22:57:19.367Z,1466722639.367 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI56M.000
2016-06-23T22:57:30.550Z,1466722650.550 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:57:41.899Z,1466722661.899 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:57:41.899Z,1466722661.899 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:57:41.899Z,1466722661.899 [BPC1](ERROR): Communications Fault
2016-06-23T22:57:42.002Z,1466722662.002 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:57:42.590Z,1466722662.590 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:57:42.942Z,1466722662.941 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:57:42.942Z,1466722662.942 [BPC1] No Fault, FailCount= 1
2016-06-23T22:57:52.716Z,1466722672.716 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:58:02.388Z,1466722682.388 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T22:58:07.489Z,1466722687.489 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI56M.000
2016-06-23T22:58:08.107Z,1466722688.107 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI56M.000
2016-06-23T22:58:12.081Z,1466722692.081 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T22:58:17.584Z,1466722697.584 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI56M.000
2016-06-23T22:58:19.852Z,1466722699.852 [NavChartDb](INFO): # of records loaded: 26626
2016-06-23T22:58:19.856Z,1466722699.856 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI56M.000
2016-06-23T22:58:19.857Z,1466722699.857 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4IN01M.000
2016-06-23T22:58:22.858Z,1466722702.858 [BPC1](ERROR): Failed to parse bank B battery data
2016-06-23T22:58:22.858Z,1466722702.858 [BPC1] Data Fault, FailCount= 1
2016-06-23T22:58:22.858Z,1466722702.858 [BPC1](ERROR): Data Fault
2016-06-23T22:58:22.936Z,1466722702.935 [CBIT](ERROR): Data Fault in component: BPC1
2016-06-23T22:58:23.561Z,1466722703.560 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4IN01M.000
2016-06-23T22:58:24.122Z,1466722704.122 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:58:24.122Z,1466722704.122 [BPC1] No Fault, FailCount= 1
2016-06-23T22:58:34.883Z,1466722714.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2016-06-23T22:58:34.883Z,1466722714.883 [Default:CheckIn:C.Wait] Stopped
2016-06-23T22:58:34.887Z,1466722714.887 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2016-06-23T22:58:34.887Z,1466722714.887 [Default:CheckIn:D] Running Loop=1
2016-06-23T22:58:35.291Z,1466722715.291 [Default:CheckIn:D] Stopped
2016-06-23T22:58:35.291Z,1466722715.291 [Default:CheckIn:E] Running Loop=1
2016-06-23T22:58:35.673Z,1466722715.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.072111 min 
2016-06-23T22:58:35.673Z,1466722715.673 [Default:CheckIn:E] Stopped
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn](INFO): Completed Default:CheckIn
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn] Stopped
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn](INFO): Running loop #3
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn] Running Loop=3
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2016-06-23T22:58:35.674Z,1466722715.674 [Default:CheckIn:Read_GPS] Running Loop=1
2016-06-23T22:58:39.668Z,1466722719.668 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:58:41.985Z,1466722721.985 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:58:41.985Z,1466722721.985 [BPC1] Communications Fault, FailCount= 2
2016-06-23T22:58:41.985Z,1466722721.985 [BPC1](ERROR): Communications Fault
2016-06-23T22:58:42.059Z,1466722722.059 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:58:42.937Z,1466722722.937 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:58:42.937Z,1466722722.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2016-06-23T22:58:42.937Z,1466722722.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T22:58:42.939Z,1466722722.939 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T22:58:42.939Z,1466722722.939 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2016-06-23T22:58:42.939Z,1466722722.939 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T22:58:42.992Z,1466722722.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T22:58:42.992Z,1466722722.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T22:58:42.992Z,1466722722.992 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:58:42.992Z,1466722722.992 [BPC1] No Fault, FailCount= 2
2016-06-23T22:58:43.440Z,1466722723.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T22:58:43.440Z,1466722723.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2016-06-23T22:58:43.440Z,1466722723.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T22:58:43.440Z,1466722723.441 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2016-06-23T22:58:43.756Z,1466722723.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T22:58:43.756Z,1466722723.756 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:58:43.757Z,1466722723.757 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:58:43.757Z,1466722723.757 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T22:58:43.757Z,1466722723.757 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T22:58:43.758Z,1466722723.758 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T22:58:44.157Z,1466722724.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2016-06-23T22:58:44.537Z,1466722724.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2016-06-23T22:58:44.948Z,1466722724.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2016-06-23T22:58:45.344Z,1466722725.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2016-06-23T22:58:45.740Z,1466722725.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2016-06-23T22:58:46.137Z,1466722726.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2016-06-23T22:58:46.547Z,1466722726.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2016-06-23T22:58:46.933Z,1466722726.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2016-06-23T22:58:47.346Z,1466722727.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2016-06-23T22:58:47.744Z,1466722727.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2016-06-23T22:58:48.146Z,1466722728.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2016-06-23T22:58:48.525Z,1466722728.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2016-06-23T22:58:48.916Z,1466722728.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2016-06-23T22:58:49.322Z,1466722729.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2016-06-23T22:58:49.741Z,1466722729.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T22:58:50.149Z,1466722730.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T22:58:50.538Z,1466722730.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2016-06-23T22:58:50.935Z,1466722730.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2016-06-23T22:58:51.234Z,1466722731.234 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T22:58:51.341Z,1466722731.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T22:58:51.717Z,1466722731.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2016-06-23T22:58:52.117Z,1466722732.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T22:58:52.557Z,1466722732.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T22:58:52.927Z,1466722732.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T22:58:53.341Z,1466722733.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T22:58:53.761Z,1466722733.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T22:58:54.161Z,1466722734.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T22:58:54.581Z,1466722734.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T22:58:54.926Z,1466722734.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2016-06-23T22:58:55.349Z,1466722735.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T22:58:55.741Z,1466722735.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2016-06-23T22:58:56.191Z,1466722736.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2016-06-23T22:58:56.549Z,1466722736.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T22:58:56.974Z,1466722736.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2016-06-23T22:58:57.333Z,1466722737.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2016-06-23T22:58:57.757Z,1466722737.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2016-06-23T22:58:58.169Z,1466722738.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2016-06-23T22:58:58.536Z,1466722738.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2016-06-23T22:58:58.682Z,1466722738.682 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4IN01M.000
2016-06-23T22:58:58.914Z,1466722738.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2016-06-23T22:58:59.360Z,1466722739.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2016-06-23T22:58:59.652Z,1466722739.652 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4IN01M.000
2016-06-23T22:58:59.748Z,1466722739.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2016-06-23T22:59:00.149Z,1466722740.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2016-06-23T22:59:00.525Z,1466722740.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2016-06-23T22:59:00.951Z,1466722740.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2016-06-23T22:59:01.322Z,1466722741.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2016-06-23T22:59:01.723Z,1466722741.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2016-06-23T22:59:02.137Z,1466722742.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2016-06-23T22:59:02.290Z,1466722742.290 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T22:59:02.541Z,1466722742.541 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2016-06-23T22:59:11.801Z,1466722751.801 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4IN01M.000
2016-06-23T22:59:22.688Z,1466722762.688 [NavChartDb](INFO): # of records loaded: 16719
2016-06-23T22:59:22.692Z,1466722762.692 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4IN01M.000
2016-06-23T22:59:22.693Z,1466722762.693 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4CA60M.000
2016-06-23T22:59:31.965Z,1466722771.965 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4CA60M.000
2016-06-23T22:59:42.139Z,1466722782.139 [BPC1](ERROR): Failed to receive battery data
2016-06-23T22:59:42.139Z,1466722782.139 [BPC1] Communications Fault, FailCount= 1
2016-06-23T22:59:42.140Z,1466722782.139 [BPC1](ERROR): Communications Fault
2016-06-23T22:59:42.239Z,1466722782.239 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T22:59:43.118Z,1466722783.118 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T22:59:43.118Z,1466722783.118 [BPC1] No Fault, FailCount= 1
2016-06-23T22:59:47.565Z,1466722787.565 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T22:59:58.640Z,1466722798.640 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T23:00:08.299Z,1466722808.299 [BPC1](ERROR): Failed to parse bank B battery data
2016-06-23T23:00:08.299Z,1466722808.299 [BPC1] Data Fault, FailCount= 1
2016-06-23T23:00:08.299Z,1466722808.299 [BPC1](ERROR): Data Fault
2016-06-23T23:00:08.394Z,1466722808.395 [CBIT](ERROR): Data Fault in component: BPC1
2016-06-23T23:00:09.197Z,1466722809.197 [NavChartDb](INFO): # of records loaded: 15000
2016-06-23T23:00:09.529Z,1466722809.529 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T23:00:09.529Z,1466722809.529 [BPC1] No Fault, FailCount= 1
2016-06-23T23:00:20.299Z,1466722820.299 [NavChartDb](INFO): # of records loaded: 20000
2016-06-23T23:00:31.634Z,1466722831.634 [NavChartDb](INFO): # of records loaded: 25000
2016-06-23T23:00:42.309Z,1466722842.309 [BPC1](ERROR): Failed to receive battery data
2016-06-23T23:00:42.309Z,1466722842.309 [BPC1] Communications Fault, FailCount= 1
2016-06-23T23:00:42.309Z,1466722842.309 [BPC1](ERROR): Communications Fault
2016-06-23T23:00:42.385Z,1466722842.385 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T23:00:43.315Z,1466722843.315 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T23:00:43.315Z,1466722843.315 [BPC1] No Fault, FailCount= 1
2016-06-23T23:00:44.910Z,1466722844.910 [NavChartDb](INFO): # of records loaded: 30000
2016-06-23T23:00:53.871Z,1466722853.871 [NavChartDb](INFO): # of records loaded: 35000
2016-06-23T23:01:10.810Z,1466722870.810 [NavChartDb](INFO): # of records loaded: 40000
2016-06-23T23:01:31.777Z,1466722891.777 [NavChartDb](INFO): # of records loaded: 45000
2016-06-23T23:01:35.376Z,1466722895.376 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4CA60M.000
2016-06-23T23:01:37.380Z,1466722897.380 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4CA60M.000
2016-06-23T23:01:42.599Z,1466722902.599 [BPC1](ERROR): Failed to receive battery data
2016-06-23T23:01:42.599Z,1466722902.599 [BPC1] Communications Fault, FailCount= 1
2016-06-23T23:01:42.599Z,1466722902.599 [BPC1](ERROR): Communications Fault
2016-06-23T23:01:42.657Z,1466722902.657 [CBIT](ERROR): Communications Fault in component: BPC1
2016-06-23T23:01:43.573Z,1466722903.573 [CBIT](INFO): Clearing failed state for component BPC1
2016-06-23T23:01:43.573Z,1466722903.573 [BPC1] No Fault, FailCount= 1
2016-06-23T23:01:44.005Z,1466722904.005 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T23:01:44.005Z,1466722904.005 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2016-06-23T23:01:44.005Z,1466722904.005 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2016-06-23T23:01:44.011Z,1466722904.011 [DeadReckonUsingSpeedCalculator](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2016-06-23T23:01:44.012Z,1466722904.011 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2016-06-23T23:01:44.012Z,1466722904.012 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2016-06-23T23:01:44.072Z,1466722904.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2016-06-23T23:01:44.072Z,1466722904.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2016-06-23T23:01:44.368Z,1466722904.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2016-06-23T23:01:44.368Z,1466722904.368 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2016-06-23T23:01:44.368Z,1466722904.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2016-06-23T23:01:44.368Z,1466722904.368 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2016-06-23T23:01:44.739Z,1466722904.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-06-23T23:01:44.739Z,1466722904.739 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T23:01:44.740Z,1466722904.740 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T23:01:44.740Z,1466722904.740 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-06-23T23:01:44.740Z,1466722904.740 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-06-23T23:01:44.741Z,1466722904.741 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-06-23T23:01:45.138Z,1466722905.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s.
2016-06-23T23:01:45.537Z,1466722905.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.5 s old, using for 20.0 s.
2016-06-23T23:01:45.935Z,1466722905.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.9 s old, using for 20.0 s.
2016-06-23T23:01:46.332Z,1466722906.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.3 s old, using for 20.0 s.
2016-06-23T23:01:46.735Z,1466722906.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.7 s old, using for 20.0 s.
2016-06-23T23:01:47.142Z,1466722907.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.1 s old, using for 20.0 s.
2016-06-23T23:01:47.552Z,1466722907.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s.
2016-06-23T23:01:47.928Z,1466722907.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.9 s old, using for 20.0 s.
2016-06-23T23:01:48.324Z,1466722908.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2016-06-23T23:01:48.733Z,1466722908.733 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s.
2016-06-23T23:01:49.152Z,1466722909.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2016-06-23T23:01:49.542Z,1466722909.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2016-06-23T23:01:49.937Z,1466722909.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2016-06-23T23:01:50.347Z,1466722910.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2016-06-23T23:01:50.758Z,1466722910.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2016-06-23T23:01:51.162Z,1466722911.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2016-06-23T23:01:51.561Z,1466722911.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2016-06-23T23:01:51.956Z,1466722911.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2016-06-23T23:01:52.358Z,1466722912.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2016-06-23T23:01:52.765Z,1466722912.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2016-06-23T23:01:53.168Z,1466722913.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2016-06-23T23:01:53.563Z,1466722913.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2016-06-23T23:01:53.742Z,1466722913.742 [NavChartDb](INFO): # of records loaded: 50000
2016-06-23T23:01:53.960Z,1466722913.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2016-06-23T23:01:54.368Z,1466722914.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2016-06-23T23:01:54.761Z,1466722914.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2016-06-23T23:01:55.184Z,1466722915.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2016-06-23T23:01:55.568Z,1466722915.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2016-06-23T23:01:55.945Z,1466722915.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.9 s old, using for 20.0 s.
2016-06-23T23:01:56.368Z,1466722916.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2016-06-23T23:01:56.744Z,1466722916.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.7 s old, using for 20.0 s.
2016-06-23T23:01:57.177Z,1466722917.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2016-06-23T23:01:57.577Z,1466722917.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2016-06-23T23:01:57.940Z,1466722917.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.9 s old, using for 20.0 s.
2016-06-23T23:01:58.322Z,1466722918.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.3 s old, using for 20.0 s.
2016-06-23T23:01:58.749Z,1466722918.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.7 s old, using for 20.0 s.
2016-06-23T23:01:59.140Z,1466722919.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2016-06-23T23:01:59.533Z,1466722919.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2016-06-23T23:01:59.926Z,1466722919.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2016-06-23T23:02:00.316Z,1466722920.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2016-06-23T23:02:00.752Z,1466722920.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2016-06-23T23:02:01.131Z,1466722921.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2016-06-23T23:02:01.536Z,1466722921.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2016-06-23T23:02:01.937Z,1466722921.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2016-06-23T23:02:02.344Z,1466722922.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.3 s old, using for 20.0 s.
2016-06-23T23:02:02.743Z,1466722922.743 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2016-06-23T23:02:03.148Z,1466722923.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2016-06-23T23:02:03.549Z,1466722923.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2016-06-23T23:02:03.947Z,1466722923.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2016-06-23T23:02:16.991Z,1466722936.991 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4CA60M.000
2016-06-23T23:02:17.795Z,1466722937.795 [NavChartDb](INFO): # of records loaded: 53494
2016-06-23T23:02:17.811Z,1466722937.811 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4CA60M.000
2016-06-23T23:02:17.828Z,1466722937.828 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US3AK5KM.000
2016-06-23T23:02:33.012Z,1466722953.012 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US3AK5KM.000
2016-06-23T23:02:52.222Z,1466722972.222 [NavChartDb](INFO): # of records loaded: 5000
2016-06-23T23:03:06.839Z,1466722986.839 [NavChartDb](INFO): # of records loaded: 10000
2016-06-23T23:03:15.640Z,1466722995.640 [CommandLine](IMPORTANT): got command restart application
2016-06-23T23:03:16.650Z,1466722996.650 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T23:03:16.651Z,1466722996.651 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.759Z,1466722996.759 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2016-06-23T23:03:16.759Z,1466722996.759 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.760Z,1466722996.760 [CommandLine](INFO): Join timeout helper Thread ID is 6698
2016-06-23T23:03:16.760Z,1466722996.760 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2016-06-23T23:03:16.760Z,1466722996.760 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.761Z,1466722996.761 [NavChartDb](INFO): Join timeout helper Thread ID is 6699
2016-06-23T23:03:16.775Z,1466722996.775 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T23:03:16.775Z,1466722996.776 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.779Z,1466722996.779 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2016-06-23T23:03:16.779Z,1466722996.779 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.780Z,1466722996.780 [Radio_Surface](INFO): Join timeout helper Thread ID is 6700
2016-06-23T23:03:16.903Z,1466722996.903 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T23:03:16.904Z,1466722996.904 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.904Z,1466722996.904 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2016-06-23T23:03:16.904Z,1466722996.904 [logger ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.905Z,1466722996.905 [logger](INFO): Join timeout helper Thread ID is 6701
2016-06-23T23:03:16.932Z,1466722996.932 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T23:03:16.932Z,1466722996.932 [logger ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.935Z,1466722996.935 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2016-06-23T23:03:16.935Z,1466722996.935 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.935Z,1466722996.935 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2016-06-23T23:03:16.936Z,1466722996.935 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:16.936Z,1466722996.936 [controlThread](INFO): Join timeout helper Thread ID is 6702
2016-06-23T23:03:16.943Z,1466722996.943 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2016-06-23T23:03:16.943Z,1466722996.943 [controlThread](DEBUG): Uninitializing ControlThread
2016-06-23T23:03:16.944Z,1466722996.944 [NAL9602](INFO): Powering down
2016-06-23T23:03:16.945Z,1466722996.945 [PNI_TCM](INFO): Powering down
2016-06-23T23:03:17.032Z,1466722997.032 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-06-23T23:03:17.034Z,1466722997.034 [Default] Stopped
2016-06-23T23:03:17.034Z,1466722997.034 [Default](DEBUG): Aggregate::uninitialize Default
2016-06-23T23:03:17.034Z,1466722997.034 [Default:B.GoToSurface] Stopped
2016-06-23T23:03:17.034Z,1466722997.034 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2016-06-23T23:03:17.034Z,1466722997.034 [Default:CheckIn] Stopped
2016-06-23T23:03:17.034Z,1466722997.034 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2016-06-23T23:03:17.034Z,1466722997.034 [Default:CheckIn:Read_GPS] Stopped
2016-06-23T23:03:17.037Z,1466722997.037 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2016-06-23T23:03:17.038Z,1466722997.037 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2016-06-23T23:03:17.038Z,1466722997.038 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2016-06-23T23:03:17.038Z,1466722997.038 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2016-06-23T23:03:17.038Z,1466722997.038 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-06-23T23:03:17.039Z,1466722997.039 [BuoyancyServo](INFO): Powering down
2016-06-23T23:03:17.051Z,1466722997.051 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2016-06-23T23:03:17.051Z,1466722997.051 [ElevatorServo](INFO): Powering down
2016-06-23T23:03:17.052Z,1466722997.052 [MassServo](DEBUG): Uninitialize Mass Servo.
2016-06-23T23:03:17.052Z,1466722997.052 [MassServo](INFO): Powering down
2016-06-23T23:03:17.053Z,1466722997.053 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2016-06-23T23:03:17.053Z,1466722997.053 [RudderServo](INFO): Powering down
2016-06-23T23:03:17.054Z,1466722997.054 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2016-06-23T23:03:17.054Z,1466722997.054 [ThrusterServo](INFO): Powering down
2016-06-23T23:03:17.055Z,1466722997.055 [SBIT](DEBUG): Uninitialize SBIT Component.
2016-06-23T23:03:17.056Z,1466722997.056 [IBIT](DEBUG): Uninitialize IBIT Component.
2016-06-23T23:03:17.056Z,1466722997.056 [CBIT](DEBUG): Uninitialize CBIT Component.
2016-06-23T23:03:17.056Z,1466722997.056 [CBIT](CRITICAL): Backplane failed to power down
2016-06-23T23:03:17.057Z,1466722997.057 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:17.167Z,1466722997.167 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:17.224Z,1466722997.224 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-06-23T23:03:17.268Z,1466722997.268 [logger ThreadHandler](INFO): Thread cancelled.