2014-09-13T00:51:02.038Z,1410569462.038 [Supervisor](DEBUG): Initializing supervisor.
2014-09-13T00:51:02.041Z,1410569462.041 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-09-13T00:51:02.042Z,1410569462.042 [SyncHandler](INFO): Protected caller Thread ID is 5853
2014-09-13T00:51:02.042Z,1410569462.042 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-09-13T00:51:02.043Z,1410569462.043 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-09-13T00:51:02.044Z,1410569462.044 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5854
2014-09-13T00:51:02.047Z,1410569462.047 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-09-13T00:51:02.069Z,1410569462.069 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-09-13T00:51:02.070Z,1410569462.070 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-09-13T00:51:02.070Z,1410569462.070 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5855
2014-09-13T00:51:02.071Z,1410569462.071 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-09-13T00:51:02.072Z,1410569462.072 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-09-13T00:51:02.073Z,1410569462.073 [logger ThreadHandler](INFO): Protected caller Thread ID is 5856
2014-09-13T00:51:02.075Z,1410569462.075 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-09-13T00:51:02.075Z,1410569462.075 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-09-13T00:51:02.077Z,1410569462.077 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-09-13T00:51:02.414Z,1410569462.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-09-13T00:51:02.415Z,1410569462.415 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-09-13T00:51:02.575Z,1410569462.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-09-13T00:51:02.576Z,1410569462.576 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-09-13T00:51:02.688Z,1410569462.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-09-13T00:51:02.688Z,1410569462.688 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-09-13T00:51:02.814Z,1410569462.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-09-13T00:51:02.815Z,1410569462.815 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-09-13T00:51:02.902Z,1410569462.902 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-09-13T00:51:03.049Z,1410569463.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-09-13T00:51:03.049Z,1410569463.049 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-09-13T00:51:03.137Z,1410569463.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-09-13T00:51:03.138Z,1410569463.138 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-09-13T00:51:03.342Z,1410569463.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-09-13T00:51:03.343Z,1410569463.343 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-09-13T00:51:03.657Z,1410569463.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-09-13T00:51:03.658Z,1410569463.658 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-09-13T00:51:03.965Z,1410569463.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-09-13T00:51:03.966Z,1410569463.966 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-09-13T00:51:04.471Z,1410569464.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-09-13T00:51:04.472Z,1410569464.472 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-09-13T00:51:04.665Z,1410569464.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-09-13T00:51:04.666Z,1410569464.666 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-09-13T00:51:04.774Z,1410569464.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-09-13T00:51:04.775Z,1410569464.775 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-09-13T00:51:05.196Z,1410569465.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-09-13T00:51:05.197Z,1410569465.197 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-09-13T00:51:05.310Z,1410569465.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-09-13T00:51:05.312Z,1410569465.312 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2014-09-13T00:51:05.313Z,1410569465.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2014-09-13T00:51:05.559Z,1410569465.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2014-09-13T00:51:05.559Z,1410569465.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2014-09-13T00:51:05.679Z,1410569465.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2014-09-13T00:51:05.778Z,1410569465.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2014-09-13T00:51:05.877Z,1410569465.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2014-09-13T00:51:05.975Z,1410569465.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2014-09-13T00:51:06.117Z,1410569466.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2014-09-13T00:51:06.282Z,1410569466.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2014-09-13T00:51:06.388Z,1410569466.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2014-09-13T00:51:06.476Z,1410569466.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2014-09-13T00:51:06.570Z,1410569466.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2014-09-13T00:51:06.669Z,1410569466.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2014-09-13T00:51:06.840Z,1410569466.840 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/LOGIN/
2014-09-13T00:51:06.840Z,1410569466.840 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-09-13T00:51:06.842Z,1410569466.842 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-09-13T00:51:06.893Z,1410569466.893 [VerticalControl](DEBUG): Construct VerticalControl.
2014-09-13T00:51:07.009Z,1410569467.009 [VerticalControl] Loaded
2014-09-13T00:51:07.010Z,1410569467.010 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-09-13T00:51:07.010Z,1410569467.010 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-09-13T00:51:07.080Z,1410569467.080 [HorizontalControl] Loaded
2014-09-13T00:51:07.080Z,1410569467.080 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-09-13T00:51:07.081Z,1410569467.081 [SpeedControl](DEBUG): Construct SpeedControl.
2014-09-13T00:51:07.086Z,1410569467.086 [SpeedControl] Loaded
2014-09-13T00:51:07.087Z,1410569467.087 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-09-13T00:51:07.088Z,1410569467.088 [LoopControl](DEBUG): Construct LoopControl.
2014-09-13T00:51:07.088Z,1410569467.088 [LoopControl] Loaded
2014-09-13T00:51:07.088Z,1410569467.088 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-09-13T00:51:07.089Z,1410569467.089 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-09-13T00:51:07.089Z,1410569467.089 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-09-13T00:51:07.183Z,1410569467.183 [SBIT](DEBUG): Construct Startup Built In Test.
2014-09-13T00:51:07.194Z,1410569467.194 [SBIT] Loaded
2014-09-13T00:51:07.194Z,1410569467.194 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-09-13T00:51:07.195Z,1410569467.195 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-09-13T00:51:07.224Z,1410569467.224 [IBIT] Loaded
2014-09-13T00:51:07.225Z,1410569467.225 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-09-13T00:51:07.227Z,1410569467.227 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-09-13T00:51:07.346Z,1410569467.346 [CBIT] Loaded
2014-09-13T00:51:07.346Z,1410569467.346 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-09-13T00:51:07.347Z,1410569467.347 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-09-13T00:51:07.348Z,1410569467.348 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-09-13T00:51:07.366Z,1410569467.366 [DepthRateCalculator] Loaded
2014-09-13T00:51:07.366Z,1410569467.366 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-09-13T00:51:07.372Z,1410569467.372 [PitchRateCalculator] Loaded
2014-09-13T00:51:07.372Z,1410569467.372 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-09-13T00:51:07.383Z,1410569467.383 [SpeedCalculator] Loaded
2014-09-13T00:51:07.384Z,1410569467.384 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-09-13T00:51:07.400Z,1410569467.400 [TempGradientCalculator] Loaded
2014-09-13T00:51:07.400Z,1410569467.400 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-09-13T00:51:07.405Z,1410569467.405 [YawRateCalculator] Loaded
2014-09-13T00:51:07.405Z,1410569467.405 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-09-13T00:51:07.406Z,1410569467.406 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-09-13T00:51:07.406Z,1410569467.406 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-09-13T00:51:08.541Z,1410569468.541 [HFRCMReconstructedInterpolator] Loaded
2014-09-13T00:51:08.541Z,1410569468.541 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-09-13T00:51:08.542Z,1410569468.542 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-09-13T00:51:08.543Z,1410569468.543 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-09-13T00:51:08.766Z,1410569468.766 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-09-13T00:51:08.767Z,1410569468.767 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-09-13T00:51:08.847Z,1410569468.847 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-09-13T00:51:08.847Z,1410569468.847 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-09-13T00:51:08.900Z,1410569468.900 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-09-13T00:51:08.901Z,1410569468.901 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-09-13T00:51:08.945Z,1410569468.945 [DeadReckonWithRespectToWater] Loaded
2014-09-13T00:51:08.945Z,1410569468.945 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-09-13T00:51:08.990Z,1410569468.990 [DeadReckonWithRespectToSeafloor] Loaded
2014-09-13T00:51:08.990Z,1410569468.990 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-09-13T00:51:09.049Z,1410569469.049 [DeadReckonUsingDVLWaterTrack] Loaded
2014-09-13T00:51:09.049Z,1410569469.049 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-09-13T00:51:09.066Z,1410569469.066 [NavChart] Loaded
2014-09-13T00:51:09.066Z,1410569469.066 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-09-13T00:51:09.071Z,1410569469.071 [UniversalFixResidualReporter] Loaded
2014-09-13T00:51:09.071Z,1410569469.071 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-09-13T00:51:09.071Z,1410569469.071 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-09-13T00:51:09.072Z,1410569469.072 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-09-13T00:51:09.078Z,1410569469.078 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-09-13T00:51:09.078Z,1410569469.078 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-09-13T00:51:09.245Z,1410569469.245 [rhodamine] Loaded
2014-09-13T00:51:09.245Z,1410569469.245 [ComponentRegistry](DEBUG): SyncComponent "rhodamine" handled in the control thread.
2014-09-13T00:51:09.246Z,1410569469.246 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-09-13T00:51:09.247Z,1410569469.247 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-09-13T00:51:09.535Z,1410569469.535 [AHRS_sp3003D] Loaded
2014-09-13T00:51:09.536Z,1410569469.536 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-09-13T00:51:09.606Z,1410569469.606 [DataOverHttps] Loaded
2014-09-13T00:51:09.606Z,1410569469.606 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-09-13T00:51:09.620Z,1410569469.620 [Depth_Keller] Loaded
2014-09-13T00:51:09.620Z,1410569469.620 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-09-13T00:51:09.625Z,1410569469.625 [DropWeight] Loaded
2014-09-13T00:51:09.625Z,1410569469.625 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-09-13T00:51:09.755Z,1410569469.755 [DVL_micro] Loaded
2014-09-13T00:51:09.755Z,1410569469.755 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-09-13T00:51:09.849Z,1410569469.849 [NAL9602] Loaded
2014-09-13T00:51:09.850Z,1410569469.850 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-09-13T00:51:09.890Z,1410569469.890 [Onboard] Loaded
2014-09-13T00:51:09.891Z,1410569469.891 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-09-13T00:51:11.777Z,1410569471.777 [BPC1] Loaded
2014-09-13T00:51:11.777Z,1410569471.777 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2014-09-13T00:51:11.778Z,1410569471.778 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-09-13T00:51:11.779Z,1410569471.779 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-09-13T00:51:11.876Z,1410569471.876 [BuoyancyServo] Loaded
2014-09-13T00:51:11.876Z,1410569471.876 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-09-13T00:51:11.888Z,1410569471.888 [ElevatorServo] Loaded
2014-09-13T00:51:11.888Z,1410569471.888 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-09-13T00:51:11.900Z,1410569471.900 [MassServo] Loaded
2014-09-13T00:51:11.900Z,1410569471.900 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-09-13T00:51:11.912Z,1410569471.912 [RudderServo] Loaded
2014-09-13T00:51:11.913Z,1410569471.913 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-09-13T00:51:11.924Z,1410569471.924 [ThrusterServo] Loaded
2014-09-13T00:51:11.924Z,1410569471.924 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-09-13T00:51:11.925Z,1410569471.925 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-09-13T00:51:11.925Z,1410569471.925 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-09-13T00:51:12.165Z,1410569472.165 [ExternalSim] Loaded
2014-09-13T00:51:12.166Z,1410569472.166 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2014-09-13T00:51:12.219Z,1410569472.219 [InternalSim] Loaded
2014-09-13T00:51:12.219Z,1410569472.219 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-09-13T00:51:12.220Z,1410569472.220 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-09-13T00:51:12.221Z,1410569472.221 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-09-13T00:51:12.244Z,1410569472.244 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-09-13T00:51:12.246Z,1410569472.246 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-09-13T00:51:12.247Z,1410569472.247 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-09-13T00:51:12.254Z,1410569472.254 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-09-13T00:51:12.255Z,1410569472.255 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408684E0
2014-09-13T00:51:12.255Z,1410569472.255 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5938
2014-09-13T00:51:12.260Z,1410569472.260 [Supervisor](INFO): Main Thread ID is 5852
2014-09-13T00:51:12.260Z,1410569472.260 [Supervisor](DEBUG): Running supervisor.
2014-09-13T00:51:12.261Z,1410569472.261 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5939
2014-09-13T00:51:12.263Z,1410569472.263 [controlThread ThreadHandler](INFO): Handler Thread ID is 5940
2014-09-13T00:51:12.264Z,1410569472.264 [controlThread](DEBUG): Initializing ControlThread
2014-09-13T00:51:12.265Z,1410569472.265 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-09-13T00:51:12.267Z,1410569472.267 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-09-13T00:51:12.267Z,1410569472.267 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-09-13T00:51:12.268Z,1410569472.268 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-09-13T00:51:12.268Z,1410569472.268 [SBIT](INFO): Initialize SBIT Component.
2014-09-13T00:51:12.269Z,1410569472.269 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11616
2014-09-13T00:51:12.269Z,1410569472.269 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-09-13T00:51:12.269Z,1410569472.269 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-09-13T00:51:12.270Z,1410569472.270 [IBIT](INFO): Initialize IBIT Component.
2014-09-13T00:51:12.271Z,1410569472.271 [CBIT](DEBUG): Initialize CBIT Component.
2014-09-13T00:51:12.271Z,1410569472.271 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-09-13T00:51:12.272Z,1410569472.272 [logger ThreadHandler](INFO): Handler Thread ID is 5941
2014-09-13T00:51:12.292Z,1410569472.292 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5942
2014-09-13T00:51:12.294Z,1410569472.294 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2014-09-13T00:51:12.298Z,1410569472.298 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-13T00:51:12.299Z,1410569472.299 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-09-13T00:51:12.299Z,1410569472.299 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-09-13T00:51:12.299Z,1410569472.299 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-09-13T00:51:12.300Z,1410569472.300 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-09-13T00:51:12.301Z,1410569472.301 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-09-13T00:51:12.303Z,1410569472.303 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-09-13T00:51:12.303Z,1410569472.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-13T00:51:12.304Z,1410569472.304 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-09-13T00:51:12.304Z,1410569472.304 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-09-13T00:51:12.305Z,1410569472.305 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-09-13T00:51:12.305Z,1410569472.305 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-09-13T00:51:12.305Z,1410569472.305 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-09-13T00:51:12.312Z,1410569472.312 [ExternalSim](INFO): ExternalSim initializing...
2014-09-13T00:51:12.329Z,1410569472.329 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-09-13T00:51:12.331Z,1410569472.331 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2014-09-13T00:51:12.332Z,1410569472.332 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2014-09-13T00:51:12.334Z,1410569472.334 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2014-09-13T00:51:12.336Z,1410569472.336 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2014-09-13T00:51:12.337Z,1410569472.337 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2014-09-13T00:51:12.339Z,1410569472.339 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2014-09-13T00:51:12.341Z,1410569472.341 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2014-09-13T00:51:12.343Z,1410569472.343 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2014-09-13T00:51:12.528Z,1410569472.528 [NavChartDb](INFO): Setup scan of Resources/US5CA83M.000
2014-09-13T00:51:32.420Z,1410569492.420 [ExternalSim](ERROR): Error making socket connection to simulation server at tellum.shore.mbari.org: Can not connect to invalid network address: tellum.shore.mbari.org
2014-09-13T00:51:32.462Z,1410569492.462 [ExternalSim](DEBUG): beaconLat = 36.81340
2014-09-13T00:51:32.462Z,1410569492.462 [ExternalSim](DEBUG): beaconLon = -121.82240
2014-09-13T00:51:32.462Z,1410569492.462 [ExternalSim](DEBUG): beaconDepth = 25.00
2014-09-13T00:51:32.465Z,1410569492.465 [ExternalSim](DEBUG): Simulator initialized
2014-09-13T00:51:32.468Z,1410569492.468 [InternalSim](DEBUG): InternalSim initializing...
2014-09-13T00:51:32.513Z,1410569492.513 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-09-13T00:51:32.523Z,1410569492.523 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-13T00:51:32.533Z,1410569492.533 [MissionManager](DEBUG):
2014-09-13T00:51:32.534Z,1410569492.533 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-09-13T00:51:32.602Z,1410569492.602 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-09-13T00:51:32.606Z,1410569492.606 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-09-13T00:51:32.611Z,1410569492.611 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-09-13T00:51:32.621Z,1410569492.621 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-09-13T00:51:32.627Z,1410569492.627 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-09-13T00:51:32.636Z,1410569492.636 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-09-13T00:51:32.667Z,1410569492.667 [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
2014-09-13T00:51:32.673Z,1410569492.673 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalSim,rhodamine,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-09-13T00:51:32.732Z,1410569492.732 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-09-13T00:51:32.859Z,1410569492.859 [DVL_micro](INFO): Initializing
2014-09-13T00:51:32.883Z,1410569492.883 [NAL9602](INFO): Powering up NAL9602
2014-09-13T00:51:33.000Z,1410569493.000 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:33.006Z,1410569493.006 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.014Z,1410569493.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.061Z,1410569493.061 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.067Z,1410569493.067 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.084Z,1410569493.084 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.164Z,1410569493.164 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:51:33.172Z,1410569493.172 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-13T00:51:33.178Z,1410569493.178 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:51:33.184Z,1410569493.184 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-09-13T00:51:33.190Z,1410569493.190 [MassServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:51:33.196Z,1410569493.196 [MassServo](DEBUG): Initializing MassServo.
2014-09-13T00:51:33.202Z,1410569493.202 [RudderServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:51:33.208Z,1410569493.208 [RudderServo](DEBUG): Initializing RudderServo.
2014-09-13T00:51:33.214Z,1410569493.214 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:51:33.220Z,1410569493.220 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-09-13T00:51:33.342Z,1410569493.342 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:33.342Z,1410569493.342 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.344Z,1410569493.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.353Z,1410569493.353 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.354Z,1410569493.354 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.355Z,1410569493.355 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.371Z,1410569493.371 [SBIT](IMPORTANT): Beginning Startup BIT
2014-09-13T00:51:33.378Z,1410569493.378 [CBIT](IMPORTANT): Beginning GF scan
2014-09-13T00:51:33.812Z,1410569493.812 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:33.813Z,1410569493.813 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.814Z,1410569493.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.815Z,1410569493.815 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.816Z,1410569493.816 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:33.816Z,1410569493.816 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-09-13T00:51:34.145Z,1410569494.145 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-09-13T00:51:34.146Z,1410569494.146 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-09-13T00:51:34.146Z,1410569494.146 [BuoyancyServo] Communications Fault, FailCount= 1
2014-09-13T00:51:34.146Z,1410569494.146 [BuoyancyServo](ERROR): Communications Fault
2014-09-13T00:51:34.360Z,1410569494.360 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-09-13T00:51:34.720Z,1410569494.720 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:34.731Z,1410569494.731 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-13T00:51:34.733Z,1410569494.733 [BuoyancyServo](INFO): Powering down
2014-09-13T00:51:35.165Z,1410569495.165 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:35.579Z,1410569495.579 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:35.972Z,1410569495.972 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:36.465Z,1410569496.465 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:36.881Z,1410569496.881 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:37.077Z,1410569497.077 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-09-13T00:51:37.077Z,1410569497.077 [BuoyancyServo] No Fault, FailCount= 1
2014-09-13T00:51:37.432Z,1410569497.432 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:37.442Z,1410569497.442 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-09-13T00:51:37.564Z,1410569497.564 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-09-13T00:51:38.164Z,1410569498.164 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:38.693Z,1410569498.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:39.172Z,1410569499.172 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:39.638Z,1410569499.638 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:40.091Z,1410569500.091 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:40.713Z,1410569500.713 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:41.233Z,1410569501.233 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:41.693Z,1410569501.693 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:42.126Z,1410569502.126 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:42.539Z,1410569502.539 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:42.972Z,1410569502.972 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:43.466Z,1410569503.466 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-13T00:51:43.466Z,1410569503.466 [DVL_micro] Communications Fault, FailCount= 1
2014-09-13T00:51:43.466Z,1410569503.466 [DVL_micro](ERROR): Communications Fault
2014-09-13T00:51:43.512Z,1410569503.512 [NAL9602](INFO): NAL9602 initialized
2014-09-13T00:51:43.534Z,1410569503.534 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:43.576Z,1410569503.576 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-13T00:51:43.993Z,1410569503.993 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:51:44.075Z,1410569504.075 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:44.497Z,1410569504.497 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:44.919Z,1410569504.919 [DVL_micro](INFO): Initializing
2014-09-13T00:51:44.949Z,1410569504.949 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:45.006Z,1410569505.006 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-13T00:51:45.006Z,1410569505.006 [DVL_micro] No Fault, FailCount= 1
2014-09-13T00:51:45.443Z,1410569505.443 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:45.887Z,1410569505.887 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:46.284Z,1410569506.284 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:46.727Z,1410569506.727 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:47.136Z,1410569507.136 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:47.594Z,1410569507.594 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:48.030Z,1410569508.030 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:48.450Z,1410569508.450 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:48.843Z,1410569508.843 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:49.357Z,1410569509.357 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:49.837Z,1410569509.837 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:50.287Z,1410569510.287 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:50.757Z,1410569510.757 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:51.240Z,1410569511.240 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:51.698Z,1410569511.698 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:52.149Z,1410569512.149 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:52.587Z,1410569512.587 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:53.055Z,1410569513.055 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:53.541Z,1410569513.541 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:54.037Z,1410569514.037 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:54.603Z,1410569514.603 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:55.246Z,1410569515.246 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:55.669Z,1410569515.669 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-13T00:51:55.669Z,1410569515.669 [DVL_micro] Communications Fault, FailCount= 2
2014-09-13T00:51:55.669Z,1410569515.669 [DVL_micro](ERROR): Communications Fault
2014-09-13T00:51:55.696Z,1410569515.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:55.771Z,1410569515.771 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-13T00:51:56.104Z,1410569516.104 [CommandLine](IMPORTANT): got command report touch DVL_micro.platform_orientation
2014-09-13T00:51:56.224Z,1410569516.224 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:51:56.247Z,1410569516.247 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:56.686Z,1410569516.686 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:57.093Z,1410569517.093 [DVL_micro](INFO): Initializing
2014-09-13T00:51:57.138Z,1410569517.138 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:57.189Z,1410569517.189 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-13T00:51:57.189Z,1410569517.189 [DVL_micro] No Fault, FailCount= 2
2014-09-13T00:51:57.561Z,1410569517.561 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:57.951Z,1410569517.951 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:58.364Z,1410569518.364 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:58.787Z,1410569518.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:59.226Z,1410569519.226 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:51:59.652Z,1410569519.652 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:00.144Z,1410569520.144 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:00.544Z,1410569520.544 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:00.591Z,1410569520.591 [CBIT](IMPORTANT): No ground fault detected
2014-09-13T00:52:00.631Z,1410569520.631 [CommandLine](IMPORTANT): got command failComponent
2014-09-13T00:52:00.631Z,1410569520.631 [CommandLine](IMPORTANT): Failed components:
2014-09-13T00:52:00.632Z,1410569520.632 [CommandLine](IMPORTANT): No failed Components.
2014-09-13T00:52:00.987Z,1410569520.987 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:01.386Z,1410569521.386 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:01.833Z,1410569521.833 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:02.290Z,1410569522.290 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:02.725Z,1410569522.725 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:03.158Z,1410569523.158 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:03.580Z,1410569523.580 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:03.988Z,1410569523.988 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:04.397Z,1410569524.397 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:04.793Z,1410569524.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:05.150Z,1410569525.150 [CommandLine](IMPORTANT): got command report
2014-09-13T00:52:05.150Z,1410569525.150 [Reporter](INFO): DVL_micro.platform_orientation reporting when touched
2014-09-13T00:52:05.228Z,1410569525.228 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:05.658Z,1410569525.658 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:06.097Z,1410569526.097 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:06.487Z,1410569526.487 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:06.901Z,1410569526.901 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:07.297Z,1410569527.297 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:07.682Z,1410569527.682 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-13T00:52:07.682Z,1410569527.682 [DVL_micro] Communications Fault, FailCount= 3
2014-09-13T00:52:07.682Z,1410569527.682 [DVL_micro](ERROR): Communications Fault
2014-09-13T00:52:07.694Z,1410569527.694 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:07.741Z,1410569527.741 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-13T00:52:08.166Z,1410569528.166 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:52:08.184Z,1410569528.184 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:08.609Z,1410569528.609 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:09.014Z,1410569529.014 [DVL_micro](INFO): Initializing
2014-09-13T00:52:09.041Z,1410569529.041 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:09.092Z,1410569529.092 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-13T00:52:09.092Z,1410569529.092 [DVL_micro] No Fault, FailCount= 3
2014-09-13T00:52:09.464Z,1410569529.464 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:09.884Z,1410569529.884 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:10.298Z,1410569530.298 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:10.699Z,1410569530.699 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:11.107Z,1410569531.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:11.546Z,1410569531.546 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:11.968Z,1410569531.968 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:12.408Z,1410569532.408 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:12.802Z,1410569532.802 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:13.210Z,1410569533.210 [BPC1](ERROR): A bank expecting battery number:1 and read number:0
2014-09-13T00:52:13.211Z,1410569533.211 [BPC1](FAULT): Failed to parse bank A battery data
2014-09-13T00:52:13.211Z,1410569533.211 [BPC1] Data Fault, FailCount= 1
2014-09-13T00:52:13.211Z,1410569533.211 [BPC1](ERROR): Data Fault
2014-09-13T00:52:13.217Z,1410569533.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:13.255Z,1410569533.255 [CBIT](ERROR): Data Fault in component: BPC1
2014-09-13T00:52:13.620Z,1410569533.620 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:14.014Z,1410569534.014 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:14.455Z,1410569534.455 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:14.506Z,1410569534.506 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-13T00:52:14.506Z,1410569534.506 [BPC1] No Fault, FailCount= 1
2014-09-13T00:52:14.930Z,1410569534.930 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:15.385Z,1410569535.385 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:15.919Z,1410569535.919 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:16.372Z,1410569536.372 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:16.829Z,1410569536.829 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:17.312Z,1410569537.312 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:17.788Z,1410569537.788 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:18.268Z,1410569538.268 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:18.832Z,1410569538.832 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:19.599Z,1410569539.599 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-13T00:52:19.599Z,1410569539.599 [DVL_micro] Communications Fault, FailCount= 4
2014-09-13T00:52:19.599Z,1410569539.599 [DVL_micro](ERROR): Communications Fault
2014-09-13T00:52:19.611Z,1410569539.611 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:19.625Z,1410569539.625 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-13T00:52:20.073Z,1410569540.073 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:52:20.084Z,1410569540.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:20.488Z,1410569540.488 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:20.865Z,1410569540.865 [DVL_micro](INFO): Initializing
2014-09-13T00:52:20.910Z,1410569540.910 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:20.925Z,1410569540.925 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-09-13T00:52:20.925Z,1410569540.925 [DVL_micro] No Fault, FailCount= 4
2014-09-13T00:52:21.308Z,1410569541.308 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:21.705Z,1410569541.705 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:22.084Z,1410569542.084 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:22.466Z,1410569542.466 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:22.877Z,1410569542.877 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:23.292Z,1410569543.292 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:23.714Z,1410569543.714 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:24.109Z,1410569544.109 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:24.512Z,1410569544.512 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:24.907Z,1410569544.907 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:25.306Z,1410569545.306 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:25.687Z,1410569545.687 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:26.107Z,1410569546.107 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:26.522Z,1410569546.522 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:26.925Z,1410569546.925 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:27.337Z,1410569547.337 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:27.369Z,1410569547.369 [SBIT](IMPORTANT): SBIT PASSED
2014-09-13T00:52:27.772Z,1410569547.772 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:27.778Z,1410569547.778 [MissionManager](IMPORTANT): Started mission Startup
2014-09-13T00:52:27.778Z,1410569547.778 [Startup] Running Loop=1
2014-09-13T00:52:27.778Z,1410569547.778 [Startup](INFO): Aggregate::initialize Startup
2014-09-13T00:52:27.778Z,1410569547.778 [Startup:A.GoToSurface] Running Loop=1
2014-09-13T00:52:27.778Z,1410569547.778 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-09-13T00:52:27.779Z,1410569547.779 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-09-13T00:52:27.779Z,1410569547.779 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-09-13T00:52:27.780Z,1410569547.780 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-09-13T00:52:27.785Z,1410569547.785 [Startup:StartupSatComms] Running Loop=1
2014-09-13T00:52:27.786Z,1410569547.786 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-09-13T00:52:27.786Z,1410569547.786 [Startup:StartupSatComms:A] Running Loop=1
2014-09-13T00:52:28.177Z,1410569548.177 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:28.183Z,1410569548.183 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-09-13T00:52:28.581Z,1410569548.581 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:28.975Z,1410569548.975 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:29.381Z,1410569549.381 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:29.787Z,1410569549.787 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:30.192Z,1410569550.192 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:30.564Z,1410569550.564 [BPC1](FAULT): Failed to receive battery data
2014-09-13T00:52:30.564Z,1410569550.564 [BPC1] Communications Fault, FailCount= 2
2014-09-13T00:52:30.564Z,1410569550.564 [BPC1](ERROR): Communications Fault
2014-09-13T00:52:30.570Z,1410569550.570 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:30.585Z,1410569550.585 [CBIT](ERROR): Communications Fault in component: BPC1
2014-09-13T00:52:30.979Z,1410569550.979 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:31.364Z,1410569551.364 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-09-13T00:52:31.364Z,1410569551.364 [DVL_micro] Communications Fault, FailCount= 5
2014-09-13T00:52:31.364Z,1410569551.364 [DVL_micro](ERROR): Communications Fault
2014-09-13T00:52:31.376Z,1410569551.376 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:31.391Z,1410569551.391 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-09-13T00:52:31.391Z,1410569551.391 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2014-09-13T00:52:31.851Z,1410569551.851 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:52:31.873Z,1410569551.873 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:31.888Z,1410569551.888 [CommandLine](FAULT): Scheduling is paused
2014-09-13T00:52:31.889Z,1410569551.889 [CBIT](INFO): Clearing failed state for component BPC1
2014-09-13T00:52:31.889Z,1410569551.889 [BPC1] No Fault, FailCount= 2
2014-09-13T00:52:32.320Z,1410569552.320 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:32.684Z,1410569552.684 [DVL_micro](INFO): Initializing
2014-09-13T00:52:32.724Z,1410569552.724 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:33.099Z,1410569553.099 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:33.502Z,1410569553.502 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:33.894Z,1410569553.894 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:34.295Z,1410569554.295 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:34.303Z,1410569554.303 [CommandLine](IMPORTANT): got command quit
2014-09-13T00:52:34.696Z,1410569554.696 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:35.096Z,1410569555.096 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:35.308Z,1410569555.308 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:52:35.308Z,1410569555.308 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.332Z,1410569555.332 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2014-09-13T00:52:35.332Z,1410569555.332 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.333Z,1410569555.333 [CommandLine](INFO): Join timeout helper Thread ID is 5960
2014-09-13T00:52:35.334Z,1410569555.334 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-09-13T00:52:35.334Z,1410569555.334 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.334Z,1410569555.334 [NavChartDb](INFO): Join timeout helper Thread ID is 5961
2014-09-13T00:52:35.429Z,1410569555.429 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:52:35.429Z,1410569555.429 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.433Z,1410569555.433 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-09-13T00:52:35.433Z,1410569555.433 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.433Z,1410569555.433 [logger](INFO): Join timeout helper Thread ID is 5962
2014-09-13T00:52:35.457Z,1410569555.457 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:52:35.457Z,1410569555.457 [logger ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.473Z,1410569555.473 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-09-13T00:52:35.473Z,1410569555.473 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.473Z,1410569555.473 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-09-13T00:52:35.473Z,1410569555.473 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.473Z,1410569555.473 [controlThread](INFO): Join timeout helper Thread ID is 5963
2014-09-13T00:52:35.523Z,1410569555.523 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2014-09-13T00:52:35.539Z,1410569555.539 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-09-13T00:52:35.539Z,1410569555.539 [controlThread](DEBUG): Uninitializing ControlThread
2014-09-13T00:52:35.540Z,1410569555.540 [AHRS_sp3003D](INFO): Powering down
2014-09-13T00:52:35.697Z,1410569555.697 [DVL_micro](INFO): uninitialize:Powering down
2014-09-13T00:52:35.698Z,1410569555.698 [NAL9602](INFO): Powering down
2014-09-13T00:52:35.700Z,1410569555.700 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-09-13T00:52:35.702Z,1410569555.702 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-09-13T00:52:35.705Z,1410569555.705 [Startup] Stopped
2014-09-13T00:52:35.705Z,1410569555.705 [Startup](INFO): Aggregate::uninitialize Startup
2014-09-13T00:52:35.705Z,1410569555.705 [Startup:A.GoToSurface] Stopped
2014-09-13T00:52:35.706Z,1410569555.706 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-09-13T00:52:35.706Z,1410569555.706 [Startup:StartupSatComms] Stopped
2014-09-13T00:52:35.706Z,1410569555.706 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-09-13T00:52:35.706Z,1410569555.706 [Startup:StartupSatComms:A] Stopped
2014-09-13T00:52:35.707Z,1410569555.707 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-09-13T00:52:35.707Z,1410569555.707 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-09-13T00:52:35.708Z,1410569555.708 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-09-13T00:52:35.708Z,1410569555.708 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-09-13T00:52:35.708Z,1410569555.708 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-09-13T00:52:35.708Z,1410569555.708 [BuoyancyServo](INFO): Powering down
2014-09-13T00:52:35.721Z,1410569555.721 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-09-13T00:52:35.721Z,1410569555.721 [ElevatorServo](INFO): Powering down
2014-09-13T00:52:35.722Z,1410569555.722 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-09-13T00:52:35.722Z,1410569555.722 [MassServo](INFO): Powering down
2014-09-13T00:52:35.723Z,1410569555.723 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-09-13T00:52:35.723Z,1410569555.723 [RudderServo](INFO): Powering down
2014-09-13T00:52:35.724Z,1410569555.724 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-09-13T00:52:35.724Z,1410569555.724 [ThrusterServo](INFO): Powering down
2014-09-13T00:52:35.725Z,1410569555.725 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-09-13T00:52:35.726Z,1410569555.726 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-09-13T00:52:35.726Z,1410569555.726 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-09-13T00:52:35.758Z,1410569555.758 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.928Z,1410569555.928 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-09-13T00:52:35.965Z,1410569555.965 [logger ThreadHandler](INFO): Thread cancelled.