2014-08-21T04:51:31.374Z,1408596691.374 [Supervisor](DEBUG): Initializing supervisor.
2014-08-21T04:51:31.377Z,1408596691.377 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-08-21T04:51:31.378Z,1408596691.378 [SyncHandler](INFO): Protected caller Thread ID is 2160
2014-08-21T04:51:31.378Z,1408596691.378 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-08-21T04:51:31.379Z,1408596691.379 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-08-21T04:51:31.380Z,1408596691.380 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2161
2014-08-21T04:51:31.384Z,1408596691.384 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-08-21T04:51:31.396Z,1408596691.396 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-08-21T04:51:31.397Z,1408596691.397 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-08-21T04:51:31.398Z,1408596691.398 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2162
2014-08-21T04:51:31.398Z,1408596691.398 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-08-21T04:51:31.399Z,1408596691.399 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-08-21T04:51:31.400Z,1408596691.400 [logger ThreadHandler](INFO): Protected caller Thread ID is 2163
2014-08-21T04:51:31.402Z,1408596691.402 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-08-21T04:51:31.402Z,1408596691.402 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-08-21T04:51:31.404Z,1408596691.404 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-08-21T04:51:31.734Z,1408596691.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-08-21T04:51:31.735Z,1408596691.735 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-08-21T04:51:31.892Z,1408596691.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-08-21T04:51:31.893Z,1408596691.893 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-08-21T04:51:32.003Z,1408596692.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-08-21T04:51:32.003Z,1408596692.003 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-08-21T04:51:32.129Z,1408596692.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-08-21T04:51:32.129Z,1408596692.129 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-08-21T04:51:32.217Z,1408596692.217 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-08-21T04:51:32.363Z,1408596692.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-08-21T04:51:32.364Z,1408596692.364 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-08-21T04:51:32.452Z,1408596692.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-08-21T04:51:32.452Z,1408596692.452 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-08-21T04:51:32.633Z,1408596692.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-08-21T04:51:32.633Z,1408596692.633 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-08-21T04:51:32.931Z,1408596692.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-08-21T04:51:32.931Z,1408596692.931 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-08-21T04:51:33.234Z,1408596693.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-08-21T04:51:33.235Z,1408596693.235 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-08-21T04:51:33.723Z,1408596693.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-08-21T04:51:33.723Z,1408596693.723 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-08-21T04:51:33.913Z,1408596693.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-08-21T04:51:33.914Z,1408596693.914 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-08-21T04:51:34.019Z,1408596694.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-08-21T04:51:34.020Z,1408596694.020 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-08-21T04:51:34.415Z,1408596694.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-08-21T04:51:34.416Z,1408596694.416 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-08-21T04:51:34.528Z,1408596694.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-08-21T04:51:34.530Z,1408596694.530 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-08-21T04:51:34.530Z,1408596694.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-08-21T04:51:34.637Z,1408596694.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-08-21T04:51:34.756Z,1408596694.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-08-21T04:51:34.856Z,1408596694.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-08-21T04:51:34.967Z,1408596694.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-08-21T04:51:35.067Z,1408596695.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-08-21T04:51:35.196Z,1408596695.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-08-21T04:51:35.345Z,1408596695.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-08-21T04:51:35.464Z,1408596695.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-08-21T04:51:35.595Z,1408596695.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-08-21T04:51:35.679Z,1408596695.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-08-21T04:51:35.778Z,1408596695.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-08-21T04:51:35.891Z,1408596695.891 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-08-21T04:51:35.891Z,1408596695.891 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-08-21T04:51:35.896Z,1408596695.896 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-08-21T04:51:35.946Z,1408596695.946 [VerticalControl](DEBUG): Construct VerticalControl.
2014-08-21T04:51:36.063Z,1408596696.063 [VerticalControl] Loaded
2014-08-21T04:51:36.063Z,1408596696.063 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-08-21T04:51:36.064Z,1408596696.064 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-08-21T04:51:36.134Z,1408596696.134 [HorizontalControl] Loaded
2014-08-21T04:51:36.135Z,1408596696.135 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-08-21T04:51:36.135Z,1408596696.135 [SpeedControl](DEBUG): Construct SpeedControl.
2014-08-21T04:51:36.141Z,1408596696.141 [SpeedControl] Loaded
2014-08-21T04:51:36.141Z,1408596696.141 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-08-21T04:51:36.142Z,1408596696.142 [LoopControl](DEBUG): Construct LoopControl.
2014-08-21T04:51:36.142Z,1408596696.142 [LoopControl] Loaded
2014-08-21T04:51:36.143Z,1408596696.143 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-08-21T04:51:36.143Z,1408596696.143 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-08-21T04:51:36.144Z,1408596696.144 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-08-21T04:51:36.243Z,1408596696.243 [SBIT](DEBUG): Construct Startup Built In Test.
2014-08-21T04:51:36.254Z,1408596696.254 [SBIT] Loaded
2014-08-21T04:51:36.254Z,1408596696.254 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-08-21T04:51:36.255Z,1408596696.255 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-08-21T04:51:36.284Z,1408596696.284 [IBIT] Loaded
2014-08-21T04:51:36.284Z,1408596696.284 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-08-21T04:51:36.286Z,1408596696.286 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-08-21T04:51:36.403Z,1408596696.403 [CBIT] Loaded
2014-08-21T04:51:36.403Z,1408596696.403 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-08-21T04:51:36.404Z,1408596696.404 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-08-21T04:51:36.404Z,1408596696.404 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-08-21T04:51:36.423Z,1408596696.423 [DepthRateCalculator] Loaded
2014-08-21T04:51:36.423Z,1408596696.423 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-08-21T04:51:36.429Z,1408596696.429 [PitchRateCalculator] Loaded
2014-08-21T04:51:36.429Z,1408596696.429 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-08-21T04:51:36.440Z,1408596696.440 [SpeedCalculator] Loaded
2014-08-21T04:51:36.440Z,1408596696.440 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-08-21T04:51:36.456Z,1408596696.456 [TempGradientCalculator] Loaded
2014-08-21T04:51:36.457Z,1408596696.457 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-08-21T04:51:36.462Z,1408596696.462 [YawRateCalculator] Loaded
2014-08-21T04:51:36.462Z,1408596696.462 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-08-21T04:51:36.463Z,1408596696.463 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-08-21T04:51:36.463Z,1408596696.463 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-08-21T04:51:40.559Z,1408596700.559 [HFRadarCompactModelForecaster] Loaded
2014-08-21T04:51:40.560Z,1408596700.560 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-08-21T04:51:41.639Z,1408596701.639 [HFRCMSpaceInterpolator] Loaded
2014-08-21T04:51:41.640Z,1408596701.640 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-08-21T04:51:41.642Z,1408596701.642 [HFRCMTimeInterpolator] Loaded
2014-08-21T04:51:41.642Z,1408596701.642 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-08-21T04:51:42.647Z,1408596702.647 [HFRCMReconstructedInterpolator] Loaded
2014-08-21T04:51:42.648Z,1408596702.648 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-08-21T04:51:42.660Z,1408596702.659 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-08-21T04:51:42.660Z,1408596702.660 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-08-21T04:51:42.660Z,1408596702.660 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-08-21T04:51:42.661Z,1408596702.661 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-08-21T04:51:42.886Z,1408596702.886 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-08-21T04:51:42.887Z,1408596702.887 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-08-21T04:51:42.966Z,1408596702.966 [DeadReckonUsingMultipleVelocitySourcesVector] Loaded
2014-08-21T04:51:42.966Z,1408596702.966 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySourcesVector" handled in the control thread.
2014-08-21T04:51:43.021Z,1408596703.021 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-08-21T04:51:43.021Z,1408596703.021 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-08-21T04:51:43.065Z,1408596703.065 [DeadReckonWithRespectToWater] Loaded
2014-08-21T04:51:43.065Z,1408596703.065 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-08-21T04:51:43.111Z,1408596703.111 [DeadReckonWithRespectToSeafloor] Loaded
2014-08-21T04:51:43.111Z,1408596703.111 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-08-21T04:51:43.169Z,1408596703.169 [DeadReckonUsingDVLWaterTrack] Loaded
2014-08-21T04:51:43.170Z,1408596703.170 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-08-21T04:51:43.215Z,1408596703.215 [DeadReckonUsingCompactModelForecast] Loaded
2014-08-21T04:51:43.216Z,1408596703.216 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-08-21T04:51:43.233Z,1408596703.233 [NavChart] Loaded
2014-08-21T04:51:43.234Z,1408596703.234 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-08-21T04:51:43.238Z,1408596703.238 [UniversalFixResidualReporter] Loaded
2014-08-21T04:51:43.238Z,1408596703.238 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-08-21T04:51:43.239Z,1408596703.239 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-08-21T04:51:43.239Z,1408596703.239 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-08-21T04:51:43.245Z,1408596703.245 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-08-21T04:51:43.246Z,1408596703.246 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-08-21T04:51:43.420Z,1408596703.420 [CTD_NeilBrown] Loaded
2014-08-21T04:51:43.421Z,1408596703.421 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-08-21T04:51:43.422Z,1408596703.422 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406C64E0
2014-08-21T04:51:43.422Z,1408596703.422 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2245
2014-08-21T04:51:43.438Z,1408596703.438 [PAR_Licor] Loaded
2014-08-21T04:51:43.438Z,1408596703.438 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-08-21T04:51:43.473Z,1408596703.473 [WetLabsBB2FL] Loaded
2014-08-21T04:51:43.473Z,1408596703.473 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-08-21T04:51:43.474Z,1408596703.474 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406F64E0
2014-08-21T04:51:43.475Z,1408596703.475 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2246
2014-08-21T04:51:43.475Z,1408596703.475 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-08-21T04:51:43.476Z,1408596703.476 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-08-21T04:51:43.763Z,1408596703.763 [AHRS_sp3003D] Loaded
2014-08-21T04:51:43.764Z,1408596703.764 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-08-21T04:51:44.019Z,1408596704.019 [Batt_Ocean_Server] Loaded
2014-08-21T04:51:44.020Z,1408596704.020 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-08-21T04:51:44.095Z,1408596704.095 [DataOverHttps] Loaded
2014-08-21T04:51:44.096Z,1408596704.096 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-08-21T04:51:44.109Z,1408596704.109 [Depth_Keller] Loaded
2014-08-21T04:51:44.109Z,1408596704.109 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-08-21T04:51:44.240Z,1408596704.240 [DVL_micro] Loaded
2014-08-21T04:51:44.241Z,1408596704.241 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-08-21T04:51:44.333Z,1408596704.333 [NAL9602] Loaded
2014-08-21T04:51:44.333Z,1408596704.333 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-08-21T04:51:44.374Z,1408596704.374 [Onboard] Loaded
2014-08-21T04:51:44.375Z,1408596704.375 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-08-21T04:51:44.382Z,1408596704.382 [Radio_Freewave] Loaded
2014-08-21T04:51:44.382Z,1408596704.382 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-08-21T04:51:44.384Z,1408596704.384 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2014-08-21T04:51:44.384Z,1408596704.384 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 2247
2014-08-21T04:51:44.508Z,1408596704.508 [DAT] Loaded
2014-08-21T04:51:44.508Z,1408596704.508 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2014-08-21T04:51:44.515Z,1408596704.515 [SCPI] Loaded
2014-08-21T04:51:44.515Z,1408596704.515 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-08-21T04:51:44.516Z,1408596704.516 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-08-21T04:51:44.516Z,1408596704.516 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-08-21T04:51:44.614Z,1408596704.614 [BuoyancyServo] Loaded
2014-08-21T04:51:44.614Z,1408596704.614 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-08-21T04:51:44.626Z,1408596704.626 [ElevatorServo] Loaded
2014-08-21T04:51:44.627Z,1408596704.627 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-08-21T04:51:44.638Z,1408596704.638 [MassServo] Loaded
2014-08-21T04:51:44.638Z,1408596704.638 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-08-21T04:51:44.650Z,1408596704.650 [RudderServo] Loaded
2014-08-21T04:51:44.650Z,1408596704.650 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-08-21T04:51:44.662Z,1408596704.662 [ThrusterServo] Loaded
2014-08-21T04:51:44.662Z,1408596704.662 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-08-21T04:51:44.662Z,1408596704.662 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-08-21T04:51:44.663Z,1408596704.663 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-08-21T04:51:44.821Z,1408596704.821 [InternalSim] Loaded
2014-08-21T04:51:44.822Z,1408596704.822 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-08-21T04:51:44.822Z,1408596704.822 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-08-21T04:51:44.823Z,1408596704.823 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-08-21T04:51:44.846Z,1408596704.846 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-08-21T04:51:44.848Z,1408596704.848 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-08-21T04:51:44.849Z,1408596704.849 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-08-21T04:51:44.856Z,1408596704.856 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-08-21T04:51:44.857Z,1408596704.857 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2014-08-21T04:51:44.857Z,1408596704.857 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2248
2014-08-21T04:51:44.863Z,1408596704.863 [Supervisor](INFO): Main Thread ID is 768
2014-08-21T04:51:44.863Z,1408596704.863 [Supervisor](DEBUG): Running supervisor.
2014-08-21T04:51:44.863Z,1408596704.863 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2249
2014-08-21T04:51:44.868Z,1408596704.868 [controlThread ThreadHandler](INFO): Handler Thread ID is 2250
2014-08-21T04:51:44.868Z,1408596704.868 [controlThread](DEBUG): Initializing ControlThread
2014-08-21T04:51:44.869Z,1408596704.869 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-08-21T04:51:44.871Z,1408596704.871 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-08-21T04:51:44.872Z,1408596704.872 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-08-21T04:51:44.872Z,1408596704.872 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-08-21T04:51:44.873Z,1408596704.873 [SBIT](INFO): Initialize SBIT Component.
2014-08-21T04:51:44.873Z,1408596704.873 [SBIT](IMPORTANT): Tethys CM Info: SVN revision: 11562
2014-08-21T04:51:44.873Z,1408596704.873 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2014-08-21T04:51:44.874Z,1408596704.874 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-08-21T04:51:44.874Z,1408596704.874 [IBIT](INFO): Initialize IBIT Component.
2014-08-21T04:51:44.875Z,1408596704.875 [CBIT](DEBUG): Initialize CBIT Component.
2014-08-21T04:51:44.875Z,1408596704.875 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-08-21T04:51:44.876Z,1408596704.876 [logger ThreadHandler](INFO): Handler Thread ID is 2251
2014-08-21T04:51:44.901Z,1408596704.901 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-21T04:51:44.902Z,1408596704.902 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-08-21T04:51:44.902Z,1408596704.902 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-08-21T04:51:44.902Z,1408596704.902 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-08-21T04:51:44.903Z,1408596704.903 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-08-21T04:51:44.910Z,1408596704.910 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-08-21T04:51:44.932Z,1408596704.932 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2252
2014-08-21T04:51:44.933Z,1408596704.933 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-08-21T04:51:44.942Z,1408596704.942 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-08-21T04:51:44.951Z,1408596704.951 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2254
2014-08-21T04:51:44.952Z,1408596704.952 [WetLabsBB2FL](INFO): Powering down
2014-08-21T04:51:44.986Z,1408596704.986 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 2255
2014-08-21T04:51:45.022Z,1408596705.022 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2257
2014-08-21T04:51:45.025Z,1408596705.025 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-08-21T04:51:45.026Z,1408596705.026 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-08-21T04:51:45.026Z,1408596705.026 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-08-21T04:51:45.027Z,1408596705.027 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-08-21T04:51:45.027Z,1408596705.027 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-08-21T04:51:45.027Z,1408596705.027 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-08-21T04:51:45.027Z,1408596705.027 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-08-21T04:51:45.028Z,1408596705.028 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-08-21T04:51:45.028Z,1408596705.028 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-08-21T04:51:45.321Z,1408596705.321 [Radio_Freewave](INFO): Powering up
2014-08-21T04:51:46.128Z,1408596706.128 [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-21T04:51:54.295Z,1408596714.295 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201408210100 published
2014-08-21T04:51:54.312Z,1408596714.312 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-08-21T04:51:54.314Z,1408596714.314 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-08-21T04:51:54.315Z,1408596714.315 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2014-08-21T04:51:54.316Z,1408596714.316 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-08-21T04:51:54.317Z,1408596714.317 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Initializing DeadReckonUsingMultipleVelocitySourcesVector component.
2014-08-21T04:51:54.317Z,1408596714.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-21T04:51:54.318Z,1408596714.318 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-08-21T04:51:54.318Z,1408596714.318 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-08-21T04:51:54.319Z,1408596714.319 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-08-21T04:51:54.319Z,1408596714.319 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-08-21T04:51:54.320Z,1408596714.320 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-08-21T04:51:54.320Z,1408596714.320 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-08-21T04:51:56.336Z,1408596716.336 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-08-21T04:51:56.366Z,1408596716.366 [InternalSim](DEBUG):  InternalSim initializing...
2014-08-21T04:51:56.508Z,1408596716.508 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-08-21T04:51:56.546Z,1408596716.546 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-21T04:51:56.576Z,1408596716.576 [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-21T04:51:56.577Z,1408596716.577 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-08-21T04:51:56.668Z,1408596716.668 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2014-08-21T04:51:56.671Z,1408596716.671 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2014-08-21T04:51:56.703Z,1408596716.703 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-08-21T04:51:56.725Z,1408596716.725 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2014-08-21T04:51:56.744Z,1408596716.744 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-08-21T04:51:56.765Z,1408596716.765 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-08-21T04:51:56.791Z,1408596716.791 [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">

    <DefineArg Name="TimeMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <DefineArg Name="ElapsedSinceMissionWasStarted"><Units:hour/><Value>0</Value></DefineArg>

    <Aggregate Id="StartClock">

        <Sequence/>

        <Assign><Arg Name="TimeMissionWasStarted"/><Universal:time/></Assign>

    </Aggregate>

    <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="UpdateAndReportMinutesSinceMissionStarted">

        <When>
            <Elapsed><Arg Name="ElapsedSinceMissionWasStarted"/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
        </When>

        <Assign><Arg Name="ElapsedSinceMissionWasStarted"/><Universal:time/><Sub><Arg Name="TimeMissionWasStarted"/></Sub></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceMissionWasStarted"/><Units:minute/></Syslog>

    </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-21T04:51:56.814Z,1408596716.814 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,PAR_Licor,AHRS_sp3003D,Batt_Ocean_Server,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,DAT,SCPI,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,DeadReckonUsingMultipleVelocitySourcesVector,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-08-21T04:51:56.984Z,1408596716.984 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-08-21T04:51:57.043Z,1408596717.043 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2014-08-21T04:51:57.092Z,1408596717.092 [DVL_micro](INFO): Initializing
2014-08-21T04:51:57.118Z,1408596717.118 [NAL9602](INFO): Powering up NAL9602
2014-08-21T04:51:57.164Z,1408596717.164 [DAT](INFO): Powering up
2014-08-21T04:51:57.164Z,1408596717.164 [DAT](DEBUG): Initializing DAT.
2014-08-21T04:52:04.314Z,1408596724.314 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:04.331Z,1408596724.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:04.338Z,1408596724.338 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:04.372Z,1408596724.372 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:04.384Z,1408596724.384 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:04.390Z,1408596724.390 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:04.523Z,1408596724.523 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-08-21T04:52:04.524Z,1408596724.524 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-08-21T04:52:04.547Z,1408596724.547 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-08-21T04:52:04.548Z,1408596724.548 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-08-21T04:52:04.582Z,1408596724.582 [MassServo](DEBUG): Initializing EZServoServo.
2014-08-21T04:52:04.588Z,1408596724.588 [MassServo](DEBUG): Initializing MassServo.
2014-08-21T04:52:04.600Z,1408596724.600 [RudderServo](DEBUG): Initializing EZServoServo.
2014-08-21T04:52:04.608Z,1408596724.608 [RudderServo](DEBUG): Initializing RudderServo.
2014-08-21T04:52:04.626Z,1408596724.626 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-08-21T04:52:04.632Z,1408596724.632 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-08-21T04:52:05.312Z,1408596725.312 [DeadReckonUsingMultipleVelocitySourcesVector](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:05.313Z,1408596725.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:05.314Z,1408596725.314 [DeadReckonWithRespectToWater](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:05.315Z,1408596725.315 [DeadReckonWithRespectToSeafloor](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:05.316Z,1408596725.316 [DeadReckonUsingDVLWaterTrack](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:05.317Z,1408596725.317 [DeadReckonUsingCompactModelForecast](DEBUG): Could not determine rotation from vehicle frame to navigation frame.
2014-08-21T04:52:05.618Z,1408596725.618 [SBIT](IMPORTANT): Beginning Startup BIT
2014-08-21T04:52:05.622Z,1408596725.622 [CBIT](IMPORTANT): Beginning GF scan
2014-08-21T04:52:08.262Z,1408596728.262 [NAL9602](INFO): NAL9602 initialized
2014-08-21T04:52:08.698Z,1408596728.698 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003907
2014-08-21T04:52:10.881Z,1408596730.881 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:11.504Z,1408596731.504 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:11.552Z,1408596731.552 [DAT](INFO): entering command mode
2014-08-21T04:52:12.199Z,1408596732.199 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:12.782Z,1408596732.782 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:12.796Z,1408596732.796 [DAT](INFO): entering command mode
2014-08-21T04:52:13.503Z,1408596733.503 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:13.541Z,1408596733.541 [DAT](INFO): entering command mode
2014-08-21T04:52:14.132Z,1408596734.132 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:14.158Z,1408596734.158 [DAT](INFO): entering command mode
2014-08-21T04:52:14.674Z,1408596734.674 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:14.695Z,1408596734.695 [DAT](INFO): entering command mode
2014-08-21T04:52:15.246Z,1408596735.246 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:15.320Z,1408596735.320 [DAT](INFO): entering command mode
2014-08-21T04:52:15.903Z,1408596735.903 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:15.920Z,1408596735.920 [DAT](INFO): entering command mode
2014-08-21T04:52:16.472Z,1408596736.472 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:16.485Z,1408596736.485 [DAT](FAULT): failed to initialize; deviceResponse_:user:1> 00800 bits/sec
2014-08-21T04:52:16.485Z,1408596736.485 [DAT] Communications Fault, FailCount= 1
2014-08-21T04:52:16.485Z,1408596736.485 [DAT](ERROR): Communications Fault
2014-08-21T04:52:16.691Z,1408596736.691 [CBIT](ERROR): Communications Fault in component: DAT
2014-08-21T04:52:17.162Z,1408596737.162 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:17.190Z,1408596737.190 [DAT](INFO): Powering down
2014-08-21T04:52:17.930Z,1408596737.930 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:18.467Z,1408596738.467 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:18.726Z,1408596738.726 [CBIT](INFO): Clearing failed state for component DAT
2014-08-21T04:52:18.726Z,1408596738.726 [DAT] No Fault, FailCount= 1
2014-08-21T04:52:19.096Z,1408596739.096 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:19.662Z,1408596739.662 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:20.101Z,1408596740.101 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:20.534Z,1408596740.534 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:20.543Z,1408596740.543 [DAT](INFO): Powering up
2014-08-21T04:52:20.543Z,1408596740.543 [DAT](DEBUG): Initializing DAT.
2014-08-21T04:52:21.047Z,1408596741.047 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:21.724Z,1408596741.724 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:22.754Z,1408596742.754 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:23.287Z,1408596743.287 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:23.803Z,1408596743.803 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:24.317Z,1408596744.317 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:24.825Z,1408596744.825 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:25.353Z,1408596745.353 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:25.983Z,1408596745.983 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:26.511Z,1408596746.511 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:27.229Z,1408596747.229 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:27.942Z,1408596747.942 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:28.402Z,1408596748.402 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:28.875Z,1408596748.875 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:29.424Z,1408596749.424 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:29.897Z,1408596749.897 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:30.382Z,1408596750.382 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:30.931Z,1408596750.931 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:31.411Z,1408596751.411 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:31.885Z,1408596751.885 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:32.500Z,1408596752.500 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:32.689Z,1408596752.689 [CBIT](IMPORTANT): No ground fault detected
2014-08-21T04:52:33.062Z,1408596753.062 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:33.546Z,1408596753.546 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:34.095Z,1408596754.095 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:34.545Z,1408596754.545 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:34.984Z,1408596754.984 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:35.026Z,1408596755.026 [DAT](INFO): entering command mode
2014-08-21T04:52:35.465Z,1408596755.465 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:35.991Z,1408596755.991 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:36.088Z,1408596756.088 [DAT](INFO): entering command mode
2014-08-21T04:52:36.535Z,1408596756.535 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:36.545Z,1408596756.545 [DAT](INFO): entering command mode
2014-08-21T04:52:36.000Z,1408596757.000 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:37.022Z,1408596757.022 [DAT](INFO): entering command mode
2014-08-21T04:52:37.541Z,1408596757.541 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:37.594Z,1408596757.594 [DAT](INFO): entering command mode
2014-08-21T04:52:38.061Z,1408596758.061 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:38.091Z,1408596758.091 [DAT](INFO): entering command mode
2014-08-21T04:52:38.542Z,1408596758.542 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:38.572Z,1408596758.572 [DAT](INFO): entering command mode
2014-08-21T04:52:39.008Z,1408596759.008 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:39.033Z,1408596759.033 [DAT](INFO): entering command mode
2014-08-21T04:52:39.491Z,1408596759.491 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:39.549Z,1408596759.549 [DAT](INFO): entering command mode
2014-08-21T04:52:39.992Z,1408596759.992 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:40.005Z,1408596760.005 [DAT](FAULT): failed to initialize; deviceResponse_:user:1> 00800 bits/sec
2014-08-21T04:52:40.006Z,1408596760.006 [DAT] Communications Fault, FailCount= 2
2014-08-21T04:52:40.006Z,1408596760.006 [DAT](ERROR): Communications Fault
2014-08-21T04:52:40.098Z,1408596760.098 [CBIT](ERROR): Communications Fault in component: DAT
2014-08-21T04:52:40.481Z,1408596760.481 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:40.494Z,1408596760.494 [DAT](INFO): Powering down
2014-08-21T04:52:41.106Z,1408596761.106 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:41.547Z,1408596761.547 [Batt_Ocean_Server](ERROR): Battery controller B communication timeout. Re-initializing
2014-08-21T04:52:41.551Z,1408596761.551 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:41.657Z,1408596761.657 [CBIT](INFO): Clearing failed state for component DAT
2014-08-21T04:52:41.658Z,1408596761.658 [DAT] No Fault, FailCount= 2
2014-08-21T04:52:42.028Z,1408596762.028 [Batt_Ocean_Server] Communications Fault, FailCount= 1
2014-08-21T04:52:42.028Z,1408596762.028 [Batt_Ocean_Server](ERROR): Communications Fault
2014-08-21T04:52:43.465Z,1408596763.465 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout
2014-08-21T04:52:43.465Z,1408596763.465 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing
2014-08-21T04:52:43.493Z,1408596763.493 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:43.578Z,1408596763.578 [DAT](INFO): Powering up
2014-08-21T04:52:43.578Z,1408596763.578 [DAT](DEBUG): Initializing DAT.
2014-08-21T04:52:43.685Z,1408596763.685 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server
2014-08-21T04:52:44.107Z,1408596764.107 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:44.190Z,1408596764.190 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server
2014-08-21T04:52:44.190Z,1408596764.190 [Batt_Ocean_Server] No Fault, FailCount= 1
2014-08-21T04:52:46.322Z,1408596766.322 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-08-21T04:52:46.354Z,1408596766.354 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:46.794Z,1408596766.794 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:47.219Z,1408596767.219 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:47.672Z,1408596767.672 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:48.088Z,1408596768.088 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:48.521Z,1408596768.521 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:49.093Z,1408596769.093 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:49.920Z,1408596769.920 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:50.452Z,1408596770.452 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:50.962Z,1408596770.962 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:51.582Z,1408596771.582 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:52.144Z,1408596772.144 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:52.793Z,1408596772.793 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:53.213Z,1408596773.213 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:53.661Z,1408596773.661 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:54.110Z,1408596774.110 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:54.575Z,1408596774.575 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:55.032Z,1408596775.032 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:55.453Z,1408596775.453 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:55.955Z,1408596775.955 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:56.413Z,1408596776.413 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:56.924Z,1408596776.924 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:57.356Z,1408596777.356 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:57.792Z,1408596777.792 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:57.818Z,1408596777.818 [DAT](INFO): entering command mode
2014-08-21T04:52:58.693Z,1408596778.693 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:59.114Z,1408596779.114 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:59.124Z,1408596779.124 [DAT](INFO): entering command mode
2014-08-21T04:52:59.538Z,1408596779.538 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:52:59.596Z,1408596779.596 [DAT](INFO): entering command mode
2014-08-21T04:52:59.999Z,1408596779.999 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:00.021Z,1408596780.021 [DAT](INFO): entering command mode
2014-08-21T04:53:00.421Z,1408596780.421 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:00.446Z,1408596780.446 [DAT](INFO): entering command mode
2014-08-21T04:53:00.500Z,1408596780.500 [SBIT](IMPORTANT): SBIT PASSED
2014-08-21T04:53:00.868Z,1408596780.868 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:00.907Z,1408596780.907 [DAT](INFO): entering command mode
2014-08-21T04:53:00.952Z,1408596780.952 [MissionManager](IMPORTANT): Started mission Startup
2014-08-21T04:53:00.952Z,1408596780.952 [Startup] Running Loop=1
2014-08-21T04:53:00.953Z,1408596780.953 [Startup](INFO): Aggregate::initialize Startup
2014-08-21T04:53:00.953Z,1408596780.953 [Startup:A.GoToSurface] Running Loop=1
2014-08-21T04:53:00.953Z,1408596780.953 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-08-21T04:53:00.953Z,1408596780.953 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2014-08-21T04:53:00.954Z,1408596780.954 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2014-08-21T04:53:00.954Z,1408596780.954 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 0.500000 m/s.
2014-08-21T04:53:01.021Z,1408596781.021 [Startup:StartupSatComms] Running Loop=1
2014-08-21T04:53:01.021Z,1408596781.021 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-08-21T04:53:01.021Z,1408596781.021 [Startup:StartupSatComms:A] Running Loop=1
2014-08-21T04:53:01.498Z,1408596781.498 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:01.520Z,1408596781.520 [DAT](INFO): entering command mode
2014-08-21T04:53:01.595Z,1408596781.595 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-08-21T04:53:02.088Z,1408596782.088 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:02.130Z,1408596782.130 [DAT](INFO): entering command mode
2014-08-21T04:53:02.615Z,1408596782.615 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:02.709Z,1408596782.709 [DAT](FAULT): failed to initialize; deviceResponse_:user:1> 00800 bits/sec
2014-08-21T04:53:02.710Z,1408596782.710 [DAT] Communications Fault, FailCount= 3
2014-08-21T04:53:02.710Z,1408596782.710 [DAT](ERROR): Communications Fault
2014-08-21T04:53:03.222Z,1408596783.222 [CBIT](ERROR): Communications Fault in component: DAT
2014-08-21T04:53:03.605Z,1408596783.605 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:03.658Z,1408596783.658 [DAT](INFO): Powering down
2014-08-21T04:53:04.283Z,1408596784.283 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:04.299Z,1408596784.299 [NAL9602](IMPORTANT): GPS fix at: 1408596782.00
2014-08-21T04:53:04.517Z,1408596784.517 [Startup:StartupSatComms:A] Stopped
2014-08-21T04:53:04.517Z,1408596784.517 [Startup:StartupSatComms:B] Running Loop=1
2014-08-21T04:53:05.007Z,1408596785.007 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:05.066Z,1408596785.066 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:05.068Z,1408596785.068 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:05.175Z,1408596785.175 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-08-21T04:53:05.242Z,1408596785.242 [CBIT](INFO): Clearing failed state for component DAT
2014-08-21T04:53:05.242Z,1408596785.242 [DAT] No Fault, FailCount= 3
2014-08-21T04:53:06.554Z,1408596786.554 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:06.666Z,1408596786.666 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:06.668Z,1408596786.668 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:07.446Z,1408596787.446 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:07.623Z,1408596787.623 [DAT](INFO): Powering up
2014-08-21T04:53:07.623Z,1408596787.623 [DAT](DEBUG): Initializing DAT.
2014-08-21T04:53:07.705Z,1408596787.705 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:07.707Z,1408596787.707 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:09.903Z,1408596789.903 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:09.980Z,1408596789.980 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:09.999Z,1408596789.999 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:10.697Z,1408596790.697 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20140821T044415/Courier0004.lzma
2014-08-21T04:53:10.708Z,1408596790.708 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:10.868Z,1408596790.868 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:10.870Z,1408596790.870 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:11.427Z,1408596791.427 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:11.517Z,1408596791.517 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:11.519Z,1408596791.519 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:12.171Z,1408596792.171 [DataOverHttps](INFO): Moved sent file to Logs/20140821T044415/Courier0004.lzma.bak
2014-08-21T04:53:12.171Z,1408596792.171 [DataOverHttps](INFO): SBD MOMSN=1090332
2014-08-21T04:53:12.235Z,1408596792.235 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:12.277Z,1408596792.277 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:12.279Z,1408596792.279 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:12.314Z,1408596792.314 [CommandLine](IMPORTANT): got command restart application
2014-08-21T04:53:13.001Z,1408596793.001 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:13.001Z,1408596793.001 [NAL9602](INFO): Powering down
2014-08-21T04:53:13.029Z,1408596793.029 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:13.035Z,1408596793.035 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:13.321Z,1408596793.321 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:13.322Z,1408596793.322 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:13.482Z,1408596793.482 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-08-21T04:53:13.482Z,1408596793.482 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:13.483Z,1408596793.483 [CommandLine](INFO): Join timeout helper Thread ID is 2294
2014-08-21T04:53:13.507Z,1408596793.507 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-08-21T04:53:13.507Z,1408596793.507 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:13.508Z,1408596793.508 [NavChartDb](INFO): Join timeout helper Thread ID is 2295
2014-08-21T04:53:13.524Z,1408596793.524 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:13.546Z,1408596793.546 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:13.550Z,1408596793.550 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:13.550Z,1408596793.550 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:13.575Z,1408596793.575 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-08-21T04:53:13.575Z,1408596793.575 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:13.575Z,1408596793.575 [Radio_Freewave](INFO): Join timeout helper Thread ID is 2296
2014-08-21T04:53:13.577Z,1408596793.577 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:13.767Z,1408596793.767 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:13.864Z,1408596793.864 [Radio_Freewave](INFO): Powering down
2014-08-21T04:53:14.268Z,1408596794.268 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.288Z,1408596794.288 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-08-21T04:53:14.288Z,1408596794.288 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.288Z,1408596794.288 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2298
2014-08-21T04:53:14.452Z,1408596794.452 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:14.460Z,1408596794.460 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:14.461Z,1408596794.461 [WetLabsBB2FL](INFO): Powering down
2014-08-21T04:53:14.462Z,1408596794.462 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.480Z,1408596794.480 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-08-21T04:53:14.480Z,1408596794.480 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.480Z,1408596794.480 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2299
2014-08-21T04:53:14.499Z,1408596794.499 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:14.501Z,1408596794.501 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:14.805Z,1408596794.805 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:14.805Z,1408596794.805 [CTD_NeilBrown](INFO): Powering down
2014-08-21T04:53:14.806Z,1408596794.806 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.810Z,1408596794.810 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-08-21T04:53:14.810Z,1408596794.810 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.810Z,1408596794.810 [logger](INFO): Join timeout helper Thread ID is 2300
2014-08-21T04:53:14.817Z,1408596794.817 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:14.817Z,1408596794.817 [logger ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.829Z,1408596794.829 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-08-21T04:53:14.830Z,1408596794.830 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.830Z,1408596794.830 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-08-21T04:53:14.830Z,1408596794.830 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:14.830Z,1408596794.830 [controlThread](INFO): Join timeout helper Thread ID is 2301
2014-08-21T04:53:14.961Z,1408596794.961 [DVL_micro](DEBUG): LCB error: Software Overcurrent.
2014-08-21T04:53:14.976Z,1408596794.976 [HFRCMReconstructedInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:14.978Z,1408596794.978 [HFRCMSpaceInterpolator](DEBUG): requested location (36.803207, -121.786674) is outside the bounding box
2014-08-21T04:53:15.003Z,1408596795.003 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-08-21T04:53:15.003Z,1408596795.003 [controlThread](DEBUG): Uninitializing ControlThread
2014-08-21T04:53:15.004Z,1408596795.004 [AHRS_sp3003D](INFO): Powering down
2014-08-21T04:53:15.161Z,1408596795.161 [DVL_micro](INFO): uninitialize:Powering down
2014-08-21T04:53:15.162Z,1408596795.162 [NAL9602](INFO): Powering down
2014-08-21T04:53:15.164Z,1408596795.164 [DAT](INFO): Powering down
2014-08-21T04:53:15.282Z,1408596795.282 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-08-21T04:53:15.285Z,1408596795.285 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-08-21T04:53:15.289Z,1408596795.289 [Startup] Stopped
2014-08-21T04:53:15.289Z,1408596795.289 [Startup](INFO): Aggregate::uninitialize Startup
2014-08-21T04:53:15.289Z,1408596795.289 [Startup:A.GoToSurface] Stopped
2014-08-21T04:53:15.289Z,1408596795.289 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-08-21T04:53:15.289Z,1408596795.289 [Startup:StartupSatComms] Stopped
2014-08-21T04:53:15.289Z,1408596795.289 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-08-21T04:53:15.289Z,1408596795.289 [Startup:StartupSatComms:B] Stopped
2014-08-21T04:53:15.291Z,1408596795.291 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-08-21T04:53:15.291Z,1408596795.291 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-08-21T04:53:15.292Z,1408596795.292 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-08-21T04:53:15.292Z,1408596795.292 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-08-21T04:53:15.292Z,1408596795.292 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-08-21T04:53:15.292Z,1408596795.292 [BuoyancyServo](INFO): Powering down
2014-08-21T04:53:15.305Z,1408596795.305 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-08-21T04:53:15.306Z,1408596795.306 [ElevatorServo](INFO): Powering down
2014-08-21T04:53:15.306Z,1408596795.306 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-08-21T04:53:15.307Z,1408596795.307 [MassServo](INFO): Powering down
2014-08-21T04:53:15.307Z,1408596795.307 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-08-21T04:53:15.308Z,1408596795.308 [RudderServo](INFO): Powering down
2014-08-21T04:53:15.308Z,1408596795.308 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-08-21T04:53:15.309Z,1408596795.309 [ThrusterServo](INFO): Powering down
2014-08-21T04:53:15.310Z,1408596795.310 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-08-21T04:53:15.310Z,1408596795.310 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-08-21T04:53:15.310Z,1408596795.310 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-08-21T04:53:15.355Z,1408596795.355 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:15.466Z,1408596795.466 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:15.470Z,1408596795.470 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:15.479Z,1408596795.479 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:15.542Z,1408596795.542 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-08-21T04:53:15.586Z,1408596795.586 [logger ThreadHandler](INFO): Thread cancelled.