2014-08-14T18:37:00.445Z,1408041420.445 [Supervisor](DEBUG): Initializing supervisor.
2014-08-14T18:37:00.447Z,1408041420.447 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-08-14T18:37:00.448Z,1408041420.448 [SyncHandler](INFO): Protected caller Thread ID is 2147
2014-08-14T18:37:00.448Z,1408041420.448 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-08-14T18:37:00.449Z,1408041420.449 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-08-14T18:37:00.450Z,1408041420.450 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2148
2014-08-14T18:37:00.452Z,1408041420.452 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-08-14T18:37:00.464Z,1408041420.464 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-08-14T18:37:00.465Z,1408041420.465 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-08-14T18:37:00.465Z,1408041420.465 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2149
2014-08-14T18:37:00.466Z,1408041420.466 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-08-14T18:37:00.467Z,1408041420.467 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-08-14T18:37:00.467Z,1408041420.467 [logger ThreadHandler](INFO): Protected caller Thread ID is 2150
2014-08-14T18:37:00.469Z,1408041420.469 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-08-14T18:37:00.470Z,1408041420.470 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-08-14T18:37:00.471Z,1408041420.471 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-08-14T18:37:00.799Z,1408041420.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-08-14T18:37:00.800Z,1408041420.800 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-08-14T18:37:00.955Z,1408041420.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-08-14T18:37:00.956Z,1408041420.956 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-08-14T18:37:01.063Z,1408041421.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-08-14T18:37:01.064Z,1408041421.064 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-08-14T18:37:01.187Z,1408041421.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-08-14T18:37:01.187Z,1408041421.187 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-08-14T18:37:01.272Z,1408041421.272 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-08-14T18:37:01.407Z,1408041421.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-08-14T18:37:01.407Z,1408041421.407 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-08-14T18:37:01.493Z,1408041421.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-08-14T18:37:01.493Z,1408041421.493 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-08-14T18:37:01.672Z,1408041421.672 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-08-14T18:37:01.673Z,1408041421.673 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-08-14T18:37:01.963Z,1408041421.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-08-14T18:37:01.963Z,1408041421.963 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-08-14T18:37:02.276Z,1408041422.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-08-14T18:37:02.277Z,1408041422.277 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-08-14T18:37:02.772Z,1408041422.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-08-14T18:37:02.773Z,1408041422.773 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-08-14T18:37:02.963Z,1408041422.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-08-14T18:37:02.963Z,1408041422.963 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-08-14T18:37:03.065Z,1408041423.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-08-14T18:37:03.066Z,1408041423.066 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-08-14T18:37:03.475Z,1408041423.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-08-14T18:37:03.476Z,1408041423.476 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-08-14T18:37:03.588Z,1408041423.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-08-14T18:37:03.590Z,1408041423.590 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-08-14T18:37:03.591Z,1408041423.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-08-14T18:37:03.698Z,1408041423.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-08-14T18:37:03.815Z,1408041423.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-08-14T18:37:03.917Z,1408041423.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-08-14T18:37:04.027Z,1408041424.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-08-14T18:37:04.124Z,1408041424.124 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-08-14T18:37:04.251Z,1408041424.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-08-14T18:37:04.397Z,1408041424.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-08-14T18:37:04.497Z,1408041424.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-08-14T18:37:04.585Z,1408041424.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-08-14T18:37:04.674Z,1408041424.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-08-14T18:37:04.799Z,1408041424.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-08-14T18:37:04.942Z,1408041424.942 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2014-08-14T18:37:04.944Z,1408041424.944 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-08-14T18:37:04.994Z,1408041424.994 [VerticalControl](DEBUG): Construct VerticalControl.
2014-08-14T18:37:05.110Z,1408041425.110 [VerticalControl] Loaded
2014-08-14T18:37:05.111Z,1408041425.111 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-08-14T18:37:05.111Z,1408041425.111 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-08-14T18:37:05.182Z,1408041425.182 [HorizontalControl] Loaded
2014-08-14T18:37:05.182Z,1408041425.182 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-08-14T18:37:05.183Z,1408041425.183 [SpeedControl](DEBUG): Construct SpeedControl.
2014-08-14T18:37:05.188Z,1408041425.188 [SpeedControl] Loaded
2014-08-14T18:37:05.188Z,1408041425.188 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-08-14T18:37:05.189Z,1408041425.189 [LoopControl](DEBUG): Construct LoopControl.
2014-08-14T18:37:05.190Z,1408041425.190 [LoopControl] Loaded
2014-08-14T18:37:05.190Z,1408041425.190 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-08-14T18:37:05.190Z,1408041425.190 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-08-14T18:37:05.191Z,1408041425.191 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-08-14T18:37:05.290Z,1408041425.290 [SBIT](DEBUG): Construct Startup Built In Test.
2014-08-14T18:37:05.301Z,1408041425.301 [SBIT] Loaded
2014-08-14T18:37:05.301Z,1408041425.301 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-08-14T18:37:05.302Z,1408041425.302 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-08-14T18:37:05.330Z,1408041425.330 [IBIT] Loaded
2014-08-14T18:37:05.330Z,1408041425.330 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-08-14T18:37:05.332Z,1408041425.332 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-08-14T18:37:05.447Z,1408041425.447 [CBIT] Loaded
2014-08-14T18:37:05.447Z,1408041425.447 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-08-14T18:37:05.448Z,1408041425.448 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-08-14T18:37:05.448Z,1408041425.448 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-08-14T18:37:05.467Z,1408041425.467 [DepthRateCalculator] Loaded
2014-08-14T18:37:05.467Z,1408041425.467 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-08-14T18:37:05.473Z,1408041425.473 [PitchRateCalculator] Loaded
2014-08-14T18:37:05.473Z,1408041425.473 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-08-14T18:37:05.484Z,1408041425.484 [SpeedCalculator] Loaded
2014-08-14T18:37:05.484Z,1408041425.484 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-08-14T18:37:05.500Z,1408041425.500 [TempGradientCalculator] Loaded
2014-08-14T18:37:05.500Z,1408041425.500 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-08-14T18:37:05.505Z,1408041425.505 [YawRateCalculator] Loaded
2014-08-14T18:37:05.506Z,1408041425.506 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-08-14T18:37:05.506Z,1408041425.506 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-08-14T18:37:05.507Z,1408041425.507 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-08-14T18:37:09.911Z,1408041429.911 [HFRadarCompactModelForecaster] Loaded
2014-08-14T18:37:09.911Z,1408041429.911 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-08-14T18:37:11.049Z,1408041431.049 [HFRCMSpaceInterpolator] Loaded
2014-08-14T18:37:11.049Z,1408041431.049 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-08-14T18:37:11.051Z,1408041431.051 [HFRCMTimeInterpolator] Loaded
2014-08-14T18:37:11.052Z,1408041431.052 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-08-14T18:37:12.166Z,1408041432.166 [HFRCMReconstructedInterpolator] Loaded
2014-08-14T18:37:12.167Z,1408041432.167 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-08-14T18:37:12.179Z,1408041432.179 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-08-14T18:37:12.179Z,1408041432.179 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-08-14T18:37:12.179Z,1408041432.179 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-08-14T18:37:12.180Z,1408041432.180 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-08-14T18:37:12.405Z,1408041432.405 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-08-14T18:37:12.406Z,1408041432.406 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-08-14T18:37:12.484Z,1408041432.484 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-08-14T18:37:12.484Z,1408041432.484 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-08-14T18:37:12.530Z,1408041432.530 [DeadReckonWithRespectToWater] Loaded
2014-08-14T18:37:12.530Z,1408041432.530 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-08-14T18:37:12.575Z,1408041432.575 [DeadReckonWithRespectToSeafloor] Loaded
2014-08-14T18:37:12.576Z,1408041432.576 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-08-14T18:37:12.633Z,1408041432.633 [DeadReckonUsingDVLWaterTrack] Loaded
2014-08-14T18:37:12.633Z,1408041432.633 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-08-14T18:37:12.679Z,1408041432.679 [DeadReckonUsingCompactModelForecast] Loaded
2014-08-14T18:37:12.680Z,1408041432.680 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-08-14T18:37:12.696Z,1408041432.696 [NavChart] Loaded
2014-08-14T18:37:12.696Z,1408041432.696 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-08-14T18:37:12.700Z,1408041432.700 [UniversalFixResidualReporter] Loaded
2014-08-14T18:37:12.701Z,1408041432.701 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-08-14T18:37:12.701Z,1408041432.701 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-08-14T18:37:12.702Z,1408041432.702 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-08-14T18:37:12.707Z,1408041432.707 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-08-14T18:37:12.708Z,1408041432.708 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-08-14T18:37:12.880Z,1408041432.880 [CTD_NeilBrown] Loaded
2014-08-14T18:37:12.881Z,1408041432.881 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-08-14T18:37:12.882Z,1408041432.882 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406C34E0
2014-08-14T18:37:12.882Z,1408041432.882 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2233
2014-08-14T18:37:12.897Z,1408041432.897 [PAR_Licor] Loaded
2014-08-14T18:37:12.897Z,1408041432.897 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-08-14T18:37:12.932Z,1408041432.932 [WetLabsBB2FL] Loaded
2014-08-14T18:37:12.932Z,1408041432.932 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-08-14T18:37:12.934Z,1408041432.934 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406F34E0
2014-08-14T18:37:12.934Z,1408041432.934 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2234
2014-08-14T18:37:12.934Z,1408041432.934 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-08-14T18:37:12.935Z,1408041432.935 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-08-14T18:37:13.209Z,1408041433.210 [AHRS_sp3003D] Loaded
2014-08-14T18:37:13.210Z,1408041433.210 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-08-14T18:37:13.465Z,1408041433.465 [Batt_Ocean_Server] Loaded
2014-08-14T18:37:13.465Z,1408041433.465 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-08-14T18:37:13.478Z,1408041433.478 [Depth_Keller] Loaded
2014-08-14T18:37:13.479Z,1408041433.479 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-08-14T18:37:13.484Z,1408041433.484 [DropWeight] Loaded
2014-08-14T18:37:13.484Z,1408041433.484 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-08-14T18:37:13.613Z,1408041433.613 [DVL_micro] Loaded
2014-08-14T18:37:13.614Z,1408041433.614 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-08-14T18:37:13.708Z,1408041433.708 [NAL9602] Loaded
2014-08-14T18:37:13.709Z,1408041433.709 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-08-14T18:37:13.749Z,1408041433.749 [Onboard] Loaded
2014-08-14T18:37:13.750Z,1408041433.750 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-08-14T18:37:13.757Z,1408041433.757 [Radio_Freewave] Loaded
2014-08-14T18:37:13.757Z,1408041433.757 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-08-14T18:37:13.758Z,1408041433.758 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 4092C4E0
2014-08-14T18:37:13.759Z,1408041433.759 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 2235
2014-08-14T18:37:13.884Z,1408041433.884 [DAT] Loaded
2014-08-14T18:37:13.885Z,1408041433.885 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-08-14T18:37:13.891Z,1408041433.891 [SCPI] Loaded
2014-08-14T18:37:13.891Z,1408041433.891 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-08-14T18:37:13.892Z,1408041433.892 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-08-14T18:37:13.893Z,1408041433.893 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-08-14T18:37:13.992Z,1408041433.992 [BuoyancyServo] Loaded
2014-08-14T18:37:13.992Z,1408041433.992 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-08-14T18:37:14.004Z,1408041434.004 [ElevatorServo] Loaded
2014-08-14T18:37:14.004Z,1408041434.004 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-08-14T18:37:14.016Z,1408041434.016 [MassServo] Loaded
2014-08-14T18:37:14.016Z,1408041434.016 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-08-14T18:37:14.028Z,1408041434.028 [RudderServo] Loaded
2014-08-14T18:37:14.028Z,1408041434.028 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-08-14T18:37:14.039Z,1408041434.039 [ThrusterServo] Loaded
2014-08-14T18:37:14.039Z,1408041434.039 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-08-14T18:37:14.040Z,1408041434.040 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-08-14T18:37:14.040Z,1408041434.040 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-08-14T18:37:14.191Z,1408041434.191 [InternalSim] Loaded
2014-08-14T18:37:14.191Z,1408041434.191 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-08-14T18:37:14.192Z,1408041434.192 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-08-14T18:37:14.193Z,1408041434.193 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-08-14T18:37:14.216Z,1408041434.216 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-08-14T18:37:14.218Z,1408041434.218 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-08-14T18:37:14.219Z,1408041434.219 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-08-14T18:37:14.225Z,1408041434.225 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-08-14T18:37:14.226Z,1408041434.226 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2014-08-14T18:37:14.226Z,1408041434.226 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2236
2014-08-14T18:37:14.231Z,1408041434.231 [Supervisor](INFO): Main Thread ID is 2146
2014-08-14T18:37:14.231Z,1408041434.231 [Supervisor](DEBUG): Running supervisor.
2014-08-14T18:37:14.232Z,1408041434.232 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2237
2014-08-14T18:37:14.235Z,1408041434.235 [controlThread ThreadHandler](INFO): Handler Thread ID is 2238
2014-08-14T18:37:14.235Z,1408041434.235 [controlThread](DEBUG): Initializing ControlThread
2014-08-14T18:37:14.236Z,1408041434.236 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-08-14T18:37:14.238Z,1408041434.238 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-08-14T18:37:14.239Z,1408041434.239 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-08-14T18:37:14.239Z,1408041434.239 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-08-14T18:37:14.240Z,1408041434.240 [SBIT](INFO): Initialize SBIT Component.
2014-08-14T18:37:14.240Z,1408041434.240 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11510
2014-08-14T18:37:14.241Z,1408041434.241 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-08-14T18:37:14.241Z,1408041434.241 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-08-14T18:37:14.242Z,1408041434.242 [IBIT](INFO): Initialize IBIT Component.
2014-08-14T18:37:14.242Z,1408041434.242 [CBIT](DEBUG): Initialize CBIT Component.
2014-08-14T18:37:14.243Z,1408041434.243 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2014-08-14T18:37:14.243Z,1408041434.243 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-08-14T18:37:14.244Z,1408041434.244 [logger ThreadHandler](INFO): Handler Thread ID is 2239
2014-08-14T18:37:14.269Z,1408041434.269 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-14T18:37:14.270Z,1408041434.270 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-08-14T18:37:14.271Z,1408041434.271 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-08-14T18:37:14.271Z,1408041434.271 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-08-14T18:37:14.272Z,1408041434.272 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-08-14T18:37:14.270Z,1408041434.270 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2240
2014-08-14T18:37:14.277Z,1408041434.277 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-08-14T18:37:14.283Z,1408041434.283 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-08-14T18:37:14.283Z,1408041434.283 [HFRadarCompactModelForecaster](ERROR): Most recent ECs are more than six hours old.
2014-08-14T18:37:14.285Z,1408041434.285 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-08-14T18:37:14.295Z,1408041434.295 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2242
2014-08-14T18:37:14.296Z,1408041434.296 [WetLabsBB2FL](INFO): Powering down
2014-08-14T18:37:14.336Z,1408041434.336 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 2243
2014-08-14T18:37:14.349Z,1408041434.349 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2244
2014-08-14T18:37:14.352Z,1408041434.352 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-08-14T18:37:14.353Z,1408041434.353 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-08-14T18:37:14.354Z,1408041434.354 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-08-14T18:37:14.354Z,1408041434.354 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-08-14T18:37:14.354Z,1408041434.354 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-08-14T18:37:14.355Z,1408041434.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-08-14T18:37:14.355Z,1408041434.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-08-14T18:37:14.355Z,1408041434.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-08-14T18:37:14.355Z,1408041434.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-08-14T18:37:22.990Z,1408041442.990 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201408061400 published
2014-08-14T18:37:23.020Z,1408041443.020 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-08-14T18:37:23.022Z,1408041443.022 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-08-14T18:37:23.022Z,1408041443.022 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-08-14T18:37:23.024Z,1408041443.024 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-08-14T18:37:23.024Z,1408041443.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-14T18:37:23.025Z,1408041443.025 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-08-14T18:37:23.025Z,1408041443.025 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-08-14T18:37:23.026Z,1408041443.026 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-08-14T18:37:23.026Z,1408041443.026 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-08-14T18:37:23.035Z,1408041443.035 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-08-14T18:37:23.035Z,1408041443.035 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-14T18:37:25.039Z,1408041445.039 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-08-14T18:37:25.070Z,1408041445.070 [InternalSim](DEBUG):  InternalSim initializing...
2014-08-14T18:37:25.211Z,1408041445.211 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-08-14T18:37:25.271Z,1408041445.271 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-14T18:37:25.301Z,1408041445.301 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2014-08-14T18:37:25.302Z,1408041445.302 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-08-14T18:37:25.369Z,1408041445.369 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-14T18:37:25.436Z,1408041445.436 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-08-14T18:37:25.440Z,1408041445.440 [Radio_Freewave](INFO): Powering up
2014-08-14T18:37:25.458Z,1408041445.458 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-08-14T18:37:25.461Z,1408041445.461 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-08-14T18:37:25.487Z,1408041445.487 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or><Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

<!-- TODO: Do we really want this When operating on platform_communications? or on something else like time_fix? -->

<!-- Run application at high speed -->

        <Guidance:SetSpeed>
            <Parallel/>
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
<!-- NOTE: Original Default.xml didn't have a timeout on the GPS read, so theoretically could have hung indefinitely there... -->
            <Universal:latitude_fix/>
        </ReadDatum>

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped drop weight due to communications timeout</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

        <Guidance:SetSpeed Id="RunApplicationAtLowSpeed">
            <Parallel/>
            <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
        </Guidance:SetSpeed>

    </Aggregate>

</Mission>


2014-08-14T18:37:25.501Z,1408041445.501 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-08-14T18:37:25.679Z,1408041445.679 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-08-14T18:37:25.859Z,1408041445.859 [DVL_micro](INFO): Initializing
2014-08-14T18:37:25.889Z,1408041445.889 [NAL9602](INFO): Powering up NAL9602
2014-08-14T18:37:25.943Z,1408041445.943 [DAT](INFO): Powering up
2014-08-14T18:37:25.943Z,1408041445.943 [DAT](DEBUG): Initializing DAT.
2014-08-14T18:37:26.249Z,1408041446.249 [Radio_Freewave](IMPORTANT): Starting PPPD with command:/sbin/pppd nocrtscts /dev/ttyS1 noauth 115200 10.1.1.2:10.1.1.1 persist maxfail 0
2014-08-14T18:37:33.181Z,1408041453.181 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:33.179Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:33.415Z,1408041453.415 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-08-14T18:37:33.423Z,1408041453.423 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-08-14T18:37:33.429Z,1408041453.429 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-08-14T18:37:33.447Z,1408041453.447 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-08-14T18:37:33.453Z,1408041453.453 [MassServo](DEBUG): Initializing EZServoServo.
2014-08-14T18:37:33.459Z,1408041453.459 [MassServo](DEBUG): Initializing MassServo.
2014-08-14T18:37:33.464Z,1408041453.464 [RudderServo](DEBUG): Initializing EZServoServo.
2014-08-14T18:37:33.471Z,1408041453.471 [RudderServo](DEBUG): Initializing RudderServo.
2014-08-14T18:37:33.476Z,1408041453.476 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-08-14T18:37:33.504Z,1408041453.504 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-08-14T18:37:34.143Z,1408041454.143 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:34.142Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:34.441Z,1408041454.441 [SBIT](IMPORTANT): Beginning Startup BIT
2014-08-14T18:37:34.444Z,1408041454.444 [CBIT](IMPORTANT): Beginning GF scan
2014-08-14T18:37:34.959Z,1408041454.959 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:34.958Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:35.525Z,1408041455.525 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:35.524Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:35.977Z,1408041455.977 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:35.976Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:36.590Z,1408041456.590 [NAL9602](INFO): NAL9602 initialized
2014-08-14T18:37:36.631Z,1408041456.631 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:36.630Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:37.092Z,1408041457.092 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:37.087Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:37.829Z,1408041457.829 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:37.824Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:38.702Z,1408041458.702 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:38.726Z,1408041458.726 [DVL_micro] Hardware Fault, FailCount= 1
2014-08-14T18:37:38.726Z,1408041458.726 [DVL_micro](ERROR): Hardware Fault
2014-08-14T18:37:38.729Z,1408041458.729 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:38.862Z,1408041458.862 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:38.860Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:38.967Z,1408041458.967 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2014-08-14T18:37:39.330Z,1408041459.330 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:39.387Z,1408041459.387 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:39.385Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:39.938Z,1408041459.938 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:39.994Z,1408041459.994 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:39.993Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:40.675Z,1408041460.675 [DVL_micro](INFO): Initializing
2014-08-14T18:37:40.717Z,1408041460.717 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:40.715Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:41.010Z,1408041461.010 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-08-14T18:37:41.010Z,1408041461.010 [DVL_micro] No Fault, FailCount= 1
2014-08-14T18:37:41.495Z,1408041461.495 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:41.489Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:42.039Z,1408041462.039 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:42.037Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:42.518Z,1408041462.518 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:42.517Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:43.084Z,1408041463.084 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:43.082Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:43.541Z,1408041463.541 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:43.540Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:44.024Z,1408041464.024 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:44.018Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:44.646Z,1408041464.646 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:44.644Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:45.273Z,1408041465.273 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:45.268Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:45.792Z,1408041465.792 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:45.792Z,1408041465.792 [DVL_micro] Hardware Fault, FailCount= 2
2014-08-14T18:37:45.792Z,1408041465.792 [DVL_micro](ERROR): Hardware Fault
2014-08-14T18:37:45.793Z,1408041465.793 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:45.882Z,1408041465.882 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:45.880Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:45.959Z,1408041465.959 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2014-08-14T18:37:46.317Z,1408041466.317 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:46.337Z,1408041466.337 [DAT](INFO): Powering down
2014-08-14T18:37:46.438Z,1408041466.438 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:46.437Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:46.873Z,1408041466.873 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:46.948Z,1408041466.948 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:46.947Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:47.387Z,1408041467.387 [DVL_micro](INFO): Initializing
2014-08-14T18:37:47.439Z,1408041467.439 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:47.437Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:47.518Z,1408041467.518 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-08-14T18:37:47.518Z,1408041467.518 [DVL_micro] No Fault, FailCount= 2
2014-08-14T18:37:48.080Z,1408041468.080 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:48.079Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:48.605Z,1408041468.605 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:48.604Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:49.058Z,1408041469.058 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:49.056Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:49.531Z,1408041469.531 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:49.530Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:49.983Z,1408041469.983 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:49.982Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:50.734Z,1408041470.734 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:50.728Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:51.692Z,1408041471.692 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:51.686Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:52.178Z,1408041472.178 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:52.178Z,1408041472.178 [DVL_micro] Hardware Fault, FailCount= 3
2014-08-14T18:37:52.178Z,1408041472.178 [DVL_micro](ERROR): Hardware Fault
2014-08-14T18:37:52.179Z,1408041472.179 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:52.207Z,1408041472.207 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:52.205Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:52.374Z,1408041472.374 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2014-08-14T18:37:52.732Z,1408041472.732 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:52.808Z,1408041472.808 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:52.803Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:53.280Z,1408041473.280 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:53.316Z,1408041473.316 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:53.315Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:53.836Z,1408041473.836 [DVL_micro](INFO): Initializing
2014-08-14T18:37:53.901Z,1408041473.901 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:53.899Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:54.030Z,1408041474.030 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-08-14T18:37:54.030Z,1408041474.030 [DVL_micro] No Fault, FailCount= 3
2014-08-14T18:37:54.479Z,1408041474.479 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:54.462Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:54.963Z,1408041474.963 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:54.962Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:55.504Z,1408041475.504 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:55.503Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:56.341Z,1408041476.341 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:56.340Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:56.838Z,1408041476.838 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:56.813Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:57.336Z,1408041477.336 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:57.314Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:57.866Z,1408041477.866 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:57.865Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:58.336Z,1408041478.336 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:58.334Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:58.777Z,1408041478.777 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:58.777Z,1408041478.777 [DVL_micro] Hardware Fault, FailCount= 4
2014-08-14T18:37:58.777Z,1408041478.777 [DVL_micro](ERROR): Hardware Fault
2014-08-14T18:37:58.778Z,1408041478.778 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:58.802Z,1408041478.802 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:58.792Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:58.886Z,1408041478.886 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2014-08-14T18:37:59.233Z,1408041479.233 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:37:59.263Z,1408041479.263 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:59.257Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:37:59.689Z,1408041479.689 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:37:59.718Z,1408041479.718 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:37:59.717Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:00.160Z,1408041480.160 [DVL_micro](INFO): Initializing
2014-08-14T18:38:00.231Z,1408041480.231 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:00.229Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:00.309Z,1408041480.309 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-08-14T18:38:00.309Z,1408041480.309 [DVL_micro] No Fault, FailCount= 4
2014-08-14T18:38:00.713Z,1408041480.713 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:00.708Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:01.345Z,1408041481.345 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:01.344Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:01.814Z,1408041481.814 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:01.812Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:01.907Z,1408041481.907 [CBIT](IMPORTANT): No ground fault detected
2014-08-14T18:38:02.278Z,1408041482.278 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:02.277Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:02.795Z,1408041482.795 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:02.794Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:03.316Z,1408041483.316 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:03.315Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:03.817Z,1408041483.817 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:03.815Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:04.280Z,1408041484.280 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:38:04.281Z,1408041484.281 [DVL_micro] Hardware Fault, FailCount= 5
2014-08-14T18:38:04.281Z,1408041484.281 [DVL_micro](ERROR): Hardware Fault
2014-08-14T18:38:04.282Z,1408041484.282 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:38:04.315Z,1408041484.315 [NAL9602](IMPORTANT): GPS fix at: 1408041482.00
2014-08-14T18:38:04.377Z,1408041484.377 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:04.376Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:04.443Z,1408041484.443 [CBIT](ERROR): Hardware Fault in component: DVL_micro
2014-08-14T18:38:04.444Z,1408041484.444 [CBIT](CRITICAL): Hardware Fault in component: DVL_micro
2014-08-14T18:38:04.859Z,1408041484.859 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:38:04.894Z,1408041484.894 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:04.893Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:04.928Z,1408041484.928 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:04.922Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:04.929Z,1408041484.929 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:04.998Z,1408041484.998 [CommandLine](FAULT): Scheduling is paused
2014-08-14T18:38:05.381Z,1408041485.381 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-08-14T18:38:05.446Z,1408041485.446 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:05.445Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:05.448Z,1408041485.448 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:05.447Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:05.449Z,1408041485.449 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:06.006Z,1408041486.006 [DVL_micro](INFO): Initializing
2014-08-14T18:38:06.059Z,1408041486.059 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:06.058Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:06.070Z,1408041486.070 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:06.060Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:06.071Z,1408041486.071 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:06.582Z,1408041486.582 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:06.580Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:06.584Z,1408041486.584 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:06.583Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:06.585Z,1408041486.585 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:07.053Z,1408041487.053 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:07.052Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:07.056Z,1408041487.056 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:07.055Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:07.057Z,1408041487.057 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:07.609Z,1408041487.609 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:07.608Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:07.611Z,1408041487.611 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:07.610Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:07.612Z,1408041487.612 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:08.106Z,1408041488.106 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:08.105Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:08.109Z,1408041488.109 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:08.107Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:08.109Z,1408041488.109 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:08.564Z,1408041488.564 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:08.562Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:08.566Z,1408041488.566 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:08.565Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:08.567Z,1408041488.567 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:09.024Z,1408041489.024 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:09.022Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:09.026Z,1408041489.026 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:09.025Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:09.027Z,1408041489.027 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:09.178Z,1408041489.178 [CommandLine](IMPORTANT): got command quit
2014-08-14T18:38:09.501Z,1408041489.501 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:09.500Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:09.531Z,1408041489.531 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:09.502Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:09.531Z,1408041489.531 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:09.995Z,1408041489.995 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:09.994Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:10.019Z,1408041490.019 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:10.018Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:10.020Z,1408041490.020 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:10.182Z,1408041490.182 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:10.183Z,1408041490.183 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:10.344Z,1408041490.344 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-08-14T18:38:10.344Z,1408041490.344 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:10.345Z,1408041490.345 [CommandLine](INFO): Join timeout helper Thread ID is 2267
2014-08-14T18:38:10.357Z,1408041490.357 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-08-14T18:38:10.357Z,1408041490.357 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:10.358Z,1408041490.358 [NavChartDb](INFO): Join timeout helper Thread ID is 2268
2014-08-14T18:38:10.478Z,1408041490.478 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:10.477Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:10.485Z,1408041490.485 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:10.479Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:10.486Z,1408041490.486 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:10.693Z,1408041490.693 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:10.693Z,1408041490.693 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:10.708Z,1408041490.708 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-08-14T18:38:10.709Z,1408041490.709 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:10.709Z,1408041490.709 [Radio_Freewave](INFO): Join timeout helper Thread ID is 2269
2014-08-14T18:38:10.969Z,1408041490.969 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:11.132Z,1408041491.132 [Radio_Freewave](INFO): Powering down
2014-08-14T18:38:11.139Z,1408041491.139 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:11.137Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:11.143Z,1408041491.143 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:11.140Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:11.144Z,1408041491.144 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:11.534Z,1408041491.534 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:11.542Z,1408041491.542 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-08-14T18:38:11.542Z,1408041491.542 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:11.542Z,1408041491.542 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2271
2014-08-14T18:38:11.578Z,1408041491.578 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:11.577Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:11.581Z,1408041491.581 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:11.579Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:11.582Z,1408041491.582 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:11.794Z,1408041491.794 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:11.794Z,1408041491.794 [WetLabsBB2FL](INFO): Powering down
2014-08-14T18:38:11.795Z,1408041491.795 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:11.812Z,1408041491.812 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-08-14T18:38:11.813Z,1408041491.813 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:11.813Z,1408041491.813 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2272
2014-08-14T18:38:11.951Z,1408041491.951 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:11.951Z,1408041491.951 [CTD_NeilBrown](INFO): Powering down
2014-08-14T18:38:11.952Z,1408041491.952 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:11.971Z,1408041491.971 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-08-14T18:38:11.971Z,1408041491.971 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:11.972Z,1408041491.972 [logger](INFO): Join timeout helper Thread ID is 2273
2014-08-14T18:38:12.005Z,1408041492.005 [HFRCMReconstructedInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:12.003Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:12.006Z,1408041492.006 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:12.007Z,1408041492.007 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.008Z,1408041492.008 [HFRCMTimeInterpolator](DEBUG): Query for interpolated value 2014-08-14T18:38:12.005Z outside the range of the data. [2014-08-04T14:00:00.000Z, 2014-08-07T02:00:00.000Z]
2014-08-14T18:38:12.009Z,1408041492.009 [HFRCMSpaceInterpolator](DEBUG): requested location (36.802013, -121.787193) is outside the bounding box
2014-08-14T18:38:12.011Z,1408041492.011 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-08-14T18:38:12.011Z,1408041492.011 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.011Z,1408041492.011 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-08-14T18:38:12.012Z,1408041492.012 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.012Z,1408041492.012 [controlThread](INFO): Join timeout helper Thread ID is 2274
2014-08-14T18:38:12.032Z,1408041492.032 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-14T18:38:12.032Z,1408041492.032 [controlThread](DEBUG): Uninitializing ControlThread
2014-08-14T18:38:12.033Z,1408041492.033 [AHRS_sp3003D](INFO): Powering down
2014-08-14T18:38:12.034Z,1408041492.034 [DVL_micro](INFO): uninitialize:Powering down
2014-08-14T18:38:12.035Z,1408041492.035 [NAL9602](INFO): Powering down
2014-08-14T18:38:12.036Z,1408041492.036 [DAT](INFO): Powering down
2014-08-14T18:38:12.038Z,1408041492.038 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-14T18:38:12.040Z,1408041492.040 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-08-14T18:38:12.044Z,1408041492.044 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-08-14T18:38:12.044Z,1408041492.044 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-08-14T18:38:12.045Z,1408041492.045 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-08-14T18:38:12.045Z,1408041492.045 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-08-14T18:38:12.045Z,1408041492.045 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-08-14T18:38:12.046Z,1408041492.046 [BuoyancyServo](INFO): Powering down
2014-08-14T18:38:12.059Z,1408041492.059 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-08-14T18:38:12.059Z,1408041492.059 [ElevatorServo](INFO): Powering down
2014-08-14T18:38:12.060Z,1408041492.060 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-08-14T18:38:12.060Z,1408041492.060 [MassServo](INFO): Powering down
2014-08-14T18:38:12.061Z,1408041492.061 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-08-14T18:38:12.061Z,1408041492.061 [RudderServo](INFO): Powering down
2014-08-14T18:38:12.061Z,1408041492.061 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-08-14T18:38:12.062Z,1408041492.062 [ThrusterServo](INFO): Powering down
2014-08-14T18:38:12.063Z,1408041492.063 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-08-14T18:38:12.063Z,1408041492.063 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-08-14T18:38:12.063Z,1408041492.063 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-08-14T18:38:12.107Z,1408041492.107 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.180Z,1408041492.180 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.184Z,1408041492.184 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.192Z,1408041492.192 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.240Z,1408041492.240 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-14T18:38:12.276Z,1408041492.276 [logger ThreadHandler](INFO): Thread cancelled.