2015-01-28T18:34:11.076Z,1422470051.076 [Supervisor](DEBUG): Initializing supervisor.
2015-01-28T18:34:11.079Z,1422470051.079 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-01-28T18:34:11.080Z,1422470051.080 [SyncHandler](INFO): Protected caller Thread ID is 768
2015-01-28T18:34:11.080Z,1422470051.080 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-01-28T18:34:11.081Z,1422470051.081 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-01-28T18:34:11.081Z,1422470051.081 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769
2015-01-28T18:34:11.084Z,1422470051.084 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-01-28T18:34:11.096Z,1422470051.096 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-01-28T18:34:11.097Z,1422470051.097 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-01-28T18:34:11.098Z,1422470051.098 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770
2015-01-28T18:34:11.099Z,1422470051.099 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-01-28T18:34:11.099Z,1422470051.099 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-01-28T18:34:11.100Z,1422470051.100 [logger ThreadHandler](INFO): Protected caller Thread ID is 771
2015-01-28T18:34:11.102Z,1422470051.102 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-01-28T18:34:11.102Z,1422470051.102 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-01-28T18:34:11.106Z,1422470051.106 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-01-28T18:34:11.478Z,1422470051.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-01-28T18:34:11.481Z,1422470051.481 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-01-28T18:34:11.654Z,1422470051.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-01-28T18:34:11.654Z,1422470051.654 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-01-28T18:34:11.769Z,1422470051.769 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-01-28T18:34:11.769Z,1422470051.769 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-01-28T18:34:11.900Z,1422470051.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-01-28T18:34:11.900Z,1422470051.900 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-01-28T18:34:11.989Z,1422470051.989 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-01-28T18:34:12.163Z,1422470052.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-01-28T18:34:12.164Z,1422470052.164 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-01-28T18:34:12.254Z,1422470052.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-01-28T18:34:12.255Z,1422470052.255 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-01-28T18:34:12.482Z,1422470052.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-01-28T18:34:12.483Z,1422470052.483 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-01-28T18:34:12.894Z,1422470052.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-01-28T18:34:12.895Z,1422470052.895 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-01-28T18:34:13.247Z,1422470053.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-01-28T18:34:13.247Z,1422470053.247 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-01-28T18:34:13.811Z,1422470053.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-01-28T18:34:13.812Z,1422470053.812 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-01-28T18:34:14.033Z,1422470054.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-01-28T18:34:14.034Z,1422470054.034 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-01-28T18:34:14.144Z,1422470054.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-01-28T18:34:14.145Z,1422470054.145 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-01-28T18:34:14.613Z,1422470054.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-01-28T18:34:14.614Z,1422470054.614 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-01-28T18:34:14.736Z,1422470054.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-01-28T18:34:14.738Z,1422470054.738 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-01-28T18:34:18.375Z,1422470058.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-01-28T18:34:18.651Z,1422470058.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-01-28T18:34:27.095Z,1422470067.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-01-28T18:34:27.530Z,1422470067.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-01-28T18:34:29.310Z,1422470069.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-01-28T18:34:29.414Z,1422470069.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-01-28T18:34:29.537Z,1422470069.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-01-28T18:34:29.690Z,1422470069.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-01-28T18:34:29.878Z,1422470069.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-01-28T18:34:29.987Z,1422470069.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-01-28T18:34:30.081Z,1422470070.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-01-28T18:34:30.187Z,1422470070.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-01-28T18:34:30.289Z,1422470070.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-01-28T18:34:30.488Z,1422470070.488 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2015-01-28T18:34:30.512Z,1422470070.512 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-01-28T18:34:30.591Z,1422470070.591 [VerticalControl](DEBUG): Construct VerticalControl.
2015-01-28T18:34:30.723Z,1422470070.723 [VerticalControl] Loaded
2015-01-28T18:34:30.723Z,1422470070.723 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-01-28T18:34:30.724Z,1422470070.724 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-01-28T18:34:30.804Z,1422470070.804 [HorizontalControl] Loaded
2015-01-28T18:34:30.805Z,1422470070.805 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-01-28T18:34:30.805Z,1422470070.805 [SpeedControl](DEBUG): Construct SpeedControl.
2015-01-28T18:34:30.812Z,1422470070.812 [SpeedControl] Loaded
2015-01-28T18:34:30.812Z,1422470070.812 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-01-28T18:34:30.813Z,1422470070.813 [LoopControl](DEBUG): Construct LoopControl.
2015-01-28T18:34:30.813Z,1422470070.813 [LoopControl] Loaded
2015-01-28T18:34:30.814Z,1422470070.814 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-01-28T18:34:30.814Z,1422470070.814 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-01-28T18:34:30.815Z,1422470070.815 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-01-28T18:34:30.952Z,1422470070.952 [SBIT](DEBUG): Construct Startup Built In Test.
2015-01-28T18:34:30.964Z,1422470070.964 [SBIT] Loaded
2015-01-28T18:34:30.964Z,1422470070.964 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-01-28T18:34:30.965Z,1422470070.965 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-01-28T18:34:30.997Z,1422470070.997 [IBIT] Loaded
2015-01-28T18:34:30.997Z,1422470070.997 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-01-28T18:34:30.000Z,1422470071.000 [CBIT](DEBUG): Construct CBIT Built In Test.
2015-01-28T18:34:31.134Z,1422470071.134 [CBIT] Loaded
2015-01-28T18:34:31.134Z,1422470071.134 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-01-28T18:34:31.135Z,1422470071.135 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-01-28T18:34:31.136Z,1422470071.136 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-01-28T18:34:31.167Z,1422470071.167 [DepthRateCalculator] Loaded
2015-01-28T18:34:31.167Z,1422470071.167 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-01-28T18:34:31.173Z,1422470071.173 [PitchRateCalculator] Loaded
2015-01-28T18:34:31.174Z,1422470071.174 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-01-28T18:34:31.186Z,1422470071.186 [SpeedCalculator] Loaded
2015-01-28T18:34:31.187Z,1422470071.187 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-01-28T18:34:31.205Z,1422470071.205 [TempGradientCalculator] Loaded
2015-01-28T18:34:31.206Z,1422470071.206 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-01-28T18:34:31.212Z,1422470071.212 [YawRateCalculator] Loaded
2015-01-28T18:34:31.212Z,1422470071.212 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-01-28T18:34:31.212Z,1422470071.212 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-01-28T18:34:31.213Z,1422470071.213 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-01-28T18:34:31.310Z,1422470071.310 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-01-28T18:34:31.312Z,1422470071.312 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-01-28T18:34:31.622Z,1422470071.622 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-01-28T18:34:31.623Z,1422470071.623 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-01-28T18:34:31.740Z,1422470071.740 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-01-28T18:34:31.741Z,1422470071.741 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-01-28T18:34:31.760Z,1422470071.760 [NavChart] Loaded
2015-01-28T18:34:31.760Z,1422470071.760 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-01-28T18:34:31.765Z,1422470071.765 [UniversalFixResidualReporter] Loaded
2015-01-28T18:34:31.765Z,1422470071.765 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-01-28T18:34:31.766Z,1422470071.766 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-01-28T18:34:31.766Z,1422470071.766 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-01-28T18:34:31.776Z,1422470071.776 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-01-28T18:34:31.777Z,1422470071.777 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-01-28T18:34:31.905Z,1422470071.905 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-01-28T18:34:31.906Z,1422470071.906 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-01-28T18:34:32.576Z,1422470072.576 [DataOverHttps] Loaded
2015-01-28T18:34:32.577Z,1422470072.577 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-01-28T18:34:32.661Z,1422470072.661 [Depth_Keller] Loaded
2015-01-28T18:34:32.661Z,1422470072.661 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-01-28T18:34:32.767Z,1422470072.767 [NAL9602] Loaded
2015-01-28T18:34:32.767Z,1422470072.767 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-01-28T18:34:32.815Z,1422470072.815 [Onboard] Loaded
2015-01-28T18:34:32.815Z,1422470072.815 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-01-28T18:34:32.823Z,1422470072.823 [Radio_Surface] Loaded
2015-01-28T18:34:32.823Z,1422470072.823 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-01-28T18:34:32.824Z,1422470072.824 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2015-01-28T18:34:32.825Z,1422470072.825 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 853
2015-01-28T18:34:33.018Z,1422470073.018 [PNI_TCM] Loaded
2015-01-28T18:34:33.019Z,1422470073.019 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-01-28T18:34:35.102Z,1422470075.102 [BPC1] Loaded
2015-01-28T18:34:35.102Z,1422470075.102 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-01-28T18:34:35.103Z,1422470075.103 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-01-28T18:34:35.103Z,1422470075.103 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-01-28T18:34:42.908Z,1422470082.908 [BuoyancyServo] Loaded
2015-01-28T18:34:42.908Z,1422470082.908 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-01-28T18:34:42.922Z,1422470082.922 [ElevatorServo] Loaded
2015-01-28T18:34:42.922Z,1422470082.922 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-01-28T18:34:42.935Z,1422470082.935 [RudderServo] Loaded
2015-01-28T18:34:42.935Z,1422470082.935 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-01-28T18:34:42.947Z,1422470082.947 [ThrusterServo] Loaded
2015-01-28T18:34:42.948Z,1422470082.948 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-01-28T18:34:42.948Z,1422470082.948 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-01-28T18:34:42.949Z,1422470082.949 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-01-28T18:34:43.253Z,1422470083.253 [ExternalSim] Loaded
2015-01-28T18:34:43.254Z,1422470083.254 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2015-01-28T18:34:43.255Z,1422470083.255 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-01-28T18:34:43.255Z,1422470083.255 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-01-28T18:34:43.299Z,1422470083.299 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-01-28T18:34:43.301Z,1422470083.301 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-01-28T18:34:43.302Z,1422470083.302 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-01-28T18:34:43.309Z,1422470083.309 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-01-28T18:34:43.310Z,1422470083.310 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4083C4E0
2015-01-28T18:34:43.310Z,1422470083.310 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 854
2015-01-28T18:34:43.315Z,1422470083.315 [Supervisor](INFO): Main Thread ID is 766
2015-01-28T18:34:43.315Z,1422470083.315 [Supervisor](DEBUG): Running supervisor.
2015-01-28T18:34:43.316Z,1422470083.316 [CommandLine ThreadHandler](INFO): Handler Thread ID is 855
2015-01-28T18:34:43.319Z,1422470083.319 [controlThread ThreadHandler](INFO): Handler Thread ID is 856
2015-01-28T18:34:43.319Z,1422470083.319 [controlThread](DEBUG): Initializing ControlThread
2015-01-28T18:34:43.320Z,1422470083.320 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-01-28T18:34:43.322Z,1422470083.322 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-01-28T18:34:43.322Z,1422470083.322 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-01-28T18:34:43.323Z,1422470083.323 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-01-28T18:34:43.323Z,1422470083.323 [SBIT](INFO): Initialize SBIT Component.
2015-01-28T18:34:43.324Z,1422470083.324 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11963
2015-01-28T18:34:43.324Z,1422470083.324 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-01-28T18:34:43.324Z,1422470083.324 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-01-28T18:34:43.325Z,1422470083.325 [IBIT](INFO): Initialize IBIT Component.
2015-01-28T18:34:43.326Z,1422470083.326 [CBIT](DEBUG): Initialize CBIT Component.
2015-01-28T18:34:43.326Z,1422470083.326 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-01-28T18:34:43.327Z,1422470083.327 [logger ThreadHandler](INFO): Handler Thread ID is 857
2015-01-28T18:34:43.345Z,1422470083.345 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 858
2015-01-28T18:34:43.349Z,1422470083.349 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-01-28T18:34:43.349Z,1422470083.349 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-01-28T18:34:43.350Z,1422470083.350 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-01-28T18:34:43.350Z,1422470083.350 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-01-28T18:34:43.351Z,1422470083.351 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-01-28T18:34:43.351Z,1422470083.351 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:34:43.352Z,1422470083.352 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-01-28T18:34:43.352Z,1422470083.352 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-01-28T18:34:43.355Z,1422470083.355 [ExternalSim](INFO): ExternalSim initializing...
2015-01-28T18:34:43.369Z,1422470083.369 [Radio_Surface](INFO): Powering up
2015-01-28T18:34:43.390Z,1422470083.390 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859
2015-01-28T18:34:43.401Z,1422470083.401 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-01-28T18:34:43.404Z,1422470083.404 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-01-28T18:34:43.405Z,1422470083.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-01-28T18:34:43.405Z,1422470083.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-01-28T18:34:43.405Z,1422470083.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-01-28T18:34:43.406Z,1422470083.406 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-01-28T18:34:43.406Z,1422470083.406 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-01-28T18:34:43.406Z,1422470083.406 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-01-28T18:34:43.407Z,1422470083.407 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-01-28T18:34:43.532Z,1422470083.532 [ExternalSim](DEBUG): beaconLat = 36.80340
2015-01-28T18:34:43.533Z,1422470083.533 [ExternalSim](DEBUG): beaconLon = -121.82230
2015-01-28T18:34:43.549Z,1422470083.549 [ExternalSim](DEBUG): beaconDepth = 25.00
2015-01-28T18:34:43.719Z,1422470083.719 [ExternalSim](DEBUG): Simulator initialized
2015-01-28T18:34:43.836Z,1422470083.836 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-01-28T18:34:43.880Z,1422470083.880 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-01-28T18:34:43.905Z,1422470083.905 [MissionManager](DEBUG):
2015-01-28T18:34:43.906Z,1422470083.906 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-01-28T18:34:43.989Z,1422470083.989 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-01-28T18:34:43.993Z,1422470083.993 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-01-28T18:34:44.013Z,1422470084.013 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-01-28T18:34:44.030Z,1422470084.030 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-01-28T18:34:44.060Z,1422470084.060 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-01-28T18:34:44.084Z,1422470084.084 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-01-28T18:34:44.106Z,1422470084.106 [MissionManager](DEBUG):
0
0
5.0
1.0
400
Burn 300
Dropped drop weight due to communications timeout
5.0
Default mission has been running for
5
2015-01-28T18:34:44.111Z,1422470084.111 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-01-28T18:34:44.299Z,1422470084.299 [NAL9602](INFO): Start
2015-01-28T18:34:44.300Z,1422470084.300 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:34:44.672Z,1422470084.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:34:44.767Z,1422470084.767 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:34:44.775Z,1422470084.775 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-01-28T18:34:44.814Z,1422470084.814 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:34:44.823Z,1422470084.823 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-01-28T18:34:44.833Z,1422470084.833 [RudderServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:34:44.839Z,1422470084.839 [RudderServo](DEBUG): Initializing RudderServo.
2015-01-28T18:34:44.861Z,1422470084.861 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:34:44.867Z,1422470084.867 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-01-28T18:34:45.102Z,1422470085.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:34:53.120Z,1422470093.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:34:53.543Z,1422470093.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:34:53.977Z,1422470093.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:34:54.285Z,1422470094.285 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004706
2015-01-28T18:34:54.371Z,1422470094.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:34:55.521Z,1422470095.521 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:34:55.521Z,1422470095.521 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:34:56.327Z,1422470096.327 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:34:56.808Z,1422470096.808 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:34:58.889Z,1422470098.889 [SBIT](IMPORTANT): Beginning Startup BIT
2015-01-28T18:34:58.892Z,1422470098.892 [CBIT](IMPORTANT): Beginning GF scan
2015-01-28T18:35:12.148Z,1422470112.148 [SBIT](ERROR): Could not read massPosReader_.
2015-01-28T18:35:25.335Z,1422470125.335 [CBIT](IMPORTANT): No ground fault detected
2015-01-28T18:35:27.604Z,1422470127.604 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:35:28.004Z,1422470128.004 [NAL9602](INFO): MT Sig Qty requested
2015-01-28T18:35:39.045Z,1422470139.045 [SBIT](ERROR): Could not read massPosReader_.
2015-01-28T18:35:39.045Z,1422470139.045 [SBIT](FAULT): Control surface position failure.
2015-01-28T18:35:52.558Z,1422470152.558 [SBIT](ERROR): Could not read massPosReader_.
2015-01-28T18:35:52.558Z,1422470152.558 [SBIT](FAULT): Control surface position failure.
2015-01-28T18:35:52.920Z,1422470152.920 [SBIT](CRITICAL): SBIT FAILED
2015-01-28T18:35:52.921Z,1422470152.921 [CommandLine](FAULT): Scheduling is paused
2015-01-28T18:35:53.313Z,1422470153.313 [MissionManager](IMPORTANT): Started mission Startup
2015-01-28T18:35:53.313Z,1422470153.313 [Startup] Running Loop=1
2015-01-28T18:35:53.314Z,1422470153.314 [Startup](INFO): Aggregate::initialize Startup
2015-01-28T18:35:53.314Z,1422470153.314 [Startup:A.GoToSurface] Running Loop=1
2015-01-28T18:35:53.314Z,1422470153.314 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-01-28T18:35:53.314Z,1422470153.314 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-01-28T18:35:53.315Z,1422470153.315 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-01-28T18:35:53.315Z,1422470153.315 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-01-28T18:35:53.321Z,1422470153.321 [Startup:StartupSatComms] Running Loop=1
2015-01-28T18:35:53.321Z,1422470153.321 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-01-28T18:35:53.321Z,1422470153.321 [Startup:StartupSatComms:A] Running Loop=1
2015-01-28T18:35:53.714Z,1422470153.714 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-01-28T18:35:58.087Z,1422470158.087 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-01-28T18:35:58.602Z,1422470158.602 [NAL9602](FAULT): received:
2015-01-28T18:35:58.602Z,1422470158.602 [NAL9602] Data Fault, FailCount= 1
2015-01-28T18:35:58.602Z,1422470158.602 [NAL9602](ERROR): Data Fault
2015-01-28T18:35:58.635Z,1422470158.635 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:35:58.704Z,1422470158.704 [NAL9602](INFO): Stop
2015-01-28T18:35:58.704Z,1422470158.704 [NAL9602](INFO): Uninitialize
2015-01-28T18:35:58.704Z,1422470158.704 [NAL9602](INFO): Powering down
2015-01-28T18:35:59.128Z,1422470159.128 [NAL9602](INFO): Stopping
2015-01-28T18:35:59.545Z,1422470159.545 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:35:59.545Z,1422470159.545 [NAL9602] No Fault, FailCount= 1
2015-01-28T18:35:59.909Z,1422470159.909 [NAL9602](INFO): Start
2015-01-28T18:36:00.334Z,1422470160.334 [NAL9602](INFO): Start
2015-01-28T18:36:00.726Z,1422470160.726 [NAL9602](INFO): Start
2015-01-28T18:36:01.111Z,1422470161.111 [NAL9602](INFO): Start
2015-01-28T18:36:01.512Z,1422470161.512 [NAL9602](INFO): Start
2015-01-28T18:36:01.924Z,1422470161.924 [NAL9602](INFO): Start
2015-01-28T18:36:02.313Z,1422470162.313 [NAL9602](INFO): Start
2015-01-28T18:36:02.709Z,1422470162.709 [NAL9602](INFO): Start
2015-01-28T18:36:03.118Z,1422470163.118 [NAL9602](INFO): Start
2015-01-28T18:36:03.515Z,1422470163.515 [NAL9602](INFO): Start
2015-01-28T18:36:03.911Z,1422470163.911 [NAL9602](INFO): Start
2015-01-28T18:36:03.912Z,1422470163.912 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:36:14.716Z,1422470174.716 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:36:14.717Z,1422470174.717 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:36:15.514Z,1422470175.514 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:36:15.919Z,1422470175.919 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:36:48.332Z,1422470208.332 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:36:48.464Z,1422470208.464 [NAL9602](INFO): MT Sig Qty requested
2015-01-28T18:36:53.686Z,1422470213.686 [Startup:StartupSatComms:A](INFO): Timed out from 2015-01-28T18:35:53.3Z
2015-01-28T18:36:53.686Z,1422470213.686 [Startup:StartupSatComms:A] Stopped
2015-01-28T18:36:53.686Z,1422470213.686 [Startup:StartupSatComms:B] Running Loop=1
2015-01-28T18:36:54.087Z,1422470214.087 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-01-28T18:36:58.445Z,1422470218.445 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20150128T181158/Courier0008.lzma
2015-01-28T18:37:02.468Z,1422470222.468 [DataOverHttps](INFO): Moved sent file to Logs/20150128T181158/Courier0008.lzma.bak
2015-01-28T18:37:02.468Z,1422470222.468 [DataOverHttps](INFO): SBD MOMSN=1510432
2015-01-28T18:37:11.512Z,1422470231.512 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20150128T183411/Courier0000.lzma
2015-01-28T18:37:12.326Z,1422470232.326 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Courier0000.lzma.bak
2015-01-28T18:37:12.327Z,1422470232.327 [DataOverHttps](INFO): SBD MOMSN=1510434
2015-01-28T18:37:21.409Z,1422470241.409 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-01-28T18:37:21.931Z,1422470241.931 [NAL9602](FAULT): received:
2015-01-28T18:37:21.932Z,1422470241.932 [NAL9602] Data Fault, FailCount= 2
2015-01-28T18:37:21.932Z,1422470241.932 [NAL9602](ERROR): Data Fault
2015-01-28T18:37:21.965Z,1422470241.965 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:37:22.063Z,1422470242.063 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20150128T181158/Express0009.lzma
2015-01-28T18:37:22.085Z,1422470242.085 [NAL9602](INFO): Stop
2015-01-28T18:37:22.085Z,1422470242.085 [NAL9602](INFO): Uninitialize
2015-01-28T18:37:22.085Z,1422470242.085 [NAL9602](INFO): Powering down
2015-01-28T18:37:22.426Z,1422470242.426 [NAL9602](INFO): Stopping
2015-01-28T18:37:22.835Z,1422470242.835 [DataOverHttps](INFO): Moved sent file to Logs/20150128T181158/Express0009.lzma.bak
2015-01-28T18:37:22.835Z,1422470242.835 [DataOverHttps](INFO): SBD MOMSN=1510438
2015-01-28T18:37:22.907Z,1422470242.907 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:37:22.907Z,1422470242.907 [NAL9602] No Fault, FailCount= 2
2015-01-28T18:37:23.640Z,1422470243.640 [NAL9602](INFO): Start
2015-01-28T18:37:23.824Z,1422470243.824 [NAL9602](INFO): Start
2015-01-28T18:37:27.026Z,1422470247.026 [NAL9602](INFO): Start
2015-01-28T18:37:27.160Z,1422470247.160 [NAL9602](INFO): Start
2015-01-28T18:37:27.161Z,1422470247.161 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:37:32.117Z,1422470252.117 [DataOverHttps](INFO): Sending 533 bytes from file Logs/20150128T183411/Express0001.lzma
2015-01-28T18:37:32.913Z,1422470252.913 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Express0001.lzma.bak
2015-01-28T18:37:32.913Z,1422470252.913 [DataOverHttps](INFO): SBD MOMSN=1510450
2015-01-28T18:37:33.750Z,1422470253.750 [Startup:StartupSatComms:B] Stopped
2015-01-28T18:37:33.750Z,1422470253.750 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-01-28T18:37:33.750Z,1422470253.750 [Startup:StartupSatComms] Stopped
2015-01-28T18:37:33.750Z,1422470253.750 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-01-28T18:37:33.751Z,1422470253.751 [Startup](INFO): Completed Startup
2015-01-28T18:37:33.751Z,1422470253.751 [Startup] Stopped
2015-01-28T18:37:33.751Z,1422470253.751 [Startup](INFO): Aggregate::uninitialize Startup
2015-01-28T18:37:33.751Z,1422470253.751 [Startup:A.GoToSurface] Stopped
2015-01-28T18:37:33.751Z,1422470253.751 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-01-28T18:37:33.865Z,1422470253.865 [MissionManager](IMPORTANT): Started mission Default
2015-01-28T18:37:33.865Z,1422470253.865 [Default] Running Loop=1
2015-01-28T18:37:33.865Z,1422470253.865 [Default](INFO): Aggregate::initialize Default
2015-01-28T18:37:33.865Z,1422470253.865 [Default:B.GoToSurface] Running Loop=1
2015-01-28T18:37:33.865Z,1422470253.865 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-01-28T18:37:33.866Z,1422470253.866 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-01-28T18:37:33.866Z,1422470253.866 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-01-28T18:37:33.866Z,1422470253.866 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-01-28T18:37:33.867Z,1422470253.867 [Default:StartClock] Running Loop=1
2015-01-28T18:37:33.867Z,1422470253.867 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2015-01-28T18:37:33.867Z,1422470253.867 [Default:StartClock:A] Running Loop=1
2015-01-28T18:37:33.875Z,1422470253.875 [Default:StartClock:A] Stopped
2015-01-28T18:37:33.875Z,1422470253.875 [Default:StartClock](INFO): Completed Default:StartClock
2015-01-28T18:37:33.875Z,1422470253.875 [Default:StartClock] Stopped
2015-01-28T18:37:33.875Z,1422470253.875 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2015-01-28T18:37:37.153Z,1422470257.153 [Default:WaitAtTheSurface] Running Loop=1
2015-01-28T18:37:37.153Z,1422470257.153 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-01-28T18:37:37.153Z,1422470257.153 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-01-28T18:37:37.153Z,1422470257.153 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-01-28T18:37:37.263Z,1422470257.263 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-01-28T18:37:47.249Z,1422470267.249 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:37:47.249Z,1422470267.249 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:37:47.268Z,1422470267.268 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:37:47.269Z,1422470267.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-01-28T18:37:47.269Z,1422470267.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:37:47.279Z,1422470267.279 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:37:52.313Z,1422470272.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:37:52.313Z,1422470272.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-01-28T18:37:57.250Z,1422470277.250 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:37:57.280Z,1422470277.280 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:38:02.250Z,1422470282.250 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:38:02.326Z,1422470282.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:38:07.314Z,1422470287.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:38:12.268Z,1422470292.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:38:17.272Z,1422470297.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:38:22.334Z,1422470302.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:38:27.272Z,1422470307.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:38:42.242Z,1422470322.242 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:38:50.059Z,1422470330.059 [NAL9602](INFO): MT Sig Qty requested
2015-01-28T18:39:22.240Z,1422470362.240 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-01-28T18:39:22.759Z,1422470362.759 [NAL9602](FAULT): received:
2015-01-28T18:39:22.760Z,1422470362.760 [NAL9602] Data Fault, FailCount= 3
2015-01-28T18:39:22.760Z,1422470362.760 [NAL9602](ERROR): Data Fault
2015-01-28T18:39:22.841Z,1422470362.841 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:39:27.240Z,1422470367.240 [NAL9602](INFO): Stop
2015-01-28T18:39:27.240Z,1422470367.240 [NAL9602](INFO): Uninitialize
2015-01-28T18:39:27.240Z,1422470367.240 [NAL9602](INFO): Powering down
2015-01-28T18:39:32.239Z,1422470372.239 [NAL9602](INFO): Stopping
2015-01-28T18:39:37.287Z,1422470377.287 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:39:37.287Z,1422470377.287 [NAL9602] No Fault, FailCount= 3
2015-01-28T18:39:42.247Z,1422470382.247 [NAL9602](INFO): Start
2015-01-28T18:39:42.247Z,1422470382.247 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:39:47.278Z,1422470387.278 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:39:47.278Z,1422470387.278 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:39:47.278Z,1422470387.278 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:39:47.280Z,1422470387.280 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:39:47.280Z,1422470387.280 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:39:55.118Z,1422470395.118 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.223369 min
2015-01-28T18:39:55.120Z,1422470395.120 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:39:55.120Z,1422470395.120 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:39:55.120Z,1422470395.120 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:39:55.120Z,1422470395.120 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:40:02.246Z,1422470402.246 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:40:02.246Z,1422470402.246 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:40:12.250Z,1422470412.250 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:40:17.242Z,1422470417.242 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:40:22.300Z,1422470422.300 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:40:22.300Z,1422470422.300 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:40:22.300Z,1422470422.300 [BPC1](ERROR): Communications Fault
2015-01-28T18:40:22.339Z,1422470422.339 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:40:37.299Z,1422470437.299 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:40:37.299Z,1422470437.299 [BPC1] No Fault, FailCount= 1
2015-01-28T18:40:55.063Z,1422470455.063 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:40:57.244Z,1422470457.244 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-01-28T18:40:57.287Z,1422470457.287 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:40:57.287Z,1422470457.287 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2015-01-28T18:40:57.287Z,1422470457.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:40:57.299Z,1422470457.299 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:41:02.327Z,1422470462.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:41:02.327Z,1422470462.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2015-01-28T18:41:07.244Z,1422470467.244 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:41:07.306Z,1422470467.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:41:12.302Z,1422470472.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:41:17.257Z,1422470477.257 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:41:22.302Z,1422470482.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:41:27.299Z,1422470487.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:41:32.289Z,1422470492.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:41:37.261Z,1422470497.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:41:42.749Z,1422470502.749 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:41:42.805Z,1422470502.805 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:41:42.805Z,1422470502.805 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:41:42.806Z,1422470502.806 [BPC1](ERROR): Communications Fault
2015-01-28T18:41:42.870Z,1422470502.870 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:42:00.101Z,1422470520.101 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:42:00.101Z,1422470520.101 [BPC1] No Fault, FailCount= 1
2015-01-28T18:42:22.252Z,1422470542.252 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:42:32.240Z,1422470552.240 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:42:37.264Z,1422470557.264 [Default:CheckIn] Running Loop=1
2015-01-28T18:42:37.264Z,1422470557.264 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T18:42:37.264Z,1422470557.264 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T18:42:37.264Z,1422470557.264 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T18:42:37.264Z,1422470557.264 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T18:42:37.265Z,1422470557.265 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-01-28T18:42:37.267Z,1422470557.267 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T18:42:38.460Z,1422470558.460 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T18:42:53.420Z,1422470573.420 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:42:53.420Z,1422470573.420 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:42:53.420Z,1422470573.420 [BPC1](ERROR): Communications Fault
2015-01-28T18:42:53.438Z,1422470573.438 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:42:54.653Z,1422470574.653 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:42:54.653Z,1422470574.653 [BPC1] No Fault, FailCount= 1
2015-01-28T18:43:03.110Z,1422470583.110 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:43:07.287Z,1422470587.287 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5D%56
$C1,01,7F,02,00,03,7F,04,00,05,00,06,00,07,00%72
$B11,0A,FFFC%36
$B12,0A,FFFC%35
$B13,0A,FFFC%34
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$S,01,270F,02,00,04,5D%56
$C1,01,7F,02,00,03,7F,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B9D,09,3C2C,0A,FFFC,0B,FFFC%49
$B11,0C,0001,0D,0056,0E,0051,0F,139A,10,173A,11,FFFF,12,FFFF%4C
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%41
$B11,1A,0031,1B,4357,1C,00AD%31
$B12,02,000A,01,026C,03,0001,08,0B95,09,3C2B,0A,FFFC,0B,FFFC%3A
$B12,0C,0001,0D,0058,0E,0052,0F,13C1,10,16AC,11,FFFF,12,FFFF%39
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42
$B12,1A,0031,1B,4357,1C,00AB%34
$B13,02,000A,01,026C,03,0001,08,0B90,09,3C28,0A,FFFD,0B,FFFC%43
$B13,0C,0001,0D,0061,0E,0051,0F,1394,10,16AE,11,FFFF,12,FFFF%48
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4357,1C,00B1%45
$B14,02,000A,01,026C,03,0001,08,0B96,09,3C26,0A,FFFD,0B,FFFD%4B
$B14,0C,0001,0D,0054,0E,004E,0F,12D5,10,1688,11,FFFF,12,FFFF%45
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,00A0%40
$B15,02,000A,01,026C,03,0001,08,0B99,09,3C08,0A,0000,0B,0000%49
$B15,0C,0001,0D,0064,0E,005C,0F,1615,10,1736,11,FFFF,12,FFFF%35
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,008B%4A
$B16,02,000A,01,026C,03,0001,08,0B8C,09,3C23,0A,0000,0B,0000%38
$B16,0C,0001,0D,0060,0E,0059,0F,157C,10,178C,11,FFFF,12,FFFF%45
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%45
$B16,1A,0031,1B,4339,1C,007F%4A
$B17,02,000A,01,026C,03,0001,08,0B89,09,3C00,0A,0000,0B,0000%42
$B17,0C,0001,0D,0064,0E,005D,0F,1654,10,16E1,11,FFFF,12,FFFF%45
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,00B4%44
4357,1C,0098%33
$B18,02,000A,01,026C,03,0001,08,0B92,09,3C04,0A,0000,0B,0000%43
$B18,0C,0001,0D,004E,0E,0046,0F,10F0,10,168E,11,FFFF,12,FFFF%32
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%48
$B18,1A,0031,1B,4357,1C,00B3%4C
2015-01-28T18:43:07.287Z,1422470587.287 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T18:43:07.287Z,1422470587.287 [BPC1] Data Fault, FailCount= 2
2015-01-28T18:43:07.287Z,1422470587.287 [BPC1](ERROR): Data Fault
2015-01-28T18:43:07.304Z,1422470587.304 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:43:08.511Z,1422470588.511 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:43:08.511Z,1422470588.511 [BPC1] No Fault, FailCount= 2
2015-01-28T18:43:33.659Z,1422470613.659 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:43:34.461Z,1422470614.461 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:44:05.175Z,1422470645.175 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:44:07.388Z,1422470647.388 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:44:07.389Z,1422470647.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2015-01-28T18:44:07.389Z,1422470647.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:44:07.400Z,1422470647.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:44:07.790Z,1422470647.790 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:44:07.791Z,1422470647.791 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2015-01-28T18:44:08.148Z,1422470648.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:44:08.549Z,1422470648.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:44:08.957Z,1422470648.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:44:09.355Z,1422470649.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:44:09.751Z,1422470649.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:44:10.147Z,1422470650.147 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:44:10.545Z,1422470650.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:44:35.741Z,1422470675.741 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:44:36.541Z,1422470676.541 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:44:47.360Z,1422470687.360 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:44:47.360Z,1422470687.360 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:44:47.360Z,1422470687.360 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:44:47.361Z,1422470687.361 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:44:47.361Z,1422470687.361 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:44:47.765Z,1422470687.765 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 7.224882 min
2015-01-28T18:44:47.765Z,1422470687.765 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:44:47.765Z,1422470687.765 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:44:47.765Z,1422470687.765 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:44:47.765Z,1422470687.765 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:44:56.328Z,1422470696.328 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-01-28T18:44:56.328Z,1422470696.328 [NAL9602] Data Fault, FailCount= 4
2015-01-28T18:44:56.328Z,1422470696.328 [NAL9602](ERROR): Data Fault
2015-01-28T18:44:56.392Z,1422470696.392 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:44:56.741Z,1422470696.741 [NAL9602](INFO): Stop
2015-01-28T18:44:56.741Z,1422470696.741 [NAL9602](INFO): Uninitialize
2015-01-28T18:44:56.741Z,1422470696.741 [NAL9602](INFO): Powering down
2015-01-28T18:44:57.141Z,1422470697.141 [NAL9602](INFO): Stopping
2015-01-28T18:44:57.565Z,1422470697.565 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:44:57.565Z,1422470697.565 [NAL9602] No Fault, FailCount= 4
2015-01-28T18:44:57.942Z,1422470697.942 [NAL9602](INFO): Start
2015-01-28T18:44:58.327Z,1422470698.327 [NAL9602](INFO): Start
2015-01-28T18:44:58.724Z,1422470698.724 [NAL9602](INFO): Start
2015-01-28T18:44:59.144Z,1422470699.144 [NAL9602](INFO): Start
2015-01-28T18:44:59.537Z,1422470699.537 [NAL9602](INFO): Start
2015-01-28T18:44:59.937Z,1422470699.937 [NAL9602](INFO): Start
2015-01-28T18:45:00.338Z,1422470700.338 [NAL9602](INFO): Start
2015-01-28T18:45:00.742Z,1422470700.742 [NAL9602](INFO): Start
2015-01-28T18:45:01.139Z,1422470701.139 [NAL9602](INFO): Start
2015-01-28T18:45:01.527Z,1422470701.527 [NAL9602](INFO): Start
2015-01-28T18:45:01.937Z,1422470701.937 [NAL9602](INFO): Start
2015-01-28T18:45:01.938Z,1422470701.938 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:45:04.755Z,1422470704.755 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,0000%33
$B12,0A,FFFC%35
$B13,0A,FFFD%33
$B14,0A,FFFD%34
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B8B,09,3C0F,0A,0000,0B,0000%49
$B11,0C,0001,0D,0064,0E,005B,0F,15DD,10,1641,11,FFFF,12,FFFF%36
$B11,13,FFFF,14,0000,15,41A0,16,00E0,17,0002,18,1838,19,3840%4F
$B11,1A,0031,1B,4357,1C,009D%49
$B12,02,000A,01,026C,03,0001,08,0B93,09,3C22,0A,FFFC,0B,FFFC%4C
$B12,0C,0001,0D,0052,0E,0051,0F,1362,10,17B2,11,FFFF,12,FFFF%35
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42
$B12,1A,0031,1B,4357,1C,0093%3D
$B13,02,000A,01,026C,03,0001,08,0B9A,09,3C21,0A,FFFC,0B,FFFD%3B
$B13,0C,0001,0D,005B,0E,004E,0F,12B8,10,16D7,11,FFFF,12,FFFF%4C
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4333,1C,0068%3A
$B14,02,000A,01,026C,03,0001,08,0B95,09,3C22,0A,FFFD,0B,FFFD%4C
$B14,0C,0001,0D,0052,0E,004D,0F,126A,10,168C,11,FFFF,12,FFFF%3F
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,008D%4D
$B15,02,000A,01,026C,03,0001,08,0B97,09,3C07,0A,0000,0B,0000%48
$B15,0C,0001,0D,0064,0E,005B,0F,1602,10,16B6,11,FFFF,12,FFFF%42
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,008A%49
$B16,02,000A,01,026C,03,0001,08,0B8F,09,3C10,0A,0000,0B,0000%3D
$B16,0C,0001,0D,0064,0E,005E,0F,16AB,10,16AB,11,FFFF,12,FFFF%30
$B16,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B
$B16,1A,0031,1B,4357,1C,00B9%48
$B17,02,000A,01,026C,03,0001,08,0B96,09,3C0A,0A,0000,0B,0000%3D
$B17,0C,0001,0D,0064,0E,005F,0F,16DB,10,1701,11,FFFF,12,FFFF%34
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,00A5%46
$B18,02,000A,01,026C,03,0001,08,0B9A,09,3BB3,0A,0000,0B,0000%44
$B18,0C,0001,0D,0056,0E,004F,0F,12E5,10,16A5,11,FFFF,12,FFFF%3D
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%48
$B18,1A,0031,1B,4357,1C,00AE%39
2015-01-28T18:45:04.755Z,1422470704.755 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T18:45:04.755Z,1422470704.755 [BPC1] Data Fault, FailCount= 1
2015-01-28T18:45:04.756Z,1422470704.756 [BPC1](ERROR): Data Fault
2015-01-28T18:45:04.772Z,1422470704.772 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:45:05.971Z,1422470705.971 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:45:05.971Z,1422470705.971 [BPC1] No Fault, FailCount= 1
2015-01-28T18:45:13.130Z,1422470713.130 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:45:13.130Z,1422470713.130 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:45:13.944Z,1422470713.944 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:45:44.731Z,1422470744.731 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:45:45.528Z,1422470745.528 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:46:09.498Z,1422470769.498 [CommandLine](IMPORTANT): got command report mod NAL9602.sigQuality
2015-01-28T18:46:09.756Z,1422470769.756 [Reporter](INFO): NAL9602.sigQuality no_value
2015-01-28T18:46:16.227Z,1422470776.227 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:46:18.184Z,1422470778.184 [CommandLine](IMPORTANT): got command get latitude
2015-01-28T18:46:18.184Z,1422470778.184 [CommandLine](FAULT): Element has no value
2015-01-28T18:46:46.785Z,1422470806.785 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:46:47.581Z,1422470807.581 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:47:08.309Z,1422470828.309 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:47:08.309Z,1422470828.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2015-01-28T18:47:08.309Z,1422470828.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:47:08.320Z,1422470828.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:47:08.720Z,1422470828.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:47:08.720Z,1422470828.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2015-01-28T18:47:09.102Z,1422470829.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:47:09.504Z,1422470829.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:47:09.904Z,1422470829.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:47:10.304Z,1422470830.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:47:10.683Z,1422470830.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:47:11.083Z,1422470831.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:47:11.510Z,1422470831.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:47:18.375Z,1422470838.375 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:47:37.340Z,1422470857.340 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-01-28T18:42:37.3Z
2015-01-28T18:47:37.340Z,1422470857.340 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T18:47:37.340Z,1422470857.340 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-01-28T18:47:37.751Z,1422470857.751 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-01-28T18:47:41.863Z,1422470861.863 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20150128T183411/Courier0004.lzma
2015-01-28T18:47:42.667Z,1422470862.667 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Courier0004.lzma.bak
2015-01-28T18:47:42.667Z,1422470862.667 [DataOverHttps](INFO): SBD MOMSN=1510464
2015-01-28T18:47:48.536Z,1422470868.536 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:47:51.864Z,1422470871.864 [DataOverHttps](INFO): Sending 663 bytes from file Logs/20150128T183411/Express0005.lzma
2015-01-28T18:47:51.890Z,1422470871.890 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:47:52.675Z,1422470872.675 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Express0005.lzma.bak
2015-01-28T18:47:52.675Z,1422470872.675 [DataOverHttps](INFO): SBD MOMSN=1510466
2015-01-28T18:47:53.534Z,1422470873.534 [Default:CheckIn:Read_Iridium] Stopped
2015-01-28T18:47:53.535Z,1422470873.535 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-01-28T18:47:53.535Z,1422470873.535 [Default:CheckIn] Stopped
2015-01-28T18:47:53.535Z,1422470873.535 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T18:47:53.535Z,1422470873.535 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T18:47:53.535Z,1422470873.535 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T18:48:24.122Z,1422470904.122 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:49:06.429Z,1422470946.429 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:49:13.620Z,1422470953.620 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:49:49.130Z,1422470989.130 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:49:49.179Z,1422470989.179 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:49:49.179Z,1422470989.179 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:49:49.179Z,1422470989.179 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:49:49.180Z,1422470989.180 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:49:49.180Z,1422470989.180 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:49:53.716Z,1422470993.716 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 12.246429 min
2015-01-28T18:49:53.716Z,1422470993.716 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:49:53.716Z,1422470993.716 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:49:53.716Z,1422470993.716 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:49:53.716Z,1422470993.716 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:50:13.677Z,1422471013.677 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:50:13.677Z,1422471013.677 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2015-01-28T18:50:13.677Z,1422471013.677 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:50:13.692Z,1422471013.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:50:18.636Z,1422471018.636 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:50:18.636Z,1422471018.636 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:50:18.636Z,1422471018.636 [BPC1](ERROR): Communications Fault
2015-01-28T18:50:18.668Z,1422471018.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:50:18.668Z,1422471018.668 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2015-01-28T18:50:18.669Z,1422471018.669 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:50:23.636Z,1422471023.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:50:28.613Z,1422471028.613 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:50:28.642Z,1422471028.642 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:50:33.636Z,1422471033.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:50:33.647Z,1422471033.647 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:50:33.647Z,1422471033.647 [BPC1] No Fault, FailCount= 1
2015-01-28T18:50:38.617Z,1422471038.617 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:50:38.732Z,1422471038.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:50:43.695Z,1422471043.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:50:48.643Z,1422471048.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:50:53.640Z,1422471053.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:51:03.642Z,1422471063.642 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:51:03.642Z,1422471063.642 [BPC1] Communications Fault, FailCount= 2
2015-01-28T18:51:03.642Z,1422471063.642 [BPC1](ERROR): Communications Fault
2015-01-28T18:51:03.676Z,1422471063.676 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:51:11.959Z,1422471071.959 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:51:18.652Z,1422471078.652 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:51:18.652Z,1422471078.652 [BPC1] No Fault, FailCount= 2
2015-01-28T18:51:48.627Z,1422471108.627 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:51:58.630Z,1422471118.630 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:52:16.445Z,1422471136.445 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T18:52:34.138Z,1422471154.138 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:52:53.695Z,1422471173.695 [Default:CheckIn] Running Loop=1
2015-01-28T18:52:53.695Z,1422471173.695 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T18:52:53.695Z,1422471173.695 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T18:52:53.695Z,1422471173.695 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T18:52:53.695Z,1422471173.695 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T18:53:08.931Z,1422471188.931 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:53:09.735Z,1422471189.735 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:53:23.760Z,1422471203.760 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:53:23.761Z,1422471203.761 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2015-01-28T18:53:23.761Z,1422471203.761 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:53:23.772Z,1422471203.772 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:53:24.174Z,1422471204.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:53:24.174Z,1422471204.174 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2015-01-28T18:53:24.561Z,1422471204.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:53:24.962Z,1422471204.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:53:25.362Z,1422471205.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:53:25.759Z,1422471205.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:53:26.154Z,1422471206.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:53:26.564Z,1422471206.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:53:26.962Z,1422471206.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:53:40.650Z,1422471220.650 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:54:10.561Z,1422471250.561 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B8A,09,3C0E,0A,0000,0B,0004%4D
$B11,0C,0001,0D,0064,0E,005B,0F,15DD,10,1641,11,FFFF,12,FFFF%36
$B11,13,07D0,14,0000,15,41A0,16,00E0,17,0002,18,1838,19,3840%3C
$B11,1A,0031,1B,4357,1C,009D%49
$B12,02,000A,01,026C,03,0001,08,0B92,09,3C21,0A,FFFC,0B,0001%4A
$B12,0C,0001,0D,0052,0E,0051,0F,1362,10,17B2,11,FFFF,12,FFFF%35
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42
$B12,1A,0031,1B,4357,1C,0093%3D
$B13,02,000A,01,026C,03,0001,08,0B99,09,3C21,0A,FFFC,0B,0000%41
$B13,0C,0001,0D,005B,0E,004E,0F,12B8,10,16D7,11,FFFF,12,FFFF%4C
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4333,1C,0068%3A
$B14,02,000A,01,026C,03,0001,08,0B95,09,3C21,0A,FFFC,0B,0001%4B
$B14,0C,0001,0D,0052,0E,004D,0F,126A,10,168C,11,FFFF,12,FFFF%3F
$B14,13,F7F8,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%4B
$B14,1A,0031,1B,4357,1C,008D%4D
$B15,02,000A,01,026C,03,0001,08,0B97,09,3C06,0A,0000,0B,0003%4A
$B15,0C,0001,0D,0064,0E,005B,0F,1602,10,16B6,11,FFFF,12,FFFF%42
$B15,13,0E10,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%3C
$B15,1A,0031,1B,4357,1C,008A%49
$B16,02,000A,01,026C,03,0001,08,0B8F,09,3C0E,0A,0000,0B,0003%4A
$B16,0C,0001,0D,0064,0E,005E,0F,16AB,10,16AB,11,FFFF,12,FFFF%30
$B16,13,0000,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B
$B16,1A,0031,1B,4357,1C,00B9%48
$B17,02,000A,01,026C,03,0001,08,0B95,09,3C0A,0A,0000,0B,0003%3D
$B17,0C,0001,0D,0064,0E,005F,0F,16DB,10,1701,11,FFFF,12,FFFF%34
$B17,13,02F8,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%36
$B17,1A,0031,1B,4357,1C,00A5%46
$B18,02,000A,01,026C,03,0001,08,0B9A,09,3BB3,0A,0000,0B,0002%46
$B18,0C,0001,0D,0056,0E,004F,0F,12E5,10,16A5,11,FFFF,12,FFFF%3D
$B18,13,7080,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%47
$B18,1A,0031,1B,4357,1C,00AE%39
,026C,03,0001,08,0B93,09,3C05,0A,0000,0B,0000%43
$B18,0C,0001,0D,004E,0E,0046,0F,10F0,10,168E,11,FFFF,12,FFFF%32
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%48
$B18,1A,0031,1B,4357,1C,00B3%4C
2015-01-28T18:54:10.561Z,1422471250.561 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T18:54:10.561Z,1422471250.561 [BPC1] Data Fault, FailCount= 1
2015-01-28T18:54:10.561Z,1422471250.561 [BPC1](ERROR): Data Fault
2015-01-28T18:54:10.578Z,1422471250.578 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:54:10.943Z,1422471250.943 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:54:11.740Z,1422471251.740 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:54:11.827Z,1422471251.827 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:54:11.827Z,1422471251.827 [BPC1] No Fault, FailCount= 1
2015-01-28T18:54:42.650Z,1422471282.650 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:54:49.206Z,1422471289.206 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:54:49.206Z,1422471289.206 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:54:49.206Z,1422471289.206 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:54:49.207Z,1422471289.207 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:54:49.207Z,1422471289.207 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:54:49.635Z,1422471289.635 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 17.255821 min
2015-01-28T18:54:49.635Z,1422471289.635 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:54:49.635Z,1422471289.635 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:54:49.635Z,1422471289.635 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:54:49.635Z,1422471289.635 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:55:13.003Z,1422471313.003 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:55:13.797Z,1422471313.797 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:55:14.205Z,1422471314.205 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-01-28T18:55:14.205Z,1422471314.205 [NAL9602] Data Fault, FailCount= 5
2015-01-28T18:55:14.205Z,1422471314.205 [NAL9602](ERROR): Data Fault
2015-01-28T18:55:14.276Z,1422471314.276 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:55:14.277Z,1422471314.277 [CBIT](CRITICAL): Data Fault in component: NAL9602
2015-01-28T18:55:14.593Z,1422471314.593 [NAL9602](INFO): Stop
2015-01-28T18:55:14.593Z,1422471314.593 [NAL9602](INFO): Uninitialize
2015-01-28T18:55:14.594Z,1422471314.594 [NAL9602](INFO): Powering down
2015-01-28T18:55:14.998Z,1422471314.998 [NAL9602](INFO): Stopping
2015-01-28T18:56:11.727Z,1422471371.727 [CommandLine](IMPORTANT): got command get latitude
2015-01-28T18:56:11.727Z,1422471371.727 [CommandLine](FAULT): Element has no value
2015-01-28T18:56:24.835Z,1422471384.835 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:56:24.836Z,1422471384.836 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2015-01-28T18:56:24.836Z,1422471384.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:56:24.885Z,1422471384.885 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:56:25.228Z,1422471385.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:56:25.228Z,1422471385.228 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2015-01-28T18:56:25.593Z,1422471385.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:56:26.025Z,1422471386.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:56:26.392Z,1422471386.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:56:26.804Z,1422471386.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:56:27.196Z,1422471387.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:56:27.593Z,1422471387.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:56:27.993Z,1422471387.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:56:35.597Z,1422471395.597 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5E%57
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFC%36
$B12,0A,0000%30
$B13,0A,FFFB%35
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5E%57
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B96,09,3C2A,0A,FFFC,0B,0000%3C
$B11,0C,0001,0D,005A,0E,0056,0F,1498,10,1787,11,FFFF,12,FFFF%3F
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%41
$B11,1A,0031,1B,4357,1C,00A7%42
$B12,02,000A,01,026C,03,0001,08,0B96,09,3C11,0A,0000,0B,0002%4B
$B12,0C,0001,0D,0064,0E,0060,0F,170F,10,170F,11,FFFF,12,FFFF%42
$B12,13,0000,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4F
$B12,1A,0031,1B,4357,1C,009E%4B
$B13,02,000A,01,026C,03,0001,08,0B97,09,3C2E,0A,FFFB,0B,FFFF%3A
$B13,0C,0001,0D,0060,0E,0054,0F,1438,10,17AC,11,F2A0,12,FFFF%4F
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4357,1C,0089%37
$B14,02,000A,01,026C,03,0001,08,0B9B,09,3C2F,0A,FFFC,0B,FFFF%4A
$B14,0C,0001,0D,0058,0E,0054,0F,1421,10,16F6,11,FFFF,12,FFFF%3D
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,00A6%46
$B15,02,000A,01,026C,03,0001,08,0B9B,09,3C09,0A,0000,0B,0002%31
$B15,0C,0001,0D,0064,0E,005C,0F,162F,10,171F,11,FFFF,12,FFFF%37
$B15,13,1C20,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%38
$B15,1A,0031,1B,4357,1C,00A9%48
$B16,02,000A,01,026C,03,0001,08,0B91,09,3C2A,0A,0000,0B,0002%3B
$B16,0C,0001,0D,0061,0E,0057,0F,14ED,10,1698,11,FFFF,12,FFFF%45
$B16,13,320A,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%35
$B16,1A,0031,1B,4357,1C,00A8%4A
$B17,02,000A,01,026C,03,0001,08,0B90,09,3C25,0A,0000,0B,0002%4F
$B17,0C,0001,0D,005D,0E,0056,0F,14D3,10,16E3,11,FFFF,12,FFFF%32
$B17,13,3DE0,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%46
$B17,1A,0031,1B,4357,1C,0096%3D
$B18,02,000A,01,026C,03,0001,08,0B94,09,3BB2,0A,0000,0B,0002%32
$B18,0C,0001,0D,0057,0E,0053,0F,13F7,10,17BE,11,FFFF,12,FFFF%3A
$B18,13,7152,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%49
$B18,1A,0031,1B,4357,1C,009B%46
2015-01-28T18:56:35.598Z,1422471395.598 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T18:56:35.598Z,1422471395.598 [BPC1] Data Fault, FailCount= 1
2015-01-28T18:56:35.598Z,1422471395.598 [BPC1](ERROR): Data Fault
2015-01-28T18:56:35.666Z,1422471395.666 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:56:36.817Z,1422471396.817 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:56:36.817Z,1422471396.817 [BPC1] No Fault, FailCount= 1
2015-01-28T18:57:14.536Z,1422471434.536 [CBIT](INFO): Clearing failed count for component NAL9602
2015-01-28T18:57:14.536Z,1422471434.536 [NAL9602] No Fault, FailCount= 5
2015-01-28T18:57:14.904Z,1422471434.904 [NAL9602](INFO): Start
2015-01-28T18:57:14.905Z,1422471434.905 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:57:25.711Z,1422471445.711 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:57:25.712Z,1422471445.712 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:57:26.512Z,1422471446.512 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:57:53.746Z,1422471473.746 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-01-28T18:52:53.7Z
2015-01-28T18:57:53.746Z,1422471473.746 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T18:57:53.746Z,1422471473.746 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-01-28T18:57:58.162Z,1422471478.162 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:57:58.269Z,1422471478.269 [DataOverHttps](INFO): Sending 110 bytes from file Logs/20150128T183411/Courier0008.lzma
2015-01-28T18:57:58.673Z,1422471478.673 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:57:59.057Z,1422471479.057 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Courier0008.lzma.bak
2015-01-28T18:57:59.057Z,1422471479.057 [DataOverHttps](INFO): SBD MOMSN=1510487
2015-01-28T18:58:08.224Z,1422471488.224 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20150128T183411/Express0009.lzma
2015-01-28T18:58:09.022Z,1422471489.022 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Express0009.lzma.bak
2015-01-28T18:58:09.022Z,1422471489.022 [DataOverHttps](INFO): SBD MOMSN=1510490
2015-01-28T18:58:09.887Z,1422471489.887 [Default:CheckIn:Read_Iridium] Stopped
2015-01-28T18:58:09.888Z,1422471489.888 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-01-28T18:58:09.888Z,1422471489.888 [Default:CheckIn] Stopped
2015-01-28T18:58:09.888Z,1422471489.888 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T18:58:09.888Z,1422471489.888 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T18:58:09.888Z,1422471489.888 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T18:58:30.481Z,1422471510.481 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:59:09.999Z,1422471549.999 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:59:22.781Z,1422471562.781 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:59:30.043Z,1422471570.043 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:59:30.043Z,1422471570.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2015-01-28T18:59:30.043Z,1422471570.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:59:30.054Z,1422471570.054 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:59:35.052Z,1422471575.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:59:35.052Z,1422471575.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2015-01-28T18:59:40.003Z,1422471580.003 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T18:59:40.010Z,1422471580.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:59:45.007Z,1422471585.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:59:50.061Z,1422471590.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:59:50.063Z,1422471590.063 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:59:50.063Z,1422471590.063 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:59:50.063Z,1422471590.063 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:59:50.064Z,1422471590.064 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:59:50.064Z,1422471590.064 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:59:55.489Z,1422471595.489 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:59:55.526Z,1422471595.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:59:55.529Z,1422471595.529 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 22.268951 min
2015-01-28T18:59:55.529Z,1422471595.529 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:59:55.529Z,1422471595.529 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:59:55.529Z,1422471595.529 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:59:55.530Z,1422471595.530 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:00:00.065Z,1422471600.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:00:04.988Z,1422471604.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:00:10.028Z,1422471610.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:00:34.980Z,1422471634.980 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:00:44.972Z,1422471644.972 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:01:10.013Z,1422471670.013 [BPC1](FAULT): Failed to receive battery data
2015-01-28T19:01:10.013Z,1422471670.013 [BPC1] Communications Fault, FailCount= 1
2015-01-28T19:01:10.013Z,1422471670.013 [BPC1](ERROR): Communications Fault
2015-01-28T19:01:10.031Z,1422471670.031 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T19:01:20.481Z,1422471680.481 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T19:01:27.833Z,1422471687.833 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:01:27.833Z,1422471687.833 [BPC1] No Fault, FailCount= 1
2015-01-28T19:01:59.972Z,1422471719.972 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:02:09.972Z,1422471729.972 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:02:45.477Z,1422471765.477 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T19:02:45.543Z,1422471765.543 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:02:45.543Z,1422471765.543 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2015-01-28T19:02:45.543Z,1422471765.543 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:02:45.554Z,1422471765.554 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:02:50.054Z,1422471770.054 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:02:50.054Z,1422471770.054 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2015-01-28T19:02:55.041Z,1422471775.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:02:59.998Z,1422471779.998 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:03:05.044Z,1422471785.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:03:10.000Z,1422471790.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:03:10.003Z,1422471790.003 [Default:CheckIn] Running Loop=1
2015-01-28T19:03:10.003Z,1422471790.003 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T19:03:10.003Z,1422471790.003 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T19:03:10.003Z,1422471790.003 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T19:03:10.003Z,1422471790.003 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T19:03:10.398Z,1422471790.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:03:10.845Z,1422471790.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:03:11.210Z,1422471791.210 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:03:20.113Z,1422471800.113 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:03:20.910Z,1422471800.910 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:03:51.825Z,1422471831.825 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T19:04:19.431Z,1422471859.431 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,0000%33
$B12,0A,FFFC%35
$B13,0A,FFFC%34
$B14,0A,FFFD%34
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B8B,09,3C0D,0A,0000,0B,0000%4B
$B11,0C,0001,0D,0064,0E,005B,0F,15DD,10,1641,11,FFFF,12,FFFF%36
$B11,13,FFFF,14,0000,15,41A0,16,00E0,17,0002,18,1838,19,3840%4F
$B11,1A,0031,1B,4357,1C,009D%49
$B12,02,000A,01,026C,03,0001,08,0B93,09,3C23,0A,FFFD,0B,FFFD%4D
$B12,0C,0001,0D,0052,0E,0051,0F,1362,10,17B2,11,FFFF,12,FFFF%35
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42
$B12,1A,0031,1B,4357,1C,0093%3D
$B13,02,000A,01,026C,03,0001,08,0B9A,09,3C21,0A,FFFC,0B,FFFC%3C
$B13,0C,0001,0D,005B,0E,004E,0F,12B7,10,16D7,11,FFFF,12,FFFF%43
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4333,1C,0068%3A
$B14,02,000A,01,026C,03,0001,08,0B95,09,3C22,0A,FFFC,0B,FFFD%4B
$B14,0C,0001,0D,0052,0E,004D,0F,126A,10,168C,11,FFFF,12,FFFF%3F
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,008D%4D
$B15,02,000A,01,026C,03,0001,08,0B97,09,3C08,0A,0000,0B,0000%47
$B15,0C,0001,0D,0064,0E,005B,0F,1602,10,16B6,11,FFFF,12,FFFF%42
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,008A%49
$B16,02,000A,01,026C,03,0001,08,0B8F,09,3C11,0A,0000,0B,0000%3C
$B16,0C,0001,0D,0064,0E,005E,0F,16AB,10,16AB,11,FFFF,12,FFFF%30
$B16,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B
$B16,1A,0031,1B,4357,1C,00B9%48
$B17,02,000A,01,026C,03,0001,08,0B96,09,3C0A,0A,0000,0B,0000%3D
$B17,0C,0001,0D,0064,0E,005F,0F,16DB,10,1701,11,FFFF,12,FFFF%34
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,00A5%46
$B18,02,000A,01,026C,03,0001,08,0B9A,09,3BB4,0A,0000,0B,0000%43
$B18,0C,0001,0D,0056,0E,004F,0F,12E5,10,16A5,11,FFFF,12,FFFF%3D
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%48
$B18,1A,0031,1B,4357,1C,00AE%39
2015-01-28T19:04:19.431Z,1422471859.431 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T19:04:19.431Z,1422471859.431 [BPC1] Data Fault, FailCount= 1
2015-01-28T19:04:19.431Z,1422471859.431 [BPC1](ERROR): Data Fault
2015-01-28T19:04:19.516Z,1422471859.516 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T19:04:20.650Z,1422471860.650 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:04:20.650Z,1422471860.650 [BPC1] No Fault, FailCount= 1
2015-01-28T19:04:22.202Z,1422471862.202 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:04:23.055Z,1422471863.055 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:04:50.260Z,1422471890.260 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T19:04:50.260Z,1422471890.260 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:04:50.260Z,1422471890.260 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T19:04:50.261Z,1422471890.261 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T19:04:50.261Z,1422471890.261 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T19:04:50.667Z,1422471890.667 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 27.272750 min
2015-01-28T19:04:50.667Z,1422471890.667 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T19:04:50.667Z,1422471890.667 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:04:50.667Z,1422471890.667 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T19:04:50.667Z,1422471890.667 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:04:53.909Z,1422471893.909 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T19:05:24.234Z,1422471924.234 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:05:25.031Z,1422471925.031 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:05:55.049Z,1422471955.049 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:05:55.050Z,1422471955.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2015-01-28T19:05:55.050Z,1422471955.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:05:55.081Z,1422471955.081 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:05:55.938Z,1422471955.938 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T19:05:55.000Z,1422471956.000 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:05:56.000Z,1422471956.000 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2015-01-28T19:05:56.105Z,1422471956.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:05:56.503Z,1422471956.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:05:56.900Z,1422471956.900 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:05:57.296Z,1422471957.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:05:57.702Z,1422471957.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:05:58.101Z,1422471958.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:05:58.502Z,1422471958.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:06:00.089Z,1422471960.089 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5E%57
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFC%36
$B12,0A,0000%30
$B13,0A,FFFC%34
$B14,0A,FFFD%34
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5E%57
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B96,09,3C2A,0A,FFFC,0B,0001%3D
$B11,0C,0001,0D,005A,0E,0056,0F,1498,10,1787,11,FFFF,12,FFFF%3F
$B11,13,B004,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%37
$B11,1A,0031,1B,4357,1C,00A7%42
$B12,02,000A,01,026C,03,0001,08,0B96,09,3C11,0A,0000,0B,0003%4A
$B12,0C,0001,0D,0064,0E,0060,0F,170F,10,170F,11,FFFF,12,FFFF%42
$B12,13,0000,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4F
$B12,1A,0031,1B,4357,1C,009E%4B
$B13,02,000A,01,026C,03,0001,08,0B97,09,3C2D,0A,FFFC,0B,0000%3A
$B13,0C,0001,0D,0060,0E,0054,0F,1438,10,17AC,11,FFFF,12,FFFF%4A
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4357,1C,0089%37
$B14,02,000A,01,026C,03,0001,08,0B9C,09,3C2E,0A,FFFD,0B,FFFF%4F
$B14,0C,0001,0D,0058,0E,0054,0F,1421,10,16F6,11,FFFF,12,FFFF%3D
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,00A6%46
$B15,02,000A,01,026C,03,0001,08,0B9B,09,3C0A,0A,0000,0B,0002%49
$B15,0C,0001,0D,0064,0E,005C,0F,162F,10,171F,11,FFFF,12,FFFF%37
$B15,13,1C20,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%38
$B15,1A,0031,1B,4357,1C,00A9%48
$B16,02,000A,01,026C,03,0001,08,0B91,09,3C2B,0A,0000,0B,0002%38
$B16,0C,0001,0D,0061,0E,0057,0F,14ED,10,1698,11,FFFF,12,FFFF%45
$B16,13,320A,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%35
$B16,1A,0031,1B,4357,1C,00A8%4A
$B17,02,000A,01,026C,03,0001,08,0B90,09,3C25,0A,0000,0B,0002%4F
$B17,0C,0001,0D,005D,0E,0056,0F,14D3,10,16E3,11,FFFF,12,FFFF%32
$B17,13,3DE0,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%46
$B17,1A,0031,1B,4357,1C,0096%3D
$B18,02,000A,01,026C,03,0001,08,0B93,09,3BB3,0A,0000,0B,0005%33
$B18,0C,0001,0D,0056,0E,0053,0F,13F7,10,17BE,11,FFFF,12,FFFF%3B
$B18,13,2D54,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%3F
$B18,1A,0031,1B,4357,1C,009B%46
2015-01-28T19:06:00.089Z,1422471960.089 [BPC1](FAULT): Failed to parse bank B battery data
2015-01-28T19:06:00.089Z,1422471960.089 [BPC1] Data Fault, FailCount= 1
2015-01-28T19:06:00.089Z,1422471960.089 [BPC1](ERROR): Data Fault
2015-01-28T19:06:00.107Z,1422471960.107 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T19:06:01.328Z,1422471961.328 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:06:01.328Z,1422471961.328 [BPC1] No Fault, FailCount= 1
2015-01-28T19:06:14.130Z,1422471974.130 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T19:06:26.255Z,1422471986.255 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:06:27.056Z,1422471987.056 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:06:30.667Z,1422471990.667 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5E%57
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,FFFC%36
$B12,0A,0000%30
$B13,0A,FFFC%34
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5E%57
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B96,09,3C2A,0A,FFFD,0B,FFFD%39
$B11,0C,0001,0D,005A,0E,0056,0F,1498,10,1787,11,FFFF,12,FFFF%3F
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%41
$B11,1A,0031,1B,4357,1C,00A7%42
$B12,02,000A,01,026C,03,0001,08,0B96,09,3C12,0A,0000,0B,0000%4A
$B12,0C,0001,0D,0064,0E,0060,0F,170F,10,170F,11,FFFF,12,FFFF%42
$B12,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4F
$B12,1A,0031,1B,4357,1C,009E%4B
$B13,02,000A,01,026C,03,0001,08,0B97,09,3C2D,0A,FFFC,0B,FFFC%3F
$B13,0C,0001,0D,0060,0E,0054,0F,1438,10,17AC,11,FFFF,12,FFFF%4A
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4357,1C,0089%37
$B14,02,000A,01,026C,03,0001,08,0B9C,09,3C2E,0A,FFFD,0B,FFFD%4D
$B14,0C,0001,0D,0058,0E,0054,0F,1421,10,16F6,11,FFFF,12,FFFF%3D
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,00A6%46
$B15,02,000A,01,026C,03,0001,08,0B9B,09,3C0A,0A,0000,0B,0000%4B
$B15,0C,0001,0D,0064,0E,005C,0F,162F,10,171F,11,FFFF,12,FFFF%37
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,00A9%48
$B16,02,000A,01,026C,03,0001,08,0B91,09,3C2A,0A,0000,0B,0000%39
$B16,0C,0001,0D,0061,0E,0057,0F,14ED,10,1698,11,FFFF,12,FFFF%45
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%45
$B16,1A,0031,1B,4357,1C,00A8%4A
$B17,02,000A,01,026C,03,0001,08,0B91,09,3C26,0A,0000,0B,0000%4F
$B17,0C,0001,0D,005D,0E,0056,0F,14D3,10,16E3,11,FFFF,12,FFFF%32
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0001,18,1838,19,3840%44
$B17,1A,0031,1B,4357,1C,0096%3D
$B18,02,000A,01,026C,03,0001,08,0B95,09,3BB2,0A,0000,0B,0001%30
$B18,0C,0001,0D,0057,0E,0053,0F,13F7,10,17BE,11,FFFF,12,FFFF%3A
$B18,13,E2A4,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%4A
$B18,1A,0031,1B,4357,1C,009B%46
2015-01-28T19:06:30.667Z,1422471990.667 [BPC1](FAULT): Failed to parse bank B battery data
2015-01-28T19:06:30.667Z,1422471990.667 [BPC1] Data Fault, FailCount= 1
2015-01-28T19:06:30.667Z,1422471990.667 [BPC1](ERROR): Data Fault
2015-01-28T19:06:30.685Z,1422471990.685 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T19:06:31.870Z,1422471991.870 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:06:31.870Z,1422471991.870 [BPC1] No Fault, FailCount= 1
2015-01-28T19:06:43.017Z,1422472003.017 [CommandLine](IMPORTANT): got command get latitude
2015-01-28T19:06:43.018Z,1422472003.018 [CommandLine](FAULT): Element has no value
2015-01-28T19:06:57.950Z,1422472017.950 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T19:07:28.255Z,1422472048.255 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T19:07:29.052Z,1422472049.052 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-01-28T19:07:29.052Z,1422472049.052 [NAL9602] Data Fault, FailCount= 1
2015-01-28T19:07:29.052Z,1422472049.052 [NAL9602](ERROR): Data Fault
2015-01-28T19:07:29.082Z,1422472049.082 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T19:07:29.448Z,1422472049.448 [NAL9602](INFO): Stop
2015-01-28T19:07:29.449Z,1422472049.449 [NAL9602](INFO): Uninitialize
2015-01-28T19:07:29.449Z,1422472049.449 [NAL9602](INFO): Powering down
2015-01-28T19:07:29.833Z,1422472049.833 [NAL9602](INFO): Stopping
2015-01-28T19:07:30.274Z,1422472050.274 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T19:07:30.274Z,1422472050.274 [NAL9602] No Fault, FailCount= 1
2015-01-28T19:07:30.646Z,1422472050.646 [NAL9602](INFO): Start
2015-01-28T19:07:31.052Z,1422472051.052 [NAL9602](INFO): Start
2015-01-28T19:07:31.447Z,1422472051.447 [NAL9602](INFO): Start
2015-01-28T19:07:31.860Z,1422472051.860 [NAL9602](INFO): Start
2015-01-28T19:07:32.233Z,1422472052.233 [NAL9602](INFO): Start
2015-01-28T19:07:32.630Z,1422472052.630 [NAL9602](INFO): Start
2015-01-28T19:07:33.030Z,1422472053.030 [NAL9602](INFO): Start
2015-01-28T19:07:33.451Z,1422472053.451 [NAL9602](INFO): Start
2015-01-28T19:07:33.839Z,1422472053.839 [NAL9602](INFO): Start
2015-01-28T19:07:34.253Z,1422472054.253 [NAL9602](INFO): Start
2015-01-28T19:07:34.649Z,1422472054.649 [NAL9602](INFO): Start
2015-01-28T19:07:34.649Z,1422472054.649 [NAL9602](INFO): Powering up NAL9602
2015-01-28T19:07:45.430Z,1422472065.430 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T19:07:45.430Z,1422472065.430 [NAL9602](INFO): NAL9602 initialized
2015-01-28T19:07:46.260Z,1422472066.260 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:07:46.652Z,1422472066.652 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:07:48.655Z,1422472068.655 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:07:49.064Z,1422472069.064 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:07:51.443Z,1422472071.443 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:07:51.844Z,1422472071.844 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:07:54.644Z,1422472074.644 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:07:55.053Z,1422472075.053 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:07:57.440Z,1422472077.440 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:07:57.841Z,1422472077.841 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:00.629Z,1422472080.629 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:01.035Z,1422472081.035 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:03.430Z,1422472083.430 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:03.831Z,1422472083.831 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:06.643Z,1422472086.643 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:07.040Z,1422472087.040 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:09.447Z,1422472089.447 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:09.852Z,1422472089.852 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:10.294Z,1422472090.294 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-01-28T19:03:10.0Z
2015-01-28T19:08:10.294Z,1422472090.294 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T19:08:10.294Z,1422472090.294 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-01-28T19:08:14.862Z,1422472094.862 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20150128T183411/Courier0012.lzma
2015-01-28T19:08:15.269Z,1422472095.269 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:15.731Z,1422472095.731 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Courier0012.lzma.bak
2015-01-28T19:08:15.731Z,1422472095.731 [DataOverHttps](INFO): SBD MOMSN=1510504
2015-01-28T19:08:15.742Z,1422472095.742 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:20.369Z,1422472100.369 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:20.758Z,1422472100.758 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:24.870Z,1422472104.870 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20150128T183411/Express0013.lzma
2015-01-28T19:08:25.267Z,1422472105.267 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:25.669Z,1422472105.669 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Express0013.lzma.bak
2015-01-28T19:08:25.669Z,1422472105.669 [DataOverHttps](INFO): SBD MOMSN=1510506
2015-01-28T19:08:25.676Z,1422472105.676 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:26.514Z,1422472106.514 [Default:CheckIn:Read_Iridium] Stopped
2015-01-28T19:08:26.515Z,1422472106.515 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-01-28T19:08:26.515Z,1422472106.515 [Default:CheckIn] Stopped
2015-01-28T19:08:26.515Z,1422472106.515 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T19:08:26.515Z,1422472106.515 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T19:08:26.515Z,1422472106.515 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T19:08:41.617Z,1422472121.617 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:08:46.622Z,1422472126.622 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:08:56.685Z,1422472136.685 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:08:56.685Z,1422472136.685 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2015-01-28T19:08:56.686Z,1422472136.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:08:56.697Z,1422472136.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:09:01.667Z,1422472141.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:09:01.667Z,1422472141.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2015-01-28T19:09:06.624Z,1422472146.624 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:09:06.672Z,1422472146.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:09:11.632Z,1422472151.632 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:09:11.730Z,1422472151.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:09:16.684Z,1422472156.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:09:21.694Z,1422472161.694 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:09:26.654Z,1422472166.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:09:31.639Z,1422472171.639 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:09:31.710Z,1422472171.710 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:09:39.427Z,1422472179.427 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:09:39.506Z,1422472179.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:09:51.675Z,1422472191.675 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T19:09:51.675Z,1422472191.675 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:09:51.675Z,1422472191.675 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T19:09:51.676Z,1422472191.676 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T19:09:51.676Z,1422472191.676 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T19:09:56.618Z,1422472196.618 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:09:56.683Z,1422472196.683 [BPC1](FAULT): Failed to receive battery data
2015-01-28T19:09:56.683Z,1422472196.683 [BPC1] Communications Fault, FailCount= 1
2015-01-28T19:09:56.683Z,1422472196.683 [BPC1](ERROR): Communications Fault
2015-01-28T19:09:56.692Z,1422472196.692 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 32.296417 min
2015-01-28T19:09:56.693Z,1422472196.693 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T19:09:56.693Z,1422472196.693 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:09:56.693Z,1422472196.693 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T19:09:56.693Z,1422472196.693 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:09:56.702Z,1422472196.702 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T19:10:01.627Z,1422472201.627 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:10:11.676Z,1422472211.676 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:10:11.676Z,1422472211.676 [BPC1] No Fault, FailCount= 1
2015-01-28T19:10:21.625Z,1422472221.625 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:10:26.621Z,1422472226.621 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:10:46.660Z,1422472246.660 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:10:51.628Z,1422472251.628 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:11:11.615Z,1422472271.615 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:11:16.619Z,1422472276.619 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:11:36.617Z,1422472296.617 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:11:41.622Z,1422472301.622 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:12:01.625Z,1422472321.625 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:12:01.672Z,1422472321.672 [BPC1](FAULT): Failed to receive battery data
2015-01-28T19:12:01.672Z,1422472321.672 [BPC1] Communications Fault, FailCount= 1
2015-01-28T19:12:01.672Z,1422472321.672 [BPC1](ERROR): Communications Fault
2015-01-28T19:12:01.740Z,1422472321.740 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T19:12:06.721Z,1422472326.721 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:12:06.789Z,1422472326.789 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:12:06.789Z,1422472326.789 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2015-01-28T19:12:06.789Z,1422472326.789 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:12:06.820Z,1422472326.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:12:11.682Z,1422472331.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:12:11.682Z,1422472331.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2015-01-28T19:12:16.652Z,1422472336.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:12:16.662Z,1422472336.662 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:12:16.663Z,1422472336.663 [BPC1] No Fault, FailCount= 1
2015-01-28T19:12:21.724Z,1422472341.724 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:12:26.629Z,1422472346.629 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:12:26.702Z,1422472346.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:12:31.612Z,1422472351.612 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:12:31.670Z,1422472351.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:12:36.717Z,1422472356.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:12:41.653Z,1422472361.653 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:12:49.493Z,1422472369.493 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:12:51.622Z,1422472371.622 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:12:56.622Z,1422472376.622 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:16.621Z,1422472396.621 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:21.622Z,1422472401.622 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:26.689Z,1422472406.689 [Default:CheckIn] Running Loop=1
2015-01-28T19:13:26.689Z,1422472406.689 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T19:13:26.689Z,1422472406.689 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T19:13:26.690Z,1422472406.690 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T19:13:26.690Z,1422472406.690 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T19:13:28.616Z,1422472408.616 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:29.017Z,1422472409.017 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:31.414Z,1422472411.414 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:31.817Z,1422472411.817 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:34.611Z,1422472414.611 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:35.018Z,1422472415.018 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:37.417Z,1422472417.417 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:40.641Z,1422472420.641 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:42.449Z,1422472422.449 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:42.851Z,1422472422.851 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:43.295Z,1422472423.295 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T19:13:45.632Z,1422472425.632 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:46.051Z,1422472426.051 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:48.447Z,1422472428.447 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:48.849Z,1422472428.849 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:51.648Z,1422472431.648 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:52.036Z,1422472432.036 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:54.071Z,1422472434.071 [BPC1](ERROR): B bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,0000%33
$B12,0A,FFFD%32
$B13,0A,FFFC%34
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B8B,09,3C0F,0A,0000,0B,0000%49
$B11,0C,0001,0D,0064,0E,005B,0F,15DD,10,1641,11,FFFF,12,FFFF%36
$B11,13,FFFF,14,0000,15,41A0,16,00E0,17,0002,18,1838,19,3840%4F
$B11,1A,0031,1B,4357,1C,009D%49
$B12,02,000A,01,026C,03,0001,08,0B93,09,3C23,0A,FFFD,0B,FFFD%4D
$B12,0C,0001,0D,0052,0E,0051,0F,1361,10,17B2,11,FFFF,12,FFFF%36
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42
$B12,1A,0031,1B,4357,1C,0093%3D
$B13,02,000A,01,026C,03,0001,08,0B9A,09,3C21,0A,FFFC,0B,FFFC%3C
$B13,0C,0001,0D,005B,0E,004E,0F,12B7,10,16D7,11,FFFF,12,FFFF%43
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4333,1C,0068%3A
$B14,02,000A,01,026C,03,0001,08,0B95,09,3C23,0A,FFFC,0B,FFFC%4D
$B14,0C,0001,0D,0052,0E,004D,0F,126A,10,168C,11,FFFF,12,FFFF%3F
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,4357,1C,008D%4D
$B15,02,000A,01,026C,03,0001,08,0B97,09,3C07,0A,0000,0B,0000%48
$B15,0C,0001,0D,0064,0E,005B,0F,1602,10,16B6,11,FFFF,12,FFFF%42
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,008A%49
$B16,02,000A,01,026C,03,0001,08,0B8F,09,3C0F,0A,0000,0B,0000%4A
$B16,0C,0001,0D,0064,0E,005E,0F,16AB,10,16AB,11,FFFF,12,FFFF%30
$B16,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B
$B16,1A,0031,1B,4357,1C,00B9%48
$B17,02,000A,01,026C,03,0001,08,0B96,09,3C0A,0A,0000,0B,0000%3D
$B17,0C,0001,0D,0064,0E,005F,0F,16DB,10,1701,11,FFFF,12,FFFF%34
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,00A5%46
$B18,02,000A,01,026C,03,0001,08,0B9A,09,3BB3,0A,0000,0B,0000%44
$B18,0C,0001,0D,0056,0E,004F,0F,12E5,10,16A5,11,FFFF,12,FFFF%3D
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%48
$B18,1A,0031,1B,4357,1C,00AE%39
2015-01-28T19:13:54.071Z,1422472434.071 [BPC1](FAULT): Failed to parse bank B battery data
2015-01-28T19:13:54.071Z,1422472434.071 [BPC1] Data Fault, FailCount= 1
2015-01-28T19:13:54.071Z,1422472434.071 [BPC1](ERROR): Data Fault
2015-01-28T19:13:54.089Z,1422472434.089 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T19:13:54.431Z,1422472434.431 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:54.857Z,1422472434.857 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:13:55.304Z,1422472435.304 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:13:55.304Z,1422472435.304 [BPC1] No Fault, FailCount= 1
2015-01-28T19:13:57.649Z,1422472437.649 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:13:58.054Z,1422472438.054 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:00.449Z,1422472440.449 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:00.846Z,1422472440.846 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:03.647Z,1422472443.647 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:04.048Z,1422472444.048 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:06.446Z,1422472446.446 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:06.836Z,1422472446.836 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:09.639Z,1422472449.639 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:10.040Z,1422472450.040 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:12.456Z,1422472452.456 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:12.858Z,1422472452.858 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:15.657Z,1422472455.657 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:16.059Z,1422472456.059 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:18.459Z,1422472458.459 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:18.842Z,1422472458.842 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:21.654Z,1422472461.654 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:22.039Z,1422472462.039 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:24.438Z,1422472464.438 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:24.836Z,1422472464.836 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:27.635Z,1422472467.635 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:28.045Z,1422472468.045 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:30.435Z,1422472470.435 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:30.839Z,1422472470.839 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:33.665Z,1422472473.665 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:34.050Z,1422472474.050 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:36.466Z,1422472476.466 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:36.838Z,1422472476.838 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:41.409Z,1422472481.409 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:41.828Z,1422472481.828 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:44.627Z,1422472484.627 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:45.029Z,1422472485.029 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:47.414Z,1422472487.414 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:47.811Z,1422472487.811 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:50.607Z,1422472490.607 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:50.996Z,1422472490.996 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:51.844Z,1422472491.844 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T19:14:51.844Z,1422472491.844 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:14:51.844Z,1422472491.844 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T19:14:51.844Z,1422472491.844 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T19:14:51.844Z,1422472491.844 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T19:14:52.243Z,1422472492.243 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 37.299466 min
2015-01-28T19:14:52.243Z,1422472492.243 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T19:14:52.244Z,1422472492.244 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:14:52.244Z,1422472492.244 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T19:14:52.244Z,1422472492.244 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:14:53.399Z,1422472493.399 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:53.801Z,1422472493.801 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:56.616Z,1422472496.616 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:57.009Z,1422472497.009 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:14:59.409Z,1422472499.409 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:14:59.801Z,1422472499.801 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:02.610Z,1422472502.610 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:03.011Z,1422472503.011 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:05.414Z,1422472505.414 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:05.811Z,1422472505.811 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:08.599Z,1422472508.599 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:09.004Z,1422472509.004 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:11.399Z,1422472511.399 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:11.797Z,1422472511.797 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:14.617Z,1422472514.616 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:15.009Z,1422472515.009 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:17.032Z,1422472517.032 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:15:17.033Z,1422472517.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2015-01-28T19:15:17.033Z,1422472517.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:15:17.044Z,1422472517.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:15:17.417Z,1422472517.417 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:17.483Z,1422472517.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:15:17.483Z,1422472517.483 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2015-01-28T19:15:17.806Z,1422472517.806 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:17.869Z,1422472517.869 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:15:18.245Z,1422472518.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:15:18.645Z,1422472518.645 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:15:19.031Z,1422472519.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:15:19.433Z,1422472519.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:15:19.839Z,1422472519.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:15:20.240Z,1422472520.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:15:20.606Z,1422472520.606 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:21.024Z,1422472521.024 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:23.406Z,1422472523.406 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:23.808Z,1422472523.808 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:26.599Z,1422472526.599 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:26.996Z,1422472526.996 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:29.395Z,1422472529.395 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:29.794Z,1422472529.794 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:32.608Z,1422472532.608 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:33.010Z,1422472533.010 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:35.409Z,1422472535.409 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:35.810Z,1422472535.810 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:41.142Z,1422472541.142 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:41.527Z,1422472541.527 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:43.549Z,1422472543.549 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:43.930Z,1422472543.931 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:46.321Z,1422472546.321 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:46.751Z,1422472546.751 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:48.329Z,1422472548.329 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:48.726Z,1422472548.726 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:50.332Z,1422472550.332 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:50.729Z,1422472550.729 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:52.335Z,1422472552.335 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:52.728Z,1422472552.728 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:54.322Z,1422472554.322 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:54.727Z,1422472554.727 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:56.329Z,1422472556.329 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:56.731Z,1422472556.731 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:15:58.328Z,1422472558.328 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:15:58.738Z,1422472558.738 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:00.332Z,1422472560.332 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:00.733Z,1422472560.733 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:02.335Z,1422472562.335 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:02.725Z,1422472562.725 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:04.330Z,1422472564.330 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:04.731Z,1422472564.731 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:06.329Z,1422472566.329 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:06.730Z,1422472566.730 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:08.336Z,1422472568.336 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:08.730Z,1422472568.730 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:10.327Z,1422472570.327 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:10.728Z,1422472570.728 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:12.330Z,1422472572.330 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:12.727Z,1422472572.727 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:14.329Z,1422472574.329 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:14.725Z,1422472574.725 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:16.328Z,1422472576.328 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:16.730Z,1422472576.730 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:18.327Z,1422472578.327 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:18.729Z,1422472578.729 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:20.339Z,1422472580.339 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:20.728Z,1422472580.728 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:22.338Z,1422472582.338 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:22.727Z,1422472582.727 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:24.329Z,1422472584.329 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:24.730Z,1422472584.730 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:26.336Z,1422472586.336 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:26.737Z,1422472586.737 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:28.327Z,1422472588.327 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:28.729Z,1422472588.729 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:30.339Z,1422472590.339 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:30.727Z,1422472590.727 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:32.341Z,1422472592.341 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:32.727Z,1422472592.727 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:34.328Z,1422472594.328 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:34.734Z,1422472594.734 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:36.337Z,1422472596.337 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:36.737Z,1422472596.737 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:41.166Z,1422472601.166 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:41.370Z,1422472601.370 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:43.435Z,1422472603.435 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:43.764Z,1422472603.764 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:46.555Z,1422472606.555 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:46.953Z,1422472606.953 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:49.348Z,1422472609.348 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:49.768Z,1422472609.768 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:51.347Z,1422472611.347 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:51.748Z,1422472611.748 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:54.550Z,1422472614.550 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:54.949Z,1422472614.949 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:57.348Z,1422472617.348 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:57.754Z,1422472617.754 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:16:59.351Z,1422472619.351 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:16:59.748Z,1422472619.748 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:01.350Z,1422472621.350 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:01.744Z,1422472621.744 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:03.353Z,1422472623.353 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:03.755Z,1422472623.755 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:05.345Z,1422472625.345 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:05.746Z,1422472625.746 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:08.550Z,1422472628.550 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:08.947Z,1422472628.947 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:11.346Z,1422472631.346 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:11.759Z,1422472631.759 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:14.555Z,1422472634.555 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:14.949Z,1422472634.949 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:17.355Z,1422472637.355 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:17.752Z,1422472637.752 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:20.548Z,1422472640.548 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:20.950Z,1422472640.950 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:23.357Z,1422472643.357 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:23.766Z,1422472643.766 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:25.356Z,1422472645.356 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:25.749Z,1422472645.749 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:27.355Z,1422472647.355 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:27.748Z,1422472647.748 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:29.354Z,1422472649.354 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:29.747Z,1422472649.747 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:31.489Z,1422472651.489 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:31.742Z,1422472651.742 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:33.392Z,1422472653.392 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:33.754Z,1422472653.754 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:35.351Z,1422472655.351 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:35.748Z,1422472655.748 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:37.351Z,1422472657.351 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:37.752Z,1422472657.752 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:41.917Z,1422472661.917 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:42.307Z,1422472662.307 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:44.297Z,1422472664.297 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:17:44.703Z,1422472664.703 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:17:46.304Z,1422472666.304 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-01-28T19:17:46.304Z,1422472666.304 [NAL9602] Data Fault, FailCount= 2
2015-01-28T19:17:46.304Z,1422472666.304 [NAL9602](ERROR): Data Fault
2015-01-28T19:17:46.334Z,1422472666.334 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T19:17:46.709Z,1422472666.709 [NAL9602](INFO): Stop
2015-01-28T19:17:46.709Z,1422472666.709 [NAL9602](INFO): Uninitialize
2015-01-28T19:17:46.709Z,1422472666.709 [NAL9602](INFO): Powering down
2015-01-28T19:17:47.106Z,1422472667.106 [NAL9602](INFO): Stopping
2015-01-28T19:17:47.535Z,1422472667.535 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T19:17:47.535Z,1422472667.535 [NAL9602] No Fault, FailCount= 2
2015-01-28T19:17:47.892Z,1422472667.892 [NAL9602](INFO): Start
2015-01-28T19:17:48.295Z,1422472668.295 [NAL9602](INFO): Start
2015-01-28T19:17:48.704Z,1422472668.704 [NAL9602](INFO): Start
2015-01-28T19:17:49.106Z,1422472669.106 [NAL9602](INFO): Start
2015-01-28T19:17:49.506Z,1422472669.506 [NAL9602](INFO): Start
2015-01-28T19:17:49.913Z,1422472669.913 [NAL9602](INFO): Start
2015-01-28T19:17:50.315Z,1422472670.315 [NAL9602](INFO): Start
2015-01-28T19:17:50.708Z,1422472670.708 [NAL9602](INFO): Start
2015-01-28T19:17:51.096Z,1422472671.096 [NAL9602](INFO): Start
2015-01-28T19:17:51.505Z,1422472671.505 [NAL9602](INFO): Start
2015-01-28T19:17:51.906Z,1422472671.906 [NAL9602](INFO): Start
2015-01-28T19:17:51.907Z,1422472671.907 [NAL9602](INFO): Powering up NAL9602
2015-01-28T19:18:03.098Z,1422472683.098 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T19:18:03.099Z,1422472683.099 [NAL9602](INFO): NAL9602 initialized
2015-01-28T19:18:03.904Z,1422472683.904 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:04.301Z,1422472684.301 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:06.716Z,1422472686.716 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:07.105Z,1422472687.105 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:09.905Z,1422472689.905 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:10.298Z,1422472690.298 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:12.717Z,1422472692.717 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:13.119Z,1422472693.119 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:15.911Z,1422472695.911 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:16.332Z,1422472696.332 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:17.952Z,1422472697.952 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:18:17.952Z,1422472697.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2015-01-28T19:18:17.952Z,1422472697.952 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:18:17.963Z,1422472697.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:18:18.363Z,1422472698.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:18:18.363Z,1422472698.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2015-01-28T19:18:18.711Z,1422472698.711 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:18.771Z,1422472698.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:18:19.104Z,1422472699.104 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:19.165Z,1422472699.165 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:18:19.566Z,1422472699.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:18:19.923Z,1422472699.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:18:20.328Z,1422472700.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:18:20.720Z,1422472700.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:18:21.130Z,1422472701.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:18:21.900Z,1422472701.900 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:22.313Z,1422472702.313 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:24.708Z,1422472704.708 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:25.109Z,1422472705.109 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:26.736Z,1422472706.736 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-01-28T19:13:26.7Z
2015-01-28T19:18:26.736Z,1422472706.736 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T19:18:26.736Z,1422472706.736 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-01-28T19:18:27.903Z,1422472707.903 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:31.183Z,1422472711.183 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:32.100Z,1422472712.100 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20150128T183411/Courier0016.lzma
2015-01-28T19:18:32.661Z,1422472712.661 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Courier0016.lzma.bak
2015-01-28T19:18:32.661Z,1422472712.661 [DataOverHttps](INFO): SBD MOMSN=1510520
2015-01-28T19:18:36.911Z,1422472716.911 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:37.104Z,1422472717.104 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:42.050Z,1422472722.050 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20150128T183411/Express0017.lzma
2015-01-28T19:18:42.472Z,1422472722.472 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:18:42.863Z,1422472722.863 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Express0017.lzma.bak
2015-01-28T19:18:42.863Z,1422472722.863 [DataOverHttps](INFO): SBD MOMSN=1510522
2015-01-28T19:18:42.882Z,1422472722.882 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:18:43.706Z,1422472723.706 [Default:CheckIn:Read_Iridium] Stopped
2015-01-28T19:18:43.707Z,1422472723.707 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-01-28T19:18:43.707Z,1422472723.707 [Default:CheckIn] Stopped
2015-01-28T19:18:43.707Z,1422472723.707 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T19:18:43.707Z,1422472723.707 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T19:18:43.707Z,1422472723.707 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T19:18:58.817Z,1422472738.817 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:19:03.809Z,1422472743.809 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:19:23.801Z,1422472763.801 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:19:28.805Z,1422472768.805 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:19:48.819Z,1422472788.819 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:19:56.624Z,1422472796.624 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:19:56.680Z,1422472796.680 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T19:19:56.680Z,1422472796.680 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:19:56.680Z,1422472796.680 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T19:19:56.680Z,1422472796.680 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T19:19:56.680Z,1422472796.680 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T19:19:58.908Z,1422472798.908 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 42.332896 min
2015-01-28T19:19:58.908Z,1422472798.908 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T19:19:58.908Z,1422472798.908 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:19:58.908Z,1422472798.908 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T19:19:58.908Z,1422472798.908 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:20:13.818Z,1422472813.818 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:20:18.814Z,1422472818.814 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:20:38.801Z,1422472838.801 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:20:43.805Z,1422472843.805 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:21:01.645Z,1422472861.645 [BPC1](FAULT): Failed to receive battery data
2015-01-28T19:21:01.645Z,1422472861.645 [BPC1] Communications Fault, FailCount= 1
2015-01-28T19:21:01.645Z,1422472861.645 [BPC1](ERROR): Communications Fault
2015-01-28T19:21:01.662Z,1422472861.662 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T19:21:03.824Z,1422472863.824 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:21:08.816Z,1422472868.816 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:21:13.879Z,1422472873.879 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:21:13.879Z,1422472873.879 [BPC1] No Fault, FailCount= 1
2015-01-28T19:21:18.896Z,1422472878.896 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:21:18.896Z,1422472878.896 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2015-01-28T19:21:18.896Z,1422472878.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:21:18.907Z,1422472878.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:21:23.881Z,1422472883.881 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:21:23.881Z,1422472883.881 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2015-01-28T19:21:28.815Z,1422472888.815 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:21:28.886Z,1422472888.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:21:33.815Z,1422472893.815 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:21:33.885Z,1422472893.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:21:38.902Z,1422472898.902 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:21:43.838Z,1422472903.838 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:21:48.872Z,1422472908.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:21:53.821Z,1422472913.821 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:21:53.892Z,1422472913.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:22:01.608Z,1422472921.608 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:22:01.685Z,1422472921.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:22:08.833Z,1422472928.833 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T19:22:18.804Z,1422472938.804 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:22:18.883Z,1422472938.883 [BPC1](FAULT): Failed to receive battery data
2015-01-28T19:22:18.883Z,1422472938.883 [BPC1] Communications Fault, FailCount= 1
2015-01-28T19:22:18.883Z,1422472938.883 [BPC1](ERROR): Communications Fault
2015-01-28T19:22:18.900Z,1422472938.900 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T19:22:23.813Z,1422472943.813 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:22:33.860Z,1422472953.860 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:22:33.860Z,1422472953.860 [BPC1] No Fault, FailCount= 1
2015-01-28T19:22:43.829Z,1422472963.829 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:22:48.834Z,1422472968.834 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:08.834Z,1422472988.834 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:13.806Z,1422472993.806 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:33.810Z,1422473013.810 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:38.814Z,1422473018.814 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:43.857Z,1422473023.857 [Default:CheckIn] Running Loop=1
2015-01-28T19:23:43.857Z,1422473023.857 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T19:23:43.857Z,1422473023.857 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T19:23:43.857Z,1422473023.857 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T19:23:43.857Z,1422473023.857 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T19:23:45.816Z,1422473025.816 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:46.205Z,1422473026.205 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:48.601Z,1422473028.601 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:49.001Z,1422473029.001 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:50.607Z,1422473030.607 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:51.012Z,1422473031.012 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:52.602Z,1422473032.602 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:53.012Z,1422473033.012 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:23:58.415Z,1422473038.415 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:23:58.822Z,1422473038.822 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:00.811Z,1422473040.811 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:01.212Z,1422473041.212 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:03.598Z,1422473043.598 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:04.012Z,1422473044.012 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:06.820Z,1422473046.820 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:07.222Z,1422473047.222 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:09.613Z,1422473049.613 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:10.014Z,1422473050.014 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:12.813Z,1422473052.813 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:13.215Z,1422473053.215 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:15.633Z,1422473055.633 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:16.015Z,1422473056.015 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:18.815Z,1422473058.815 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:19.210Z,1422473059.210 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:21.615Z,1422473061.615 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:22.017Z,1422473062.017 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:24.812Z,1422473064.812 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:25.214Z,1422473065.214 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:27.613Z,1422473067.613 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:28.022Z,1422473068.022 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:28.894Z,1422473068.894 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:24:28.894Z,1422473068.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2015-01-28T19:24:28.894Z,1422473068.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:24:28.905Z,1422473068.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:24:29.269Z,1422473069.269 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:24:29.269Z,1422473069.269 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2015-01-28T19:24:29.631Z,1422473069.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:24:30.040Z,1422473070.040 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:24:30.436Z,1422473070.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:24:30.814Z,1422473070.814 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:30.891Z,1422473070.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:24:31.211Z,1422473071.211 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:31.281Z,1422473071.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:24:31.678Z,1422473071.678 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:24:32.058Z,1422473072.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:24:33.618Z,1422473073.618 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:34.019Z,1422473074.019 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:36.827Z,1422473076.827 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:37.225Z,1422473077.225 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:39.620Z,1422473079.620 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:40.025Z,1422473080.025 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:42.820Z,1422473082.820 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:43.214Z,1422473083.214 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:45.631Z,1422473085.631 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:46.014Z,1422473086.014 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:48.821Z,1422473088.821 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:49.220Z,1422473089.220 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:51.618Z,1422473091.618 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:52.015Z,1422473092.015 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:54.819Z,1422473094.819 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:24:58.030Z,1422473098.030 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:24:58.147Z,1422473098.147 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T19:24:58.147Z,1422473098.147 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:24:58.147Z,1422473098.147 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T19:24:58.148Z,1422473098.148 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T19:24:58.148Z,1422473098.148 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T19:24:58.400Z,1422473098.400 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 47.356310 min
2015-01-28T19:24:58.400Z,1422473098.400 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T19:24:58.400Z,1422473098.400 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:24:58.400Z,1422473098.400 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T19:24:58.401Z,1422473098.401 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T19:24:59.847Z,1422473099.847 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:00.233Z,1422473100.233 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:02.652Z,1422473102.652 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:03.045Z,1422473103.045 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:05.849Z,1422473105.849 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:06.233Z,1422473106.233 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:08.632Z,1422473108.632 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:09.038Z,1422473109.038 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:11.837Z,1422473111.837 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:11.887Z,1422473111.887 [BPC1](ERROR): A bank expecting battery number:1 and read number: uart read:X
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,0A,0000%33
$B12,0A,FFFC%35
$B13,0A,FFFD%33
$B14,0A,FFFC%33
$B15,0A,0000%37
$B16,0A,0000%34
$B17,0A,0000%35
$B18,0A,0000%3A
$S,01,270F,02,00,04,5C%51
$C1,01,FF,02,00,03,FF,04,00,05,00,06,00,07,00%72
$B11,02,000A,01,026C,03,0001,08,0B8C,09,3C0D,0A,0000,0B,0000%4A
$B11,0C,0001,0D,0064,0E,005B,0F,15DD,10,1641,11,FFFF,12,FFFF%36
$B11,13,FFFF,14,0000,15,41A0,16,00E0,17,0002,18,1838,19,3840%4F
$B11,1A,0031,1B,4357,1C,009D%49
$B12,02,000A,01,026C,03,0001,08,0B93,09,3C22,0A,FFFC,0B,FFFC%4C
$B12,0C,0001,0D,0052,0E,0051,0F,1361,10,17B2,11,FFFF,12,FFFF%36
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%42
$B12,1A,0031,1B,4357,1C,0093%3D
$B13,02,000A,01,026C,03,0001,08,0B9A,09,3C20,0A,FFFC,0B,FFFC%3D
$B13,0C,0001,0D,005B,0E,004E,0F,12B7,10,16D7,11,FFFF,12,FFFF%43
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%43
$B13,1A,0031,1B,4333,1C,0068%3A
$B14,02,000A,01,026C,03,0001,08,0B95,09,3C21,0A,FFFC,0B,FFFC%4F
$B14,0C,0001,0D,0052,0E,004D,0F,1269,10,168C,11,FFFF,12,FFFF%47
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%44
$B14,1A,0031,1B,1717,1C,008D%48
$B15,02,000A,01,026C,03,0001,08,0B97,09,3C08,0A,0000,0B,0000%47
$B15,0C,0001,0D,0064,0E,005B,0F,1602,10,16B6,11,FFFF,12,FFFF%42
$B15,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%48
$B15,1A,0031,1B,4357,1C,008A%49
$B16,02,000A,01,026C,03,0001,08,0B90,09,3C10,0A,0000,0B,0000%4A
$B16,0C,0001,0D,0064,0E,005E,0F,16AB,10,16AB,11,FFFF,12,FFFF%30
$B16,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4B
$B16,1A,0031,1B,4357,1C,00B9%48
$B17,02,000A,01,026C,03,0001,08,0B96,09,3C0A,0A,0000,0B,0000%3D
$B17,0C,0001,0D,0064,0E,005F,0F,16DB,10,1701,11,FFFF,12,FFFF%34
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,00A5%46
$B18,02,000A,01,026C,03,0001,08,0B9B,09,3BB3,0A,0000,0B,0000%47
$B18,0C,0001,0D,0056,0E,004F,0F,12E5,10,16A5,11,FFFF,12,FFFF%3D
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0002,18,1838,19,3840%48
$B18,1A,0031,1B,4357,1C,00AE%39
2015-01-28T19:25:11.887Z,1422473111.887 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T19:25:11.887Z,1422473111.887 [BPC1] Data Fault, FailCount= 1
2015-01-28T19:25:11.888Z,1422473111.888 [BPC1](ERROR): Data Fault
2015-01-28T19:25:11.991Z,1422473111.991 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T19:25:12.244Z,1422473112.244 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:13.072Z,1422473113.072 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T19:25:13.073Z,1422473113.073 [BPC1] No Fault, FailCount= 1
2015-01-28T19:25:14.642Z,1422473114.642 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:15.047Z,1422473115.047 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:17.835Z,1422473117.835 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:18.232Z,1422473118.232 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:20.635Z,1422473120.635 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:21.045Z,1422473121.045 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:23.836Z,1422473123.836 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:24.233Z,1422473124.233 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:26.636Z,1422473126.636 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:27.034Z,1422473127.034 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:29.833Z,1422473129.833 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:30.235Z,1422473130.235 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:32.634Z,1422473132.634 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:33.035Z,1422473133.035 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:35.851Z,1422473135.851 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:36.232Z,1422473136.232 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:38.635Z,1422473138.635 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:39.032Z,1422473139.032 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:41.848Z,1422473141.848 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:42.234Z,1422473142.234 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:44.633Z,1422473144.633 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:45.034Z,1422473145.034 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:47.841Z,1422473147.841 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:48.235Z,1422473148.235 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:50.642Z,1422473150.642 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:51.035Z,1422473151.035 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:53.834Z,1422473153.834 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:54.236Z,1422473154.236 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:25:58.774Z,1422473158.774 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:25:59.180Z,1422473159.180 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:01.579Z,1422473161.579 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:01.972Z,1422473161.972 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:03.566Z,1422473163.566 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:03.987Z,1422473163.987 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:05.569Z,1422473165.569 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:05.979Z,1422473165.979 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:07.577Z,1422473167.577 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:07.969Z,1422473167.969 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:09.583Z,1422473169.583 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:09.969Z,1422473169.969 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:11.579Z,1422473171.579 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:11.988Z,1422473171.988 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:13.570Z,1422473173.570 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:13.971Z,1422473173.971 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:15.565Z,1422473175.565 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:15.966Z,1422473175.966 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:17.564Z,1422473177.564 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:17.969Z,1422473177.969 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:19.558Z,1422473179.558 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:19.972Z,1422473179.972 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:21.562Z,1422473181.562 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:21.984Z,1422473181.984 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:23.565Z,1422473183.565 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:23.962Z,1422473183.962 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:25.572Z,1422473185.572 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:25.965Z,1422473185.965 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:27.563Z,1422473187.563 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:27.959Z,1422473187.959 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:29.598Z,1422473189.598 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:30.000Z,1422473190.000 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:31.566Z,1422473191.566 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:31.975Z,1422473191.975 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:33.584Z,1422473193.584 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:33.984Z,1422473193.984 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:35.576Z,1422473195.576 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:35.977Z,1422473195.977 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:37.587Z,1422473197.587 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:37.976Z,1422473197.976 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:39.582Z,1422473199.582 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:39.983Z,1422473199.983 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:41.577Z,1422473201.577 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:41.974Z,1422473201.974 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:43.580Z,1422473203.580 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:43.973Z,1422473203.973 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:45.571Z,1422473205.571 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:45.966Z,1422473205.966 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:47.571Z,1422473207.571 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:47.975Z,1422473207.975 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:49.571Z,1422473209.571 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:49.976Z,1422473209.976 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:51.577Z,1422473211.577 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:51.978Z,1422473211.978 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:53.575Z,1422473213.575 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:53.965Z,1422473213.965 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:26:58.391Z,1422473218.391 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:26:58.540Z,1422473218.540 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:00.955Z,1422473220.955 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:01.360Z,1422473221.360 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:03.747Z,1422473223.747 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:04.153Z,1422473224.153 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:06.952Z,1422473226.952 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:07.346Z,1422473227.346 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:09.741Z,1422473229.741 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:10.154Z,1422473230.154 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:12.962Z,1422473232.962 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:13.359Z,1422473233.359 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:15.746Z,1422473235.746 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:16.148Z,1422473236.148 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:18.940Z,1422473238.940 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:19.352Z,1422473239.352 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:21.751Z,1422473241.751 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:22.161Z,1422473242.161 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:24.948Z,1422473244.948 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:25.350Z,1422473245.350 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:27.758Z,1422473247.758 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:28.152Z,1422473248.152 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:29.785Z,1422473249.785 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T19:27:29.785Z,1422473249.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2015-01-28T19:27:29.785Z,1422473249.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T19:27:29.812Z,1422473249.812 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T19:27:30.195Z,1422473250.195 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T19:27:30.226Z,1422473250.226 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2015-01-28T19:27:30.562Z,1422473250.562 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:30.594Z,1422473250.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T19:27:30.958Z,1422473250.958 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:30.993Z,1422473250.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:27:31.396Z,1422473251.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:27:31.776Z,1422473251.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:27:32.183Z,1422473252.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:27:32.583Z,1422473252.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:27:32.957Z,1422473252.957 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:32.990Z,1422473252.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T19:27:33.359Z,1422473253.359 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:35.762Z,1422473255.762 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:36.172Z,1422473256.172 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:38.946Z,1422473258.946 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:39.364Z,1422473259.364 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:41.763Z,1422473261.763 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:42.164Z,1422473262.164 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:44.960Z,1422473264.960 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:45.345Z,1422473265.345 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:47.743Z,1422473267.743 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:48.153Z,1422473268.153 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:50.552Z,1422473270.552 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:50.954Z,1422473270.954 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:53.353Z,1422473273.353 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:53.754Z,1422473273.754 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:27:59.085Z,1422473279.085 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:27:59.491Z,1422473279.491 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:01.497Z,1422473281.497 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:01.899Z,1422473281.899 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:04.707Z,1422473284.707 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-01-28T19:28:04.707Z,1422473284.707 [NAL9602] Data Fault, FailCount= 3
2015-01-28T19:28:04.707Z,1422473284.707 [NAL9602](ERROR): Data Fault
2015-01-28T19:28:04.762Z,1422473284.762 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T19:28:05.111Z,1422473285.111 [NAL9602](INFO): Stop
2015-01-28T19:28:05.111Z,1422473285.111 [NAL9602](INFO): Uninitialize
2015-01-28T19:28:05.111Z,1422473285.111 [NAL9602](INFO): Powering down
2015-01-28T19:28:05.503Z,1422473285.503 [NAL9602](INFO): Stopping
2015-01-28T19:28:05.933Z,1422473285.933 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T19:28:05.933Z,1422473285.933 [NAL9602] No Fault, FailCount= 3
2015-01-28T19:28:06.300Z,1422473286.300 [NAL9602](INFO): Start
2015-01-28T19:28:06.697Z,1422473286.697 [NAL9602](INFO): Start
2015-01-28T19:28:07.102Z,1422473287.102 [NAL9602](INFO): Start
2015-01-28T19:28:07.514Z,1422473287.514 [NAL9602](INFO): Start
2015-01-28T19:28:07.935Z,1422473287.935 [NAL9602](INFO): Start
2015-01-28T19:28:08.307Z,1422473288.307 [NAL9602](INFO): Start
2015-01-28T19:28:08.712Z,1422473288.712 [NAL9602](INFO): Start
2015-01-28T19:28:09.109Z,1422473289.109 [NAL9602](INFO): Start
2015-01-28T19:28:09.525Z,1422473289.525 [NAL9602](INFO): Start
2015-01-28T19:28:09.922Z,1422473289.922 [NAL9602](INFO): Start
2015-01-28T19:28:10.315Z,1422473290.315 [NAL9602](INFO): Start
2015-01-28T19:28:10.315Z,1422473290.315 [NAL9602](INFO): Powering up NAL9602
2015-01-28T19:28:21.100Z,1422473301.100 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T19:28:21.100Z,1422473301.100 [NAL9602](INFO): NAL9602 initialized
2015-01-28T19:28:21.897Z,1422473301.897 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:22.314Z,1422473302.314 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:24.297Z,1422473304.297 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:24.702Z,1422473304.702 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:27.081Z,1422473307.081 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:27.490Z,1422473307.490 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:30.294Z,1422473310.294 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:30.707Z,1422473310.707 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:33.103Z,1422473313.103 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:33.527Z,1422473313.527 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:36.303Z,1422473316.303 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:36.701Z,1422473316.701 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:39.100Z,1422473319.100 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:39.497Z,1422473319.497 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:42.313Z,1422473322.313 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:42.694Z,1422473322.694 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:43.937Z,1422473323.937 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-01-28T19:23:43.9Z
2015-01-28T19:28:43.937Z,1422473323.937 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T19:28:43.938Z,1422473323.938 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-01-28T19:28:45.097Z,1422473325.097 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:48.293Z,1422473328.293 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:48.553Z,1422473328.553 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20150128T183411/Courier0020.lzma
2015-01-28T19:28:49.251Z,1422473329.251 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Courier0020.lzma.bak
2015-01-28T19:28:49.252Z,1422473329.252 [DataOverHttps](INFO): SBD MOMSN=1510534
2015-01-28T19:28:50.209Z,1422473330.209 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:53.420Z,1422473333.420 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:55.189Z,1422473335.189 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:28:58.414Z,1422473338.414 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:28:58.574Z,1422473338.574 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20150128T183411/Express0021.lzma
2015-01-28T19:28:59.360Z,1422473339.360 [DataOverHttps](INFO): Moved sent file to Logs/20150128T183411/Express0021.lzma.bak
2015-01-28T19:28:59.361Z,1422473339.361 [DataOverHttps](INFO): SBD MOMSN=1510536
2015-01-28T19:29:00.241Z,1422473340.241 [Default:CheckIn:Read_Iridium] Stopped
2015-01-28T19:29:00.242Z,1422473340.242 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-01-28T19:29:00.242Z,1422473340.242 [Default:CheckIn] Stopped
2015-01-28T19:29:00.242Z,1422473340.242 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T19:29:00.242Z,1422473340.242 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T19:29:00.242Z,1422473340.242 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T19:29:00.381Z,1422473340.381 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:29:08.210Z,1422473348.210 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:29:25.372Z,1422473365.372 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T19:29:30.122Z,1422473370.122 [CommandLine](IMPORTANT): got command quit
2015-01-28T19:29:30.380Z,1422473370.380 [NAL9602](INFO): ************** GetFix **************
2015-01-28T19:29:31.130Z,1422473371.130 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T19:29:31.130Z,1422473371.130 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.199Z,1422473371.199 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2015-01-28T19:29:31.199Z,1422473371.199 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.200Z,1422473371.200 [CommandLine](INFO): Join timeout helper Thread ID is 873
2015-01-28T19:29:31.200Z,1422473371.200 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-01-28T19:29:31.201Z,1422473371.201 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.201Z,1422473371.201 [NavChartDb](INFO): Join timeout helper Thread ID is 874
2015-01-28T19:29:31.224Z,1422473371.224 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T19:29:31.224Z,1422473371.224 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.239Z,1422473371.239 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-01-28T19:29:31.239Z,1422473371.239 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.240Z,1422473371.240 [Radio_Surface](INFO): Join timeout helper Thread ID is 875
2015-01-28T19:29:31.244Z,1422473371.244 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T19:29:31.244Z,1422473371.244 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.259Z,1422473371.259 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-01-28T19:29:31.259Z,1422473371.259 [logger ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.260Z,1422473371.260 [logger](INFO): Join timeout helper Thread ID is 876
2015-01-28T19:29:31.263Z,1422473371.263 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T19:29:31.264Z,1422473371.264 [logger ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.279Z,1422473371.279 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-01-28T19:29:31.280Z,1422473371.280 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.280Z,1422473371.280 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-01-28T19:29:31.280Z,1422473371.280 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:31.280Z,1422473371.280 [controlThread](INFO): Join timeout helper Thread ID is 877
2015-01-28T19:29:35.298Z,1422473375.298 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T19:29:35.298Z,1422473375.298 [controlThread](DEBUG): Uninitializing ControlThread
2015-01-28T19:29:35.300Z,1422473375.300 [NAL9602](INFO): Uninitialize
2015-01-28T19:29:35.300Z,1422473375.300 [NAL9602](INFO): Powering down
2015-01-28T19:29:35.302Z,1422473375.302 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-01-28T19:29:35.302Z,1422473375.302 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-01-28T19:29:35.303Z,1422473375.303 [Default] Stopped
2015-01-28T19:29:35.303Z,1422473375.303 [Default](INFO): Aggregate::uninitialize Default
2015-01-28T19:29:35.303Z,1422473375.303 [Default:B.GoToSurface] Stopped
2015-01-28T19:29:35.303Z,1422473375.303 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-01-28T19:29:35.303Z,1422473375.303 [Default:WaitAtTheSurface] Stopped
2015-01-28T19:29:35.303Z,1422473375.303 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2015-01-28T19:29:35.303Z,1422473375.303 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2015-01-28T19:29:35.303Z,1422473375.303 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2015-01-28T19:29:35.307Z,1422473375.307 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-01-28T19:29:35.307Z,1422473375.307 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-01-28T19:29:35.308Z,1422473375.308 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-01-28T19:29:35.308Z,1422473375.308 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-01-28T19:29:35.308Z,1422473375.308 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-01-28T19:29:35.308Z,1422473375.308 [BuoyancyServo](INFO): Powering down
2015-01-28T19:29:35.322Z,1422473375.322 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-01-28T19:29:35.322Z,1422473375.322 [ElevatorServo](INFO): Powering down
2015-01-28T19:29:35.323Z,1422473375.323 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-01-28T19:29:35.323Z,1422473375.323 [RudderServo](INFO): Powering down
2015-01-28T19:29:35.324Z,1422473375.324 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-01-28T19:29:35.324Z,1422473375.324 [ThrusterServo](INFO): Powering down
2015-01-28T19:29:35.325Z,1422473375.325 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-01-28T19:29:35.325Z,1422473375.325 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-01-28T19:29:35.325Z,1422473375.325 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-01-28T19:29:35.358Z,1422473375.358 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:35.477Z,1422473375.477 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:35.521Z,1422473375.521 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-28T19:29:35.572Z,1422473375.572 [logger ThreadHandler](INFO): Thread cancelled.