2015-01-28T18:11:58.962Z,1422468718.962 [Supervisor](DEBUG): Initializing supervisor.
2015-01-28T18:11:58.965Z,1422468718.965 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-01-28T18:11:58.966Z,1422468718.966 [SyncHandler](INFO): Protected caller Thread ID is 769
2015-01-28T18:11:58.966Z,1422468718.966 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-01-28T18:11:58.967Z,1422468718.967 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-01-28T18:11:58.967Z,1422468718.967 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770
2015-01-28T18:11:58.970Z,1422468718.970 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-01-28T18:11:58.982Z,1422468718.982 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-01-28T18:11:58.984Z,1422468718.984 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-01-28T18:11:58.984Z,1422468718.984 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771
2015-01-28T18:11:58.985Z,1422468718.985 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-01-28T18:11:58.986Z,1422468718.986 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-01-28T18:11:58.986Z,1422468718.986 [logger ThreadHandler](INFO): Protected caller Thread ID is 772
2015-01-28T18:11:58.988Z,1422468718.988 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-01-28T18:11:58.989Z,1422468718.989 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-01-28T18:11:58.992Z,1422468718.992 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-01-28T18:11:59.336Z,1422468719.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-01-28T18:11:59.339Z,1422468719.339 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-01-28T18:11:59.502Z,1422468719.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-01-28T18:11:59.503Z,1422468719.503 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-01-28T18:11:59.614Z,1422468719.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-01-28T18:11:59.615Z,1422468719.615 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-01-28T18:11:59.740Z,1422468719.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-01-28T18:11:59.741Z,1422468719.741 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-01-28T18:11:59.829Z,1422468719.829 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-01-28T18:11:59.994Z,1422468719.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-01-28T18:11:59.995Z,1422468719.995 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-01-28T18:12:00.083Z,1422468720.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-01-28T18:12:00.084Z,1422468720.084 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-01-28T18:12:00.297Z,1422468720.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-01-28T18:12:00.298Z,1422468720.298 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-01-28T18:12:00.681Z,1422468720.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-01-28T18:12:00.682Z,1422468720.682 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-01-28T18:12:01.009Z,1422468721.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-01-28T18:12:01.010Z,1422468721.010 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-01-28T18:12:01.518Z,1422468721.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-01-28T18:12:01.519Z,1422468721.519 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-01-28T18:12:01.725Z,1422468721.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-01-28T18:12:01.726Z,1422468721.726 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-01-28T18:12:01.832Z,1422468721.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-01-28T18:12:01.833Z,1422468721.833 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-01-28T18:12:02.270Z,1422468722.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-01-28T18:12:02.271Z,1422468722.271 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-01-28T18:12:02.384Z,1422468722.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-01-28T18:12:02.386Z,1422468722.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-01-28T18:12:02.390Z,1422468722.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-01-28T18:12:02.639Z,1422468722.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-01-28T18:12:15.802Z,1422468735.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-01-28T18:12:16.076Z,1422468736.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-01-28T18:12:16.979Z,1422468736.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-01-28T18:12:17.080Z,1422468737.080 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-01-28T18:12:17.199Z,1422468737.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-01-28T18:12:17.344Z,1422468737.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-01-28T18:12:17.521Z,1422468737.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-01-28T18:12:17.627Z,1422468737.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-01-28T18:12:17.719Z,1422468737.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-01-28T18:12:17.822Z,1422468737.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-01-28T18:12:17.922Z,1422468737.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-01-28T18:12:18.110Z,1422468738.110 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-01-28T18:12:18.118Z,1422468738.118 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-01-28T18:12:18.193Z,1422468738.193 [VerticalControl](DEBUG): Construct VerticalControl.
2015-01-28T18:12:18.315Z,1422468738.315 [VerticalControl] Loaded
2015-01-28T18:12:18.315Z,1422468738.315 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-01-28T18:12:18.316Z,1422468738.316 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-01-28T18:12:18.389Z,1422468738.389 [HorizontalControl] Loaded
2015-01-28T18:12:18.389Z,1422468738.389 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-01-28T18:12:18.390Z,1422468738.390 [SpeedControl](DEBUG): Construct SpeedControl.
2015-01-28T18:12:18.396Z,1422468738.396 [SpeedControl] Loaded
2015-01-28T18:12:18.396Z,1422468738.396 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-01-28T18:12:18.397Z,1422468738.397 [LoopControl](DEBUG): Construct LoopControl.
2015-01-28T18:12:18.397Z,1422468738.397 [LoopControl] Loaded
2015-01-28T18:12:18.398Z,1422468738.398 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-01-28T18:12:18.398Z,1422468738.398 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-01-28T18:12:18.399Z,1422468738.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-01-28T18:12:18.522Z,1422468738.522 [SBIT](DEBUG): Construct Startup Built In Test.
2015-01-28T18:12:18.534Z,1422468738.534 [SBIT] Loaded
2015-01-28T18:12:18.534Z,1422468738.534 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-01-28T18:12:18.535Z,1422468738.535 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-01-28T18:12:18.565Z,1422468738.565 [IBIT] Loaded
2015-01-28T18:12:18.565Z,1422468738.565 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-01-28T18:12:18.567Z,1422468738.567 [CBIT](DEBUG): Construct CBIT Built In Test.
2015-01-28T18:12:18.687Z,1422468738.687 [CBIT] Loaded
2015-01-28T18:12:18.688Z,1422468738.688 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-01-28T18:12:18.688Z,1422468738.688 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-01-28T18:12:18.689Z,1422468738.689 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-01-28T18:12:18.718Z,1422468738.718 [DepthRateCalculator] Loaded
2015-01-28T18:12:18.719Z,1422468738.719 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-01-28T18:12:18.724Z,1422468738.724 [PitchRateCalculator] Loaded
2015-01-28T18:12:18.725Z,1422468738.725 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-01-28T18:12:18.737Z,1422468738.737 [SpeedCalculator] Loaded
2015-01-28T18:12:18.737Z,1422468738.737 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-01-28T18:12:18.754Z,1422468738.754 [TempGradientCalculator] Loaded
2015-01-28T18:12:18.754Z,1422468738.754 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-01-28T18:12:18.760Z,1422468738.760 [YawRateCalculator] Loaded
2015-01-28T18:12:18.760Z,1422468738.760 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-01-28T18:12:18.761Z,1422468738.761 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-01-28T18:12:18.761Z,1422468738.761 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-01-28T18:12:18.853Z,1422468738.853 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-01-28T18:12:18.855Z,1422468738.855 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-01-28T18:12:19.129Z,1422468739.129 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-01-28T18:12:19.130Z,1422468739.130 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-01-28T18:12:19.239Z,1422468739.239 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-01-28T18:12:19.239Z,1422468739.239 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-01-28T18:12:19.256Z,1422468739.256 [NavChart] Loaded
2015-01-28T18:12:19.257Z,1422468739.257 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-01-28T18:12:19.261Z,1422468739.261 [UniversalFixResidualReporter] Loaded
2015-01-28T18:12:19.261Z,1422468739.261 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-01-28T18:12:19.262Z,1422468739.262 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-01-28T18:12:19.263Z,1422468739.263 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-01-28T18:12:19.272Z,1422468739.272 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-01-28T18:12:19.273Z,1422468739.273 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-01-28T18:12:19.389Z,1422468739.389 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-01-28T18:12:19.390Z,1422468739.390 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-01-28T18:12:20.028Z,1422468740.028 [DataOverHttps] Loaded
2015-01-28T18:12:20.028Z,1422468740.028 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-01-28T18:12:20.112Z,1422468740.112 [Depth_Keller] Loaded
2015-01-28T18:12:20.112Z,1422468740.112 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-01-28T18:12:20.207Z,1422468740.207 [NAL9602] Loaded
2015-01-28T18:12:20.207Z,1422468740.207 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-01-28T18:12:20.251Z,1422468740.251 [Onboard] Loaded
2015-01-28T18:12:20.251Z,1422468740.251 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-01-28T18:12:20.258Z,1422468740.258 [Radio_Surface] Loaded
2015-01-28T18:12:20.258Z,1422468740.258 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-01-28T18:12:20.260Z,1422468740.260 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2015-01-28T18:12:20.260Z,1422468740.260 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854
2015-01-28T18:12:20.448Z,1422468740.448 [PNI_TCM] Loaded
2015-01-28T18:12:20.448Z,1422468740.448 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-01-28T18:12:22.277Z,1422468742.277 [BPC1] Loaded
2015-01-28T18:12:22.277Z,1422468742.277 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-01-28T18:12:22.278Z,1422468742.278 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-01-28T18:12:22.278Z,1422468742.278 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-01-28T18:12:25.834Z,1422468745.834 [BuoyancyServo] Loaded
2015-01-28T18:12:25.834Z,1422468745.834 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-01-28T18:12:25.847Z,1422468745.847 [ElevatorServo] Loaded
2015-01-28T18:12:25.847Z,1422468745.847 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-01-28T18:12:25.859Z,1422468745.859 [RudderServo] Loaded
2015-01-28T18:12:25.860Z,1422468745.860 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-01-28T18:12:25.871Z,1422468745.871 [ThrusterServo] Loaded
2015-01-28T18:12:25.872Z,1422468745.872 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-01-28T18:12:25.872Z,1422468745.872 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-01-28T18:12:25.873Z,1422468745.873 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-01-28T18:12:32.304Z,1422468752.304 [ExternalSim] Loaded
2015-01-28T18:12:32.305Z,1422468752.305 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2015-01-28T18:12:32.305Z,1422468752.305 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-01-28T18:12:32.306Z,1422468752.306 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-01-28T18:12:32.347Z,1422468752.347 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-01-28T18:12:32.349Z,1422468752.349 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-01-28T18:12:32.350Z,1422468752.350 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-01-28T18:12:32.357Z,1422468752.357 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-01-28T18:12:32.358Z,1422468752.358 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4083C4E0
2015-01-28T18:12:32.358Z,1422468752.358 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 855
2015-01-28T18:12:32.363Z,1422468752.363 [Supervisor](INFO): Main Thread ID is 767
2015-01-28T18:12:32.363Z,1422468752.363 [Supervisor](DEBUG): Running supervisor.
2015-01-28T18:12:32.364Z,1422468752.364 [CommandLine ThreadHandler](INFO): Handler Thread ID is 856
2015-01-28T18:12:32.367Z,1422468752.367 [controlThread ThreadHandler](INFO): Handler Thread ID is 857
2015-01-28T18:12:32.367Z,1422468752.367 [controlThread](DEBUG): Initializing ControlThread
2015-01-28T18:12:32.368Z,1422468752.368 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-01-28T18:12:32.370Z,1422468752.370 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-01-28T18:12:32.371Z,1422468752.371 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-01-28T18:12:32.371Z,1422468752.371 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-01-28T18:12:32.371Z,1422468752.371 [SBIT](INFO): Initialize SBIT Component.
2015-01-28T18:12:32.372Z,1422468752.372 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11963
2015-01-28T18:12:32.372Z,1422468752.372 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-01-28T18:12:32.372Z,1422468752.372 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-01-28T18:12:32.373Z,1422468752.373 [IBIT](INFO): Initialize IBIT Component.
2015-01-28T18:12:32.374Z,1422468752.374 [CBIT](DEBUG): Initialize CBIT Component.
2015-01-28T18:12:32.374Z,1422468752.374 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-01-28T18:12:32.374Z,1422468752.374 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2015-01-28T18:12:32.375Z,1422468752.375 [logger ThreadHandler](INFO): Handler Thread ID is 858
2015-01-28T18:12:32.400Z,1422468752.400 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-01-28T18:12:32.400Z,1422468752.400 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-01-28T18:12:32.400Z,1422468752.400 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-01-28T18:12:32.401Z,1422468752.401 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-01-28T18:12:32.401Z,1422468752.401 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-01-28T18:12:32.401Z,1422468752.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:12:32.402Z,1422468752.402 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-01-28T18:12:32.402Z,1422468752.402 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-01-28T18:12:32.405Z,1422468752.405 [ExternalSim](INFO): ExternalSim initializing...
2015-01-28T18:12:32.396Z,1422468752.396 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 859
2015-01-28T18:12:32.422Z,1422468752.422 [Radio_Surface](INFO): Powering up
2015-01-28T18:12:32.428Z,1422468752.428 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 860
2015-01-28T18:12:32.439Z,1422468752.439 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-01-28T18:12:32.442Z,1422468752.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-01-28T18:12:32.443Z,1422468752.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-01-28T18:12:32.443Z,1422468752.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-01-28T18:12:32.443Z,1422468752.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-01-28T18:12:32.444Z,1422468752.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-01-28T18:12:32.444Z,1422468752.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-01-28T18:12:32.444Z,1422468752.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-01-28T18:12:32.444Z,1422468752.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-01-28T18:14:05.753Z,1422468845.753 [ExternalSim](DEBUG): beaconLat = 36.80340
2015-01-28T18:14:05.753Z,1422468845.753 [ExternalSim](DEBUG): beaconLon = -121.82230
2015-01-28T18:14:05.754Z,1422468845.754 [ExternalSim](DEBUG): beaconDepth = 25.00
2015-01-28T18:14:05.856Z,1422468845.856 [ExternalSim](DEBUG): Simulator initialized
2015-01-28T18:14:05.915Z,1422468845.915 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-01-28T18:14:05.944Z,1422468845.944 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-01-28T18:14:05.960Z,1422468845.960 [MissionManager](DEBUG):
2015-01-28T18:14:05.961Z,1422468845.961 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-01-28T18:14:06.023Z,1422468846.023 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-01-28T18:14:06.027Z,1422468846.027 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-01-28T18:14:06.031Z,1422468846.031 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-01-28T18:14:06.039Z,1422468846.039 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-01-28T18:14:06.045Z,1422468846.045 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-01-28T18:14:06.053Z,1422468846.053 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-01-28T18:14:06.059Z,1422468846.059 [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:14:06.064Z,1422468846.064 [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:14:06.284Z,1422468846.284 [NAL9602](INFO): Start
2015-01-28T18:14:06.285Z,1422468846.285 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:14:06.410Z,1422468846.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:14:06.504Z,1422468846.504 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:14:06.510Z,1422468846.510 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-01-28T18:14:06.517Z,1422468846.517 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:14:06.523Z,1422468846.523 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-01-28T18:14:06.529Z,1422468846.529 [RudderServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:14:06.534Z,1422468846.534 [RudderServo](DEBUG): Initializing RudderServo.
2015-01-28T18:14:06.540Z,1422468846.540 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-01-28T18:14:06.546Z,1422468846.546 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-01-28T18:14:06.575Z,1422468846.575 [CommandLine](FAULT): Scheduling is paused
2015-01-28T18:14:06.723Z,1422468846.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:14:06.731Z,1422468846.731 [SBIT](IMPORTANT): Beginning Startup BIT
2015-01-28T18:14:06.733Z,1422468846.733 [CBIT](IMPORTANT): Beginning GF scan
2015-01-28T18:14:09.903Z,1422468849.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:14:10.280Z,1422468850.280 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:14:10.681Z,1422468850.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:14:11.056Z,1422468851.056 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003907
2015-01-28T18:14:11.084Z,1422468851.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:14:17.457Z,1422468857.457 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:14:17.457Z,1422468857.457 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:14:18.258Z,1422468858.258 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:14:18.659Z,1422468858.659 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:14:19.965Z,1422468859.965 [SBIT](ERROR): Could not read massPosReader_.
2015-01-28T18:14:33.152Z,1422468873.152 [CBIT](IMPORTANT): No ground fault detected
2015-01-28T18:14:35.075Z,1422468875.075 [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,FFFD%32
$B13,0A,FFFC%34
$B14,0A,FFFD%34
$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,3C2B,0A,FFFC,0B,FFFC%48
$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,3C2C,0A,FFFC,0B,FFFC%3B
$B12,0C,0001,0D,0058,0E,0052,0F,13C2,10,16AC,11,FFFF,12,FFFF%3A
$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,3C29,0A,FFFC,0B,FFFC%45
$B13,0C,0001,0D,0061,0E,0051,0F,1395,10,16AE,11,FFFF,12,FFFF%49
$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,3C07,0A,0000,0B,0000%46
$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,3C22,0A,0000,0B,0000%39
$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,1653,10,16E1,11,FFFF,12,FFFF%42
$B17,13,FFFF,14,0000,15,41A0,16,00E0,17,0001,18,1838,19,3840%4A
$B17,1A,0031,1B,4357,1C,00B4%44
2015-01-28T18:14:35.075Z,1422468875.075 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T18:14:35.075Z,1422468875.075 [BPC1] Data Fault, FailCount= 1
2015-01-28T18:14:35.075Z,1422468875.075 [BPC1](ERROR): Data Fault
2015-01-28T18:14:35.158Z,1422468875.158 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:14:36.360Z,1422468876.360 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:14:36.360Z,1422468876.360 [BPC1] No Fault, FailCount= 1
2015-01-28T18:14:46.359Z,1422468886.359 [SBIT](ERROR): Could not read massPosReader_.
2015-01-28T18:14:46.359Z,1422468886.359 [SBIT](FAULT): Control surface position failure.
2015-01-28T18:14:49.466Z,1422468889.466 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:14:49.866Z,1422468889.866 [NAL9602](INFO): MT Sig Qty requested
2015-01-28T18:14:59.894Z,1422468899.894 [SBIT](ERROR): Could not read massPosReader_.
2015-01-28T18:14:59.894Z,1422468899.894 [SBIT](FAULT): Control surface position failure.
2015-01-28T18:15:00.294Z,1422468900.294 [SBIT](CRITICAL): SBIT FAILED
2015-01-28T18:15:00.687Z,1422468900.687 [MissionManager](IMPORTANT): Started mission Startup
2015-01-28T18:15:00.687Z,1422468900.687 [Startup] Running Loop=1
2015-01-28T18:15:00.687Z,1422468900.687 [Startup](INFO): Aggregate::initialize Startup
2015-01-28T18:15:00.687Z,1422468900.687 [Startup:A.GoToSurface] Running Loop=1
2015-01-28T18:15:00.688Z,1422468900.688 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-01-28T18:15:00.688Z,1422468900.688 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-01-28T18:15:00.688Z,1422468900.688 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-01-28T18:15:00.689Z,1422468900.689 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-01-28T18:15:00.695Z,1422468900.695 [Startup:StartupSatComms] Running Loop=1
2015-01-28T18:15:00.696Z,1422468900.696 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2015-01-28T18:15:00.696Z,1422468900.696 [Startup:StartupSatComms:A] Running Loop=1
2015-01-28T18:15:01.089Z,1422468901.089 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-01-28T18:15:19.973Z,1422468919.973 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-01-28T18:15:20.489Z,1422468920.489 [NAL9602](FAULT): received:
2015-01-28T18:15:20.489Z,1422468920.489 [NAL9602] Data Fault, FailCount= 1
2015-01-28T18:15:20.489Z,1422468920.489 [NAL9602](ERROR): Data Fault
2015-01-28T18:15:20.571Z,1422468920.571 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:15:20.650Z,1422468920.650 [NAL9602](INFO): Stop
2015-01-28T18:15:20.650Z,1422468920.650 [NAL9602](INFO): Uninitialize
2015-01-28T18:15:20.650Z,1422468920.650 [NAL9602](INFO): Powering down
2015-01-28T18:15:21.047Z,1422468921.047 [NAL9602](INFO): Stopping
2015-01-28T18:15:21.491Z,1422468921.491 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:15:21.491Z,1422468921.491 [NAL9602] No Fault, FailCount= 1
2015-01-28T18:15:21.848Z,1422468921.848 [NAL9602](INFO): Start
2015-01-28T18:15:22.249Z,1422468922.249 [NAL9602](INFO): Start
2015-01-28T18:15:22.645Z,1422468922.645 [NAL9602](INFO): Start
2015-01-28T18:15:23.041Z,1422468923.041 [NAL9602](INFO): Start
2015-01-28T18:15:23.455Z,1422468923.455 [NAL9602](INFO): Start
2015-01-28T18:15:23.850Z,1422468923.850 [NAL9602](INFO): Start
2015-01-28T18:15:24.248Z,1422468924.248 [NAL9602](INFO): Start
2015-01-28T18:15:24.649Z,1422468924.649 [NAL9602](INFO): Start
2015-01-28T18:15:25.045Z,1422468925.045 [NAL9602](INFO): Start
2015-01-28T18:15:25.446Z,1422468925.446 [NAL9602](INFO): Start
2015-01-28T18:15:25.850Z,1422468925.850 [NAL9602](INFO): Start
2015-01-28T18:15:25.851Z,1422468925.851 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:15:32.706Z,1422468932.706 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:15:32.706Z,1422468932.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2015-01-28T18:15:32.706Z,1422468932.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:15:32.747Z,1422468932.747 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:15:33.099Z,1422468933.099 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:15:33.099Z,1422468933.099 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2015-01-28T18:15:33.493Z,1422468933.493 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:15:33.865Z,1422468933.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:15:34.355Z,1422468934.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:15:34.692Z,1422468934.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:15:35.063Z,1422468935.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:15:35.483Z,1422468935.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:15:35.864Z,1422468935.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:15:37.048Z,1422468937.048 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:15:37.048Z,1422468937.048 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:15:37.843Z,1422468937.843 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:15:38.251Z,1422468938.251 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:16:01.070Z,1422468961.070 [Startup:StartupSatComms:A](INFO): Timed out from 2015-01-28T18:15:00.7Z
2015-01-28T18:16:01.070Z,1422468961.070 [Startup:StartupSatComms:A] Stopped
2015-01-28T18:16:01.071Z,1422468961.071 [Startup:StartupSatComms:B] Running Loop=1
2015-01-28T18:16:01.496Z,1422468961.496 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-01-28T18:16:06.173Z,1422468966.173 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150128T175342/Courier0008.lzma
2015-01-28T18:16:06.970Z,1422468966.970 [DataOverHttps](INFO): Moved sent file to Logs/20150128T175342/Courier0008.lzma.bak
2015-01-28T18:16:06.970Z,1422468966.970 [DataOverHttps](INFO): SBD MOMSN=1510388
2015-01-28T18:16:10.965Z,1422468970.965 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:16:11.090Z,1422468971.090 [NAL9602](INFO): MT Sig Qty requested
2015-01-28T18:16:15.989Z,1422468975.989 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20150128T181158/Courier0000.lzma
2015-01-28T18:16:16.789Z,1422468976.789 [DataOverHttps](INFO): Moved sent file to Logs/20150128T181158/Courier0000.lzma.bak
2015-01-28T18:16:16.789Z,1422468976.789 [DataOverHttps](INFO): SBD MOMSN=1510390
2015-01-28T18:16:26.076Z,1422468986.076 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20150128T175342/Express0009.lzma
2015-01-28T18:16:26.874Z,1422468986.874 [DataOverHttps](INFO): Moved sent file to Logs/20150128T175342/Express0009.lzma.bak
2015-01-28T18:16:26.874Z,1422468986.874 [DataOverHttps](INFO): SBD MOMSN=1510395
2015-01-28T18:16:36.136Z,1422468996.136 [DataOverHttps](INFO): Sending 705 bytes from file Logs/20150128T181158/Express0001.lzma
2015-01-28T18:16:36.912Z,1422468996.912 [DataOverHttps](INFO): Moved sent file to Logs/20150128T181158/Express0001.lzma.bak
2015-01-28T18:16:36.912Z,1422468996.912 [DataOverHttps](INFO): SBD MOMSN=1510397
2015-01-28T18:16:37.792Z,1422468997.792 [Startup:StartupSatComms:B] Stopped
2015-01-28T18:16:37.793Z,1422468997.793 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2015-01-28T18:16:37.793Z,1422468997.793 [Startup:StartupSatComms] Stopped
2015-01-28T18:16:37.793Z,1422468997.793 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2015-01-28T18:16:37.794Z,1422468997.794 [Startup](INFO): Completed Startup
2015-01-28T18:16:37.794Z,1422468997.794 [Startup] Stopped
2015-01-28T18:16:37.794Z,1422468997.794 [Startup](INFO): Aggregate::uninitialize Startup
2015-01-28T18:16:37.794Z,1422468997.794 [Startup:A.GoToSurface] Stopped
2015-01-28T18:16:37.794Z,1422468997.794 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-01-28T18:16:37.916Z,1422468997.916 [MissionManager](IMPORTANT): Started mission Default
2015-01-28T18:16:37.916Z,1422468997.916 [Default] Running Loop=1
2015-01-28T18:16:37.916Z,1422468997.916 [Default](INFO): Aggregate::initialize Default
2015-01-28T18:16:37.917Z,1422468997.917 [Default:B.GoToSurface] Running Loop=1
2015-01-28T18:16:37.917Z,1422468997.917 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-01-28T18:16:37.917Z,1422468997.917 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-01-28T18:16:37.922Z,1422468997.922 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-01-28T18:16:37.922Z,1422468997.922 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2015-01-28T18:16:37.922Z,1422468997.922 [Default:StartClock] Running Loop=1
2015-01-28T18:16:37.922Z,1422468997.922 [Default:StartClock](INFO): Aggregate::initialize Default:StartClock
2015-01-28T18:16:37.922Z,1422468997.922 [Default:StartClock:A] Running Loop=1
2015-01-28T18:16:37.939Z,1422468997.939 [Default:StartClock:A] Stopped
2015-01-28T18:16:37.939Z,1422468997.939 [Default:StartClock](INFO): Completed Default:StartClock
2015-01-28T18:16:37.939Z,1422468997.939 [Default:StartClock] Stopped
2015-01-28T18:16:37.939Z,1422468997.939 [Default:StartClock](INFO): Aggregate::uninitialize Default:StartClock
2015-01-28T18:16:41.100Z,1422469001.100 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-01-28T18:16:41.615Z,1422469001.615 [NAL9602](FAULT): received:
2015-01-28T18:16:41.615Z,1422469001.615 [NAL9602] Data Fault, FailCount= 2
2015-01-28T18:16:41.615Z,1422469001.615 [NAL9602](ERROR): Data Fault
2015-01-28T18:16:41.671Z,1422469001.671 [Default:WaitAtTheSurface] Running Loop=1
2015-01-28T18:16:41.671Z,1422469001.671 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2015-01-28T18:16:41.671Z,1422469001.671 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-01-28T18:16:41.671Z,1422469001.671 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Initialize.
2015-01-28T18:16:41.679Z,1422469001.679 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:16:41.756Z,1422469001.756 [NAL9602](INFO): Stop
2015-01-28T18:16:41.756Z,1422469001.756 [NAL9602](INFO): Uninitialize
2015-01-28T18:16:41.757Z,1422469001.757 [NAL9602](INFO): Powering down
2015-01-28T18:16:41.778Z,1422469001.778 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Running Loop=1
2015-01-28T18:16:46.756Z,1422469006.756 [NAL9602](INFO): Stopping
2015-01-28T18:16:51.790Z,1422469011.790 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:16:51.790Z,1422469011.790 [NAL9602] No Fault, FailCount= 2
2015-01-28T18:16:56.764Z,1422469016.764 [NAL9602](INFO): Start
2015-01-28T18:16:56.764Z,1422469016.764 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:17:16.768Z,1422469036.768 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:17:16.768Z,1422469036.768 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:17:26.767Z,1422469046.767 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:17:31.774Z,1422469051.774 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:18:11.766Z,1422469091.766 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:18:16.757Z,1422469096.757 [NAL9602](INFO): MT Sig Qty requested
2015-01-28T18:18:36.824Z,1422469116.824 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:18:36.824Z,1422469116.824 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2015-01-28T18:18:36.824Z,1422469116.824 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:18:36.835Z,1422469116.835 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:18:41.828Z,1422469121.828 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:18:41.828Z,1422469121.828 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2015-01-28T18:18:46.768Z,1422469126.768 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2015-01-28T18:18:47.283Z,1422469127.283 [NAL9602](FAULT): received:
2015-01-28T18:18:47.283Z,1422469127.283 [NAL9602] Data Fault, FailCount= 3
2015-01-28T18:18:47.283Z,1422469127.283 [NAL9602](ERROR): Data Fault
2015-01-28T18:18:47.301Z,1422469127.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:18:47.328Z,1422469127.328 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:18:51.767Z,1422469131.767 [NAL9602](INFO): Stop
2015-01-28T18:18:51.767Z,1422469131.767 [NAL9602](INFO): Uninitialize
2015-01-28T18:18:51.768Z,1422469131.768 [NAL9602](INFO): Powering down
2015-01-28T18:18:51.787Z,1422469131.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:18:59.533Z,1422469139.533 [NAL9602](INFO): Stopping
2015-01-28T18:18:59.547Z,1422469139.547 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:18:59.547Z,1422469139.547 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:18:59.547Z,1422469139.547 [BPC1](ERROR): Communications Fault
2015-01-28T18:18:59.553Z,1422469139.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:18:59.596Z,1422469139.596 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:19:01.797Z,1422469141.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:19:01.808Z,1422469141.808 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:19:01.808Z,1422469141.808 [NAL9602] No Fault, FailCount= 3
2015-01-28T18:19:06.766Z,1422469146.766 [NAL9602](INFO): Start
2015-01-28T18:19:06.767Z,1422469146.767 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:19:06.786Z,1422469146.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:19:06.788Z,1422469146.788 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:19:06.788Z,1422469146.788 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:19:06.788Z,1422469146.788 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:19:06.791Z,1422469146.791 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:19:06.791Z,1422469146.791 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:19:11.793Z,1422469151.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:19:11.796Z,1422469151.796 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 2.481335 min
2015-01-28T18:19:11.798Z,1422469151.798 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:19:11.799Z,1422469151.799 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:19:11.799Z,1422469151.799 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:19:11.799Z,1422469151.799 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:19:11.808Z,1422469151.808 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:19:11.808Z,1422469151.808 [BPC1] No Fault, FailCount= 1
2015-01-28T18:19:16.841Z,1422469156.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:19:31.757Z,1422469171.757 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:19:31.758Z,1422469171.758 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:19:41.777Z,1422469181.777 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:19:46.778Z,1422469186.778 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:20:26.751Z,1422469226.751 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:20:31.751Z,1422469231.751 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2015-01-28T18:20:41.766Z,1422469241.766 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:20:46.805Z,1422469246.805 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T18:21:17.288Z,1422469277.288 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:21:41.803Z,1422469301.803 [Default:CheckIn] Running Loop=1
2015-01-28T18:21:41.803Z,1422469301.803 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T18:21:41.803Z,1422469301.803 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T18:21:41.803Z,1422469301.803 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T18:21:41.804Z,1422469301.804 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T18:21:41.804Z,1422469301.804 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-01-28T18:21:41.806Z,1422469301.806 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T18:21:47.393Z,1422469307.393 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:21:47.394Z,1422469307.394 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2015-01-28T18:21:47.394Z,1422469307.394 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:21:47.405Z,1422469307.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:21:47.801Z,1422469307.801 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:21:47.801Z,1422469307.801 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2015-01-28T18:21:48.199Z,1422469308.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:21:48.591Z,1422469308.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:21:48.966Z,1422469308.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:21:49.390Z,1422469309.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:21:49.798Z,1422469309.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:21:50.196Z,1422469310.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:21:50.591Z,1422469310.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:21:52.152Z,1422469312.152 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:21:55.794Z,1422469315.794 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:22:13.208Z,1422469333.208 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:22:13.208Z,1422469333.208 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:22:13.208Z,1422469333.208 [BPC1](ERROR): Communications Fault
2015-01-28T18:22:13.226Z,1422469333.226 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:22:14.428Z,1422469334.428 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:22:14.428Z,1422469334.428 [BPC1] No Fault, FailCount= 1
2015-01-28T18:22:26.504Z,1422469346.504 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:22:56.845Z,1422469376.845 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:22:57.642Z,1422469377.642 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:23:28.556Z,1422469408.556 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:23:51.512Z,1422469431.512 [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,FFFD%33
$B14,0A,FFFD%34
$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,3C2D,0A,FFFC,0B,FFFC%4E
$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,3C2C,0A,FFFD,0B,FFFC%3C
$B12,0C,0001,0D,0058,0E,0052,0F,13C2,10,16AC,11,FFFF,12,FFFF%3A
$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,FFFD%44
$B13,0C,0001,0D,0061,0E,0051,0F,1395,10,16AE,11,FFFF,12,FFFF%49
$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,3C25,0A,FFFD,0B,FFFD%48
$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,3C07,0A,0000,0B,0000%46
$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,0B8B,09,3C22,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,3C01,0A,0000,0B,0000%43
$B17,0C,0001,0D,0064,0E,005D,0F,1653,10,16E1,11,FFFF,12,FFFF%42
$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,3C05,0A,0000,0B,0000%42
$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:23:51.512Z,1422469431.512 [BPC1](FAULT): Failed to parse bank A battery data
2015-01-28T18:23:51.512Z,1422469431.512 [BPC1] Data Fault, FailCount= 1
2015-01-28T18:23:51.512Z,1422469431.512 [BPC1](ERROR): Data Fault
2015-01-28T18:23:51.530Z,1422469431.530 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:23:52.742Z,1422469432.742 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:23:52.742Z,1422469432.742 [BPC1] No Fault, FailCount= 1
2015-01-28T18:23:58.868Z,1422469438.868 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:23:59.658Z,1422469439.658 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:24:06.894Z,1422469446.894 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:24:06.894Z,1422469446.894 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:24:06.894Z,1422469446.894 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:24:06.895Z,1422469446.895 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:24:06.895Z,1422469446.895 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:24:07.338Z,1422469447.338 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 7.483049 min
2015-01-28T18:24:07.339Z,1422469447.339 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:24:07.339Z,1422469447.339 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:24:07.339Z,1422469447.339 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:24:07.339Z,1422469447.339 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:24:18.467Z,1422469458.467 [NAL9602](FAULT): GPS failed to acquire within timeout.
2015-01-28T18:24:18.467Z,1422469458.467 [NAL9602] Data Fault, FailCount= 4
2015-01-28T18:24:18.467Z,1422469458.467 [NAL9602](ERROR): Data Fault
2015-01-28T18:24:18.497Z,1422469458.497 [CBIT](ERROR): Data Fault in component: NAL9602
2015-01-28T18:24:18.876Z,1422469458.876 [NAL9602](INFO): Stop
2015-01-28T18:24:18.876Z,1422469458.876 [NAL9602](INFO): Uninitialize
2015-01-28T18:24:18.876Z,1422469458.876 [NAL9602](INFO): Powering down
2015-01-28T18:24:19.268Z,1422469459.268 [NAL9602](INFO): Stopping
2015-01-28T18:24:19.696Z,1422469459.696 [CBIT](INFO): Clearing failed state for component NAL9602
2015-01-28T18:24:19.696Z,1422469459.696 [NAL9602] No Fault, FailCount= 4
2015-01-28T18:24:20.073Z,1422469460.073 [NAL9602](INFO): Start
2015-01-28T18:24:20.465Z,1422469460.465 [NAL9602](INFO): Start
2015-01-28T18:24:20.866Z,1422469460.866 [NAL9602](INFO): Start
2015-01-28T18:24:21.267Z,1422469461.267 [NAL9602](INFO): Start
2015-01-28T18:24:21.656Z,1422469461.656 [NAL9602](INFO): Start
2015-01-28T18:24:22.069Z,1422469462.069 [NAL9602](INFO): Start
2015-01-28T18:24:22.468Z,1422469462.468 [NAL9602](INFO): Start
2015-01-28T18:24:22.858Z,1422469462.858 [NAL9602](INFO): Start
2015-01-28T18:24:23.271Z,1422469463.271 [NAL9602](INFO): Start
2015-01-28T18:24:23.666Z,1422469463.666 [NAL9602](INFO): Start
2015-01-28T18:24:24.067Z,1422469464.067 [NAL9602](INFO): Start
2015-01-28T18:24:24.067Z,1422469464.067 [NAL9602](INFO): Powering up NAL9602
2015-01-28T18:24:35.277Z,1422469475.277 [NAL9602](INFO): ************** CMDMODE **************
2015-01-28T18:24:35.278Z,1422469475.278 [NAL9602](INFO): NAL9602 initialized
2015-01-28T18:24:36.051Z,1422469476.051 [NAL9602](INFO): ************** GetFix **************
2015-01-28T18:24:48.486Z,1422469488.486 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:24:48.487Z,1422469488.487 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2015-01-28T18:24:48.487Z,1422469488.487 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:24:48.497Z,1422469488.497 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:24:48.903Z,1422469488.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:24:48.903Z,1422469488.903 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2015-01-28T18:24:49.285Z,1422469489.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:24:49.685Z,1422469489.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:24:50.085Z,1422469490.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:24:50.496Z,1422469490.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:24:50.885Z,1422469490.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:24:51.293Z,1422469491.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:24:51.686Z,1422469491.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:25:06.611Z,1422469506.611 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:25:07.411Z,1422469507.411 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:25:38.318Z,1422469538.318 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:25:51.651Z,1422469551.651 [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,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,3C0E,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,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,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,FFFD%48
$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,0B98,09,3C08,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,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,3C0B,0A,0000,0B,0000%3E
$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:25:51.651Z,1422469551.651 [BPC1](FAULT): Failed to parse bank B battery data
2015-01-28T18:25:51.652Z,1422469551.652 [BPC1] Data Fault, FailCount= 1
2015-01-28T18:25:51.652Z,1422469551.652 [BPC1](ERROR): Data Fault
2015-01-28T18:25:51.669Z,1422469551.669 [CBIT](ERROR): Data Fault in component: BPC1
2015-01-28T18:25:52.890Z,1422469552.890 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:25:52.890Z,1422469552.890 [BPC1] No Fault, FailCount= 1
2015-01-28T18:26:08.566Z,1422469568.566 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:26:09.368Z,1422469569.368 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:26:40.278Z,1422469600.278 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:26:42.060Z,1422469602.060 [Default:CheckIn:Read_GPS](INFO): Timed out from 2015-01-28T18:21:41.8Z
2015-01-28T18:26:42.060Z,1422469602.060 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T18:26:42.060Z,1422469602.060 [Default:CheckIn:Read_Iridium] Running Loop=1
2015-01-28T18:26:42.460Z,1422469602.460 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2015-01-28T18:26:46.566Z,1422469606.566 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20150128T181158/Courier0004.lzma
2015-01-28T18:26:47.372Z,1422469607.372 [DataOverHttps](INFO): Moved sent file to Logs/20150128T181158/Courier0004.lzma.bak
2015-01-28T18:26:47.372Z,1422469607.372 [DataOverHttps](INFO): SBD MOMSN=1510414
2015-01-28T18:26:56.526Z,1422469616.526 [DataOverHttps](INFO): Sending 540 bytes from file Logs/20150128T181158/Express0005.lzma
2015-01-28T18:26:57.318Z,1422469617.318 [DataOverHttps](INFO): Moved sent file to Logs/20150128T181158/Express0005.lzma.bak
2015-01-28T18:26:57.318Z,1422469617.318 [DataOverHttps](INFO): SBD MOMSN=1510416
2015-01-28T18:26:58.159Z,1422469618.159 [Default:CheckIn:Read_Iridium] Stopped
2015-01-28T18:26:58.159Z,1422469618.159 [Default:CheckIn](INFO): Completed Default:CheckIn
2015-01-28T18:26:58.159Z,1422469618.159 [Default:CheckIn] Stopped
2015-01-28T18:26:58.159Z,1422469618.159 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T18:26:58.159Z,1422469618.159 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T18:26:58.159Z,1422469618.159 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T18:27:13.304Z,1422469633.304 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:27:23.283Z,1422469643.283 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:27:53.370Z,1422469673.370 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:27:53.370Z,1422469673.370 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2015-01-28T18:27:53.370Z,1422469673.370 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:27:53.404Z,1422469673.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:27:58.797Z,1422469678.797 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:27:58.850Z,1422469678.850 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:27:58.850Z,1422469678.850 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2015-01-28T18:28:03.331Z,1422469683.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:28:11.084Z,1422469691.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:28:13.333Z,1422469693.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:28:18.343Z,1422469698.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:28:23.304Z,1422469703.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:28:28.299Z,1422469708.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:28:33.284Z,1422469713.284 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:28:33.380Z,1422469713.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:28:43.283Z,1422469723.283 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:29:03.300Z,1422469743.300 [BPC1](DEBUG): Received data from all battery sticks
2015-01-28T18:29:08.307Z,1422469748.307 [Default:UpdateAndReportMinutesSinceMissionStarted] Running Loop=1
2015-01-28T18:29:08.307Z,1422469748.307 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::initialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:29:08.308Z,1422469748.308 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Running Loop=1
2015-01-28T18:29:08.308Z,1422469748.308 [Default:UpdateAndReportMinutesSinceMissionStarted:A] Stopped
2015-01-28T18:29:08.308Z,1422469748.308 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Running Loop=1
2015-01-28T18:29:16.574Z,1422469756.574 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:29:16.604Z,1422469756.604 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:29:16.605Z,1422469756.605 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:29:16.605Z,1422469756.605 [BPC1](ERROR): Communications Fault
2015-01-28T18:29:16.613Z,1422469756.613 [Default:UpdateAndReportMinutesSinceMissionStarted:B](IMPORTANT): Default mission has been running for 12.506664 min
2015-01-28T18:29:16.614Z,1422469756.614 [Default:UpdateAndReportMinutesSinceMissionStarted:B] Stopped
2015-01-28T18:29:16.614Z,1422469756.614 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Completed Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:29:16.614Z,1422469756.614 [Default:UpdateAndReportMinutesSinceMissionStarted] Stopped
2015-01-28T18:29:16.614Z,1422469756.614 [Default:UpdateAndReportMinutesSinceMissionStarted](INFO): Aggregate::uninitialize Default:UpdateAndReportMinutesSinceMissionStarted
2015-01-28T18:29:16.660Z,1422469756.660 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:29:28.319Z,1422469768.319 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:29:28.319Z,1422469768.319 [BPC1] No Fault, FailCount= 1
2015-01-28T18:29:53.285Z,1422469793.285 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:30:03.288Z,1422469803.288 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:30:38.789Z,1422469838.789 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:30:38.820Z,1422469838.820 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:30:38.820Z,1422469838.820 [BPC1] Communications Fault, FailCount= 1
2015-01-28T18:30:38.821Z,1422469838.821 [BPC1](ERROR): Communications Fault
2015-01-28T18:30:38.862Z,1422469838.862 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:30:53.314Z,1422469853.314 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:30:53.314Z,1422469853.314 [BPC1] No Fault, FailCount= 1
2015-01-28T18:31:03.336Z,1422469863.336 [DeadReckonUsingMultipleVelocitySources](FAULT): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-28T18:31:03.336Z,1422469863.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2015-01-28T18:31:03.336Z,1422469863.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2015-01-28T18:31:03.347Z,1422469863.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2015-01-28T18:31:08.315Z,1422469868.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2015-01-28T18:31:08.315Z,1422469868.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2015-01-28T18:31:13.307Z,1422469873.307 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-28T18:31:21.097Z,1422469881.097 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:31:21.111Z,1422469881.111 [BPC1](FAULT): Failed to receive battery data
2015-01-28T18:31:21.111Z,1422469881.111 [BPC1] Communications Fault, FailCount= 2
2015-01-28T18:31:21.111Z,1422469881.111 [BPC1](ERROR): Communications Fault
2015-01-28T18:31:21.118Z,1422469881.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:31:21.149Z,1422469881.149 [CBIT](ERROR): Communications Fault in component: BPC1
2015-01-28T18:31:23.308Z,1422469883.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:31:28.276Z,1422469888.276 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:31:28.318Z,1422469888.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:31:33.307Z,1422469893.307 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:31:33.318Z,1422469893.318 [CBIT](INFO): Clearing failed state for component BPC1
2015-01-28T18:31:33.318Z,1422469893.318 [BPC1] No Fault, FailCount= 2
2015-01-28T18:31:38.358Z,1422469898.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:31:43.386Z,1422469903.386 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-28T18:31:49.991Z,1422469909.991 [CommandLine](IMPORTANT): got command report
2015-01-28T18:31:58.346Z,1422469918.346 [Default:CheckIn] Running Loop=1
2015-01-28T18:31:58.347Z,1422469918.347 [Default:CheckIn](INFO): Aggregate::initialize Default:CheckIn
2015-01-28T18:31:58.347Z,1422469918.347 [Default:CheckIn:A.SetSpeed] Running Loop=1
2015-01-28T18:31:58.347Z,1422469918.347 [Default:CheckIn:A.SetSpeed](DEBUG): Initialize.
2015-01-28T18:31:58.347Z,1422469918.347 [Default:CheckIn:Read_GPS] Running Loop=1
2015-01-28T18:31:59.179Z,1422469919.179 [NAL9602](ERROR): getFix uart error: serial timeout
2015-01-28T18:32:02.923Z,1422469922.923 [CommandLine](IMPORTANT): got command get latitude
2015-01-28T18:32:02.923Z,1422469922.923 [CommandLine](FAULT): Element has no value
2015-01-28T18:32:29.439Z,1422469949.439 [NAL9602](ERROR): parseGSV uart error: serial timeout
2015-01-28T18:32:30.244Z,1422469950.244 [NAL9602](INFO): ************** Request Fix **************
2015-01-28T18:32:30.520Z,1422469950.520 [CommandLine](IMPORTANT): got command restart system
2015-01-28T18:32:36.360Z,1422469956.360 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T18:32:36.360Z,1422469956.360 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.484Z,1422469956.484 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2015-01-28T18:32:36.484Z,1422469956.484 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.485Z,1422469956.485 [CommandLine](INFO): Join timeout helper Thread ID is 875
2015-01-28T18:32:36.486Z,1422469956.486 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-01-28T18:32:36.486Z,1422469956.486 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.486Z,1422469956.486 [NavChartDb](INFO): Join timeout helper Thread ID is 876
2015-01-28T18:32:36.521Z,1422469956.521 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T18:32:36.521Z,1422469956.521 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.525Z,1422469956.525 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-01-28T18:32:36.525Z,1422469956.525 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.525Z,1422469956.525 [Radio_Surface](INFO): Join timeout helper Thread ID is 877
2015-01-28T18:32:36.581Z,1422469956.581 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T18:32:36.581Z,1422469956.581 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.597Z,1422469956.597 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-01-28T18:32:36.597Z,1422469956.597 [logger ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.597Z,1422469956.597 [logger](INFO): Join timeout helper Thread ID is 878
2015-01-28T18:32:36.625Z,1422469956.625 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T18:32:36.625Z,1422469956.625 [logger ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.637Z,1422469956.637 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-01-28T18:32:36.637Z,1422469956.637 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.637Z,1422469956.637 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-01-28T18:32:36.637Z,1422469956.637 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.638Z,1422469956.638 [controlThread](INFO): Join timeout helper Thread ID is 879
2015-01-28T18:32:36.679Z,1422469956.679 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-28T18:32:36.679Z,1422469956.679 [controlThread](DEBUG): Uninitializing ControlThread
2015-01-28T18:32:36.681Z,1422469956.681 [NAL9602](INFO): Uninitialize
2015-01-28T18:32:36.681Z,1422469956.681 [NAL9602](INFO): Powering down
2015-01-28T18:32:36.683Z,1422469956.683 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-01-28T18:32:36.684Z,1422469956.684 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-01-28T18:32:36.684Z,1422469956.684 [Default] Stopped
2015-01-28T18:32:36.685Z,1422469956.685 [Default](INFO): Aggregate::uninitialize Default
2015-01-28T18:32:36.685Z,1422469956.685 [Default:B.GoToSurface] Stopped
2015-01-28T18:32:36.685Z,1422469956.685 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-01-28T18:32:36.685Z,1422469956.685 [Default:CheckIn] Stopped
2015-01-28T18:32:36.685Z,1422469956.685 [Default:CheckIn](INFO): Aggregate::uninitialize Default:CheckIn
2015-01-28T18:32:36.685Z,1422469956.685 [Default:CheckIn:A.SetSpeed] Stopped
2015-01-28T18:32:36.685Z,1422469956.685 [Default:CheckIn:A.SetSpeed](DEBUG): Uninitialize.
2015-01-28T18:32:36.685Z,1422469956.685 [Default:CheckIn:Read_GPS] Stopped
2015-01-28T18:32:36.685Z,1422469956.685 [Default:WaitAtTheSurface] Stopped
2015-01-28T18:32:36.686Z,1422469956.686 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2015-01-28T18:32:36.686Z,1422469956.686 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed] Stopped
2015-01-28T18:32:36.686Z,1422469956.686 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2015-01-28T18:32:36.689Z,1422469956.689 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-01-28T18:32:36.690Z,1422469956.690 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-01-28T18:32:36.690Z,1422469956.690 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-01-28T18:32:36.690Z,1422469956.690 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-01-28T18:32:36.691Z,1422469956.691 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-01-28T18:32:36.691Z,1422469956.691 [BuoyancyServo](INFO): Powering down
2015-01-28T18:32:36.705Z,1422469956.705 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-01-28T18:32:36.705Z,1422469956.705 [ElevatorServo](INFO): Powering down
2015-01-28T18:32:36.706Z,1422469956.706 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-01-28T18:32:36.706Z,1422469956.706 [RudderServo](INFO): Powering down
2015-01-28T18:32:36.707Z,1422469956.707 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-01-28T18:32:36.707Z,1422469956.707 [ThrusterServo](INFO): Powering down
2015-01-28T18:32:36.708Z,1422469956.708 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-01-28T18:32:36.708Z,1422469956.708 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-01-28T18:32:36.708Z,1422469956.708 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-01-28T18:32:36.742Z,1422469956.742 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.864Z,1422469956.864 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.911Z,1422469956.911 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-28T18:32:36.967Z,1422469956.967 [logger ThreadHandler](INFO): Thread cancelled.