2014-05-21T01:29:36.904Z,1400635776.904 [Supervisor](DEBUG): Initializing supervisor.
2014-05-21T01:29:36.906Z,1400635776.906 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-05-21T01:29:36.907Z,1400635776.907 [SyncHandler](INFO): Protected caller Thread ID is 1489
2014-05-21T01:29:36.907Z,1400635776.907 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-05-21T01:29:36.908Z,1400635776.908 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-05-21T01:29:36.909Z,1400635776.909 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1490
2014-05-21T01:29:36.912Z,1400635776.912 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-05-21T01:29:36.923Z,1400635776.923 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-05-21T01:29:36.924Z,1400635776.924 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-05-21T01:29:36.924Z,1400635776.924 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1491
2014-05-21T01:29:36.925Z,1400635776.925 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-05-21T01:29:36.926Z,1400635776.926 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-05-21T01:29:36.927Z,1400635776.927 [logger ThreadHandler](INFO): Protected caller Thread ID is 1492
2014-05-21T01:29:36.929Z,1400635776.929 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-05-21T01:29:36.929Z,1400635776.929 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-05-21T01:29:36.931Z,1400635776.931 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-05-21T01:29:37.280Z,1400635777.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-05-21T01:29:37.281Z,1400635777.281 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-05-21T01:29:37.557Z,1400635777.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-05-21T01:29:37.558Z,1400635777.558 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-05-21T01:29:37.644Z,1400635777.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-05-21T01:29:37.645Z,1400635777.645 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-05-21T01:29:37.821Z,1400635777.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-05-21T01:29:37.822Z,1400635777.822 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-05-21T01:29:37.982Z,1400635777.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-05-21T01:29:37.983Z,1400635777.983 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-05-21T01:29:38.280Z,1400635778.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-05-21T01:29:38.281Z,1400635778.281 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-05-21T01:29:38.461Z,1400635778.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-05-21T01:29:38.462Z,1400635778.462 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-05-21T01:29:38.789Z,1400635778.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-05-21T01:29:38.790Z,1400635778.790 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-05-21T01:29:38.899Z,1400635778.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-05-21T01:29:38.900Z,1400635778.900 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-05-21T01:29:39.391Z,1400635779.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-05-21T01:29:39.391Z,1400635779.391 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-05-21T01:29:39.500Z,1400635779.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-05-21T01:29:39.501Z,1400635779.501 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-05-21T01:29:39.587Z,1400635779.587 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-05-21T01:29:39.698Z,1400635779.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-05-21T01:29:39.699Z,1400635779.699 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-05-21T01:29:39.802Z,1400635779.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-05-21T01:29:39.803Z,1400635779.803 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-05-21T01:29:39.940Z,1400635779.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-05-21T01:29:39.942Z,1400635779.942 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-05-21T01:29:39.943Z,1400635779.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-05-21T01:29:40.051Z,1400635780.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-05-21T01:29:40.182Z,1400635780.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-05-21T01:29:40.279Z,1400635780.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-05-21T01:29:40.373Z,1400635780.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-05-21T01:29:40.519Z,1400635780.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-05-21T01:29:40.637Z,1400635780.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-05-21T01:29:40.724Z,1400635780.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-05-21T01:29:40.829Z,1400635780.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-05-21T01:29:40.931Z,1400635780.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-05-21T01:29:41.030Z,1400635781.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-05-21T01:29:41.119Z,1400635781.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-05-21T01:29:41.220Z,1400635781.220 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-05-21T01:29:41.221Z,1400635781.221 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-05-21T01:29:41.225Z,1400635781.225 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-05-21T01:29:41.572Z,1400635781.572 [AHRS_sp3003D] Loaded
2014-05-21T01:29:41.572Z,1400635781.572 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-05-21T01:29:41.926Z,1400635781.926 [Batt_Ocean_Server] Loaded
2014-05-21T01:29:41.926Z,1400635781.926 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-05-21T01:29:41.999Z,1400635781.999 [DataOverHttps] Loaded
2014-05-21T01:29:41.000Z,1400635782.000 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-05-21T01:29:42.013Z,1400635782.013 [Depth_Keller] Loaded
2014-05-21T01:29:42.014Z,1400635782.014 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-05-21T01:29:42.157Z,1400635782.157 [DVL_micro] Loaded
2014-05-21T01:29:42.157Z,1400635782.157 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-05-21T01:29:42.259Z,1400635782.259 [NAL9602] Loaded
2014-05-21T01:29:42.259Z,1400635782.259 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-05-21T01:29:42.313Z,1400635782.313 [Onboard] Loaded
2014-05-21T01:29:42.313Z,1400635782.313 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-05-21T01:29:42.320Z,1400635782.320 [Radio_Freewave] Loaded
2014-05-21T01:29:42.320Z,1400635782.320 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-05-21T01:29:42.321Z,1400635782.321 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 4060F4E0
2014-05-21T01:29:42.322Z,1400635782.322 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 1574
2014-05-21T01:29:42.328Z,1400635782.328 [SCPI] Loaded
2014-05-21T01:29:42.329Z,1400635782.329 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-05-21T01:29:42.329Z,1400635782.329 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-05-21T01:29:42.330Z,1400635782.330 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-05-21T01:29:42.491Z,1400635782.491 [InternalSim] Loaded
2014-05-21T01:29:42.492Z,1400635782.492 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-05-21T01:29:42.492Z,1400635782.492 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-05-21T01:29:42.493Z,1400635782.493 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-05-21T01:29:42.739Z,1400635782.739 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-05-21T01:29:42.739Z,1400635782.739 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-05-21T01:29:42.745Z,1400635782.745 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-05-21T01:29:42.746Z,1400635782.746 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-05-21T01:29:42.828Z,1400635782.828 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-05-21T01:29:42.828Z,1400635782.828 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-05-21T01:29:42.875Z,1400635782.875 [DeadReckonWithRespectToWater] Loaded
2014-05-21T01:29:42.875Z,1400635782.875 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-05-21T01:29:42.922Z,1400635782.922 [DeadReckonWithRespectToSeafloor] Loaded
2014-05-21T01:29:42.922Z,1400635782.922 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-05-21T01:29:42.969Z,1400635782.969 [DeadReckonUsingDVLWaterTrack] Loaded
2014-05-21T01:29:42.969Z,1400635782.969 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-05-21T01:29:43.018Z,1400635783.018 [DeadReckonUsingCompactModelForecast] Loaded
2014-05-21T01:29:43.018Z,1400635783.018 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-05-21T01:29:43.034Z,1400635783.034 [NavChart] Loaded
2014-05-21T01:29:43.035Z,1400635783.035 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-05-21T01:29:43.039Z,1400635783.039 [UniversalFixResidualReporter] Loaded
2014-05-21T01:29:43.039Z,1400635783.039 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-05-21T01:29:43.040Z,1400635783.040 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-05-21T01:29:43.040Z,1400635783.040 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-05-21T01:29:43.090Z,1400635783.090 [VerticalControl](DEBUG): Construct VerticalControl.
2014-05-21T01:29:43.203Z,1400635783.203 [VerticalControl] Loaded
2014-05-21T01:29:43.204Z,1400635783.204 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-05-21T01:29:43.205Z,1400635783.205 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-05-21T01:29:43.273Z,1400635783.273 [HorizontalControl] Loaded
2014-05-21T01:29:43.273Z,1400635783.273 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-05-21T01:29:43.274Z,1400635783.274 [SpeedControl](DEBUG): Construct SpeedControl.
2014-05-21T01:29:43.275Z,1400635783.275 [SpeedControl] Loaded
2014-05-21T01:29:43.276Z,1400635783.276 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-05-21T01:29:43.276Z,1400635783.276 [LoopControl](DEBUG): Construct LoopControl.
2014-05-21T01:29:43.277Z,1400635783.277 [LoopControl] Loaded
2014-05-21T01:29:43.277Z,1400635783.277 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-05-21T01:29:43.278Z,1400635783.278 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-05-21T01:29:43.278Z,1400635783.278 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-05-21T01:29:43.297Z,1400635783.297 [DepthRateCalculator] Loaded
2014-05-21T01:29:43.297Z,1400635783.297 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-05-21T01:29:43.303Z,1400635783.303 [PitchRateCalculator] Loaded
2014-05-21T01:29:43.303Z,1400635783.303 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-05-21T01:29:43.310Z,1400635783.310 [SpeedCalculator] Loaded
2014-05-21T01:29:43.311Z,1400635783.311 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-05-21T01:29:43.328Z,1400635783.328 [TempGradientCalculator] Loaded
2014-05-21T01:29:43.328Z,1400635783.328 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-05-21T01:29:43.333Z,1400635783.333 [YawRateCalculator] Loaded
2014-05-21T01:29:43.334Z,1400635783.334 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-05-21T01:29:43.334Z,1400635783.334 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-05-21T01:29:43.335Z,1400635783.335 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-05-21T01:29:43.443Z,1400635783.443 [CTD_NeilBrown] Loaded
2014-05-21T01:29:43.443Z,1400635783.443 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-05-21T01:29:43.445Z,1400635783.445 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4075E4E0
2014-05-21T01:29:43.445Z,1400635783.445 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1575
2014-05-21T01:29:43.458Z,1400635783.458 [ISUS] Loaded
2014-05-21T01:29:43.458Z,1400635783.458 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2014-05-21T01:29:43.473Z,1400635783.473 [PAR_Licor] Loaded
2014-05-21T01:29:43.473Z,1400635783.473 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-05-21T01:29:43.509Z,1400635783.509 [WetLabsBB2FL] Loaded
2014-05-21T01:29:43.509Z,1400635783.509 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-05-21T01:29:43.510Z,1400635783.510 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4078E4E0
2014-05-21T01:29:43.511Z,1400635783.511 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1576
2014-05-21T01:29:43.511Z,1400635783.511 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-05-21T01:29:43.512Z,1400635783.512 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-05-21T01:29:43.607Z,1400635783.607 [SBIT](DEBUG): Construct Startup Built In Test.
2014-05-21T01:29:43.619Z,1400635783.619 [SBIT] Loaded
2014-05-21T01:29:43.619Z,1400635783.619 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-05-21T01:29:43.620Z,1400635783.620 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-05-21T01:29:43.633Z,1400635783.633 [IBIT] Loaded
2014-05-21T01:29:43.633Z,1400635783.633 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-05-21T01:29:43.635Z,1400635783.635 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-05-21T01:29:43.705Z,1400635783.705 [CBIT] Loaded
2014-05-21T01:29:43.706Z,1400635783.706 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-05-21T01:29:43.706Z,1400635783.706 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-05-21T01:29:43.707Z,1400635783.707 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-05-21T01:29:48.307Z,1400635788.307 [HFRadarModelCalc] Loaded
2014-05-21T01:29:48.307Z,1400635788.307 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2014-05-21T01:29:51.897Z,1400635791.897 [HFRadarCompactModelForecaster] Loaded
2014-05-21T01:29:51.897Z,1400635791.897 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-05-21T01:29:52.885Z,1400635792.885 [HFRCMSpaceInterpolator] Loaded
2014-05-21T01:29:52.885Z,1400635792.885 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-05-21T01:29:52.887Z,1400635792.887 [HFRCMTimeInterpolator] Loaded
2014-05-21T01:29:52.887Z,1400635792.887 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-05-21T01:29:52.900Z,1400635792.900 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-05-21T01:29:52.901Z,1400635792.901 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-05-21T01:29:52.929Z,1400635792.929 [HFRCMVirtualSurfaceDrifter] Loaded
2014-05-21T01:29:52.929Z,1400635792.929 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-05-21T01:29:52.930Z,1400635792.930 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-05-21T01:29:52.930Z,1400635792.930 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-05-21T01:29:53.033Z,1400635793.033 [BuoyancyServo] Loaded
2014-05-21T01:29:53.033Z,1400635793.033 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-05-21T01:29:53.045Z,1400635793.045 [ElevatorServo] Loaded
2014-05-21T01:29:53.046Z,1400635793.046 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-05-21T01:29:53.057Z,1400635793.057 [MassServo] Loaded
2014-05-21T01:29:53.058Z,1400635793.058 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-05-21T01:29:53.071Z,1400635793.071 [RudderServo] Loaded
2014-05-21T01:29:53.071Z,1400635793.071 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-05-21T01:29:53.082Z,1400635793.082 [ThrusterServo] Loaded
2014-05-21T01:29:53.083Z,1400635793.083 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-05-21T01:29:53.083Z,1400635793.083 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-05-21T01:29:53.084Z,1400635793.084 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-05-21T01:29:53.108Z,1400635793.108 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-05-21T01:29:53.110Z,1400635793.110 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-05-21T01:29:53.111Z,1400635793.111 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-05-21T01:29:53.117Z,1400635793.117 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-05-21T01:29:53.118Z,1400635793.118 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0
2014-05-21T01:29:53.119Z,1400635793.119 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1577
2014-05-21T01:29:53.124Z,1400635793.124 [Supervisor](INFO): Main Thread ID is 1349
2014-05-21T01:29:53.124Z,1400635793.124 [Supervisor](DEBUG): Running supervisor.
2014-05-21T01:29:53.125Z,1400635793.125 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1578
2014-05-21T01:29:53.129Z,1400635793.129 [controlThread ThreadHandler](INFO): Handler Thread ID is 1579
2014-05-21T01:29:53.130Z,1400635793.130 [controlThread](DEBUG): Initializing ControlThread
2014-05-21T01:29:53.133Z,1400635793.133 [logger ThreadHandler](INFO): Handler Thread ID is 1580
2014-05-21T01:29:53.152Z,1400635793.152 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 1581
2014-05-21T01:29:53.168Z,1400635793.168 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1582
2014-05-21T01:29:53.168Z,1400635793.168 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-05-21T01:29:53.171Z,1400635793.171 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-05-21T01:29:53.181Z,1400635793.181 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1584
2014-05-21T01:29:53.182Z,1400635793.182 [WetLabsBB2FL](INFO): Powering down
2014-05-21T01:29:53.210Z,1400635793.210 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1585
2014-05-21T01:29:53.213Z,1400635793.213 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-05-21T01:29:53.213Z,1400635793.213 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-05-21T01:29:53.214Z,1400635793.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-05-21T01:29:53.214Z,1400635793.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-05-21T01:29:53.214Z,1400635793.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-05-21T01:29:53.214Z,1400635793.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-05-21T01:29:53.215Z,1400635793.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-05-21T01:29:53.215Z,1400635793.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-05-21T01:29:53.216Z,1400635793.216 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-05-21T01:29:54.610Z,1400635794.610 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-05-21T01:29:54.639Z,1400635794.639 [InternalSim](DEBUG):  InternalSim initializing...
2014-05-21T01:29:54.757Z,1400635794.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-05-21T01:29:54.757Z,1400635794.757 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-05-21T01:29:54.802Z,1400635794.802 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-05-21T01:29:54.803Z,1400635794.803 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-05-21T01:29:54.803Z,1400635794.803 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-05-21T01:29:54.804Z,1400635794.804 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-05-21T01:29:54.804Z,1400635794.804 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-05-21T01:29:54.805Z,1400635794.805 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-05-21T01:29:54.811Z,1400635794.811 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-05-21T01:29:54.812Z,1400635794.812 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-05-21T01:29:54.813Z,1400635794.813 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-05-21T01:29:54.813Z,1400635794.813 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-05-21T01:29:54.835Z,1400635794.835 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-05-21T01:29:54.835Z,1400635794.835 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-05-21T01:29:54.836Z,1400635794.836 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-05-21T01:29:54.837Z,1400635794.837 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-05-21T01:29:54.862Z,1400635794.862 [SBIT](INFO): Initialize SBIT Component.
2014-05-21T01:29:54.863Z,1400635794.863 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:11320
2014-05-21T01:29:54.863Z,1400635794.863 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-05-21T01:29:54.864Z,1400635794.864 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-05-21T01:29:54.864Z,1400635794.864 [IBIT](INFO): Initialize IBIT Component.
2014-05-21T01:29:54.878Z,1400635794.878 [CBIT](DEBUG): Initialize CBIT Component.
2014-05-21T01:29:54.878Z,1400635794.878 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-05-21T01:29:54.909Z,1400635794.909 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-05-21T01:29:55.047Z,1400635795.047 [Radio_Freewave](INFO): Powering up
2014-05-21T01:29:55.856Z,1400635795.856 [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-05-21T01:30:04.081Z,1400635804.081 [HFRadarCompactModelForecaster](IMPORTANT): Forecast time 201405202200: published 50 modes in rows, 61 hours in columns, 3050 elements.
2014-05-21T01:30:04.091Z,1400635804.091 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component.
2014-05-21T01:30:04.109Z,1400635804.109 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-05-21T01:30:04.110Z,1400635804.110 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-05-21T01:30:04.110Z,1400635804.110 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-05-21T01:30:04.112Z,1400635804.112 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-05-21T01:30:04.143Z,1400635804.143 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:30:04.172Z,1400635804.172 [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-05-21T01:30:04.173Z,1400635804.173 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-05-21T01:30:04.257Z,1400635804.257 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-05-21T01:30:04.259Z,1400635804.259 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-05-21T01:30:04.262Z,1400635804.262 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:30:04.303Z,1400635804.303 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-05-21T01:30:04.306Z,1400635804.306 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:30:04.331Z,1400635804.331 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-05-21T01:30:04.370Z,1400635804.370 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-05-21T01:30:04.373Z,1400635804.373 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:30:04.393Z,1400635804.393 [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="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS">

        <When>
            <Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface>
            <Sequence/>
        </Guidance:GoToSurface>

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS">
            <Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Sequence/><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium">

        <Sequence/>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface>
            <Sequence/>
        </Guidance:GoToSurface>

<!-- 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="CallIridium">

        <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>

        <Assign><Sequence/><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

        <Guidance:GoToSurface>
            <Parallel/>
        </Guidance:GoToSurface>

    </Aggregate>

</Mission>


2014-05-21T01:30:04.411Z,1400635804.411 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-05-21T01:30:04.477Z,1400635804.477 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-05-21T01:30:05.455Z,1400635805.455 [DVL_micro](INFO): Initializing
2014-05-21T01:30:05.502Z,1400635805.502 [NAL9602](INFO): Powering up NAL9602
2014-05-21T01:30:06.127Z,1400635806.127 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:06.491Z,1400635806.491 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:30:06.500Z,1400635806.500 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-05-21T01:30:06.506Z,1400635806.506 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:30:06.516Z,1400635806.516 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-05-21T01:30:06.546Z,1400635806.546 [MassServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:30:06.556Z,1400635806.556 [MassServo](DEBUG): Initializing MassServo.
2014-05-21T01:30:06.562Z,1400635806.562 [RudderServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:30:06.573Z,1400635806.573 [RudderServo](DEBUG): Initializing RudderServo.
2014-05-21T01:30:06.602Z,1400635806.602 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:30:06.612Z,1400635806.612 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-05-21T01:30:07.273Z,1400635807.273 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-05-21T01:30:07.273Z,1400635807.273 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-05-21T01:30:07.273Z,1400635807.273 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2014-05-21T01:30:07.273Z,1400635807.273 [AHRS_sp3003D](ERROR): Hardware Fault
2014-05-21T01:30:07.414Z,1400635807.414 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:07.793Z,1400635807.793 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-05-21T01:30:07.905Z,1400635807.905 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:30:08.999Z,1400635808.999 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:09.203Z,1400635809.203 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:09.533Z,1400635809.533 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:09.580Z,1400635809.580 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-05-21T01:30:09.580Z,1400635809.580 [AHRS_sp3003D] No Fault, FailCount= 1
2014-05-21T01:30:09.894Z,1400635809.894 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-05-21T01:30:10.018Z,1400635810.018 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.014419
2014-05-21T01:30:10.052Z,1400635810.052 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:10.346Z,1400635810.346 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:10.912Z,1400635810.912 [SBIT](IMPORTANT): Beginning Startup BIT
2014-05-21T01:30:10.914Z,1400635810.914 [CBIT](IMPORTANT): Beginning GF scan
2014-05-21T01:30:11.315Z,1400635811.315 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-05-21T01:30:11.315Z,1400635811.315 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-05-21T01:30:11.316Z,1400635811.316 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2014-05-21T01:30:11.316Z,1400635811.316 [AHRS_sp3003D](ERROR): Hardware Fault
2014-05-21T01:30:11.497Z,1400635811.497 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:11.708Z,1400635811.708 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-05-21T01:30:11.750Z,1400635811.750 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:30:11.865Z,1400635811.865 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:12.164Z,1400635812.164 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:12.640Z,1400635812.640 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:13.050Z,1400635813.050 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:13.186Z,1400635813.186 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-05-21T01:30:13.186Z,1400635813.186 [AHRS_sp3003D] No Fault, FailCount= 2
2014-05-21T01:30:13.323Z,1400635813.323 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-05-21T01:30:13.456Z,1400635813.456 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:13.783Z,1400635813.783 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:14.456Z,1400635814.456 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-05-21T01:30:14.456Z,1400635814.456 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-05-21T01:30:14.456Z,1400635814.456 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2014-05-21T01:30:14.456Z,1400635814.456 [AHRS_sp3003D](ERROR): Hardware Fault
2014-05-21T01:30:14.536Z,1400635814.536 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:14.693Z,1400635814.693 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-05-21T01:30:14.707Z,1400635814.707 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:30:14.833Z,1400635814.833 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:15.254Z,1400635815.254 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:15.558Z,1400635815.558 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:16.059Z,1400635816.059 [NAL9602](INFO): NAL9602 initialized
2014-05-21T01:30:16.115Z,1400635816.115 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:16.338Z,1400635816.338 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-05-21T01:30:16.338Z,1400635816.338 [AHRS_sp3003D] No Fault, FailCount= 3
2014-05-21T01:30:16.391Z,1400635816.391 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-05-21T01:30:16.449Z,1400635816.449 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:16.856Z,1400635816.856 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:17.493Z,1400635817.493 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-05-21T01:30:17.493Z,1400635817.493 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-05-21T01:30:17.493Z,1400635817.493 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2014-05-21T01:30:17.493Z,1400635817.493 [AHRS_sp3003D](ERROR): Hardware Fault
2014-05-21T01:30:17.817Z,1400635817.817 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:17.934Z,1400635817.934 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-05-21T01:30:17.968Z,1400635817.968 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:30:18.356Z,1400635818.356 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:18.707Z,1400635818.707 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:19.080Z,1400635819.080 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:19.493Z,1400635819.493 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:19.594Z,1400635819.594 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-05-21T01:30:19.594Z,1400635819.594 [AHRS_sp3003D] No Fault, FailCount= 4
2014-05-21T01:30:19.785Z,1400635819.785 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-05-21T01:30:19.921Z,1400635819.921 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:20.482Z,1400635820.482 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:20.750Z,1400635820.750 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-05-21T01:30:20.750Z,1400635820.750 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0xA409A0
2014-05-21T01:30:20.750Z,1400635820.750 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-05-21T01:30:20.750Z,1400635820.750 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2014-05-21T01:30:20.750Z,1400635820.750 [AHRS_sp3003D](ERROR): Hardware Fault
2014-05-21T01:30:20.999Z,1400635820.999 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:21.073Z,1400635821.073 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-05-21T01:30:21.074Z,1400635821.074 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2014-05-21T01:30:21.189Z,1400635821.189 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:30:21.363Z,1400635821.363 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:21.439Z,1400635821.439 [CommandLine](FAULT): Scheduling is paused
2014-05-21T01:30:21.607Z,1400635821.607 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:22.085Z,1400635822.085 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:22.460Z,1400635822.460 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:22.815Z,1400635822.815 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:23.212Z,1400635823.212 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:23.377Z,1400635823.377 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2014-05-21T01:30:23.379Z,1400635823.379 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2014-05-21T01:30:23.380Z,1400635823.380 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2014-05-21T01:30:23.381Z,1400635823.381 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2014-05-21T01:30:23.601Z,1400635823.601 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:23.687Z,1400635823.687 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,DataOverHttps,Depth_Keller,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-05-21T01:30:24.018Z,1400635824.018 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:24.400Z,1400635824.400 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:24.403Z,1400635824.403 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:24.802Z,1400635824.802 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:24.812Z,1400635824.812 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:25.250Z,1400635825.250 [HFRCMSpaceInterpolator](INFO): requested location (36.803402, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:25.252Z,1400635825.252 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:25.782Z,1400635825.782 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:25.784Z,1400635825.784 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:26.001Z,1400635826.001 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:26.011Z,1400635826.011 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:26.822Z,1400635826.822 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:26.824Z,1400635826.824 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:27.110Z,1400635827.110 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:27.121Z,1400635827.121 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:27.510Z,1400635827.510 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:27.512Z,1400635827.512 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:27.938Z,1400635827.938 [HFRCMSpaceInterpolator](INFO): requested location (36.803398, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:27.940Z,1400635827.940 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:28.483Z,1400635828.483 [HFRCMSpaceInterpolator](INFO): requested location (36.803394, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:28.485Z,1400635828.485 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:28.797Z,1400635828.797 [HFRCMSpaceInterpolator](INFO): requested location (36.803394, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:28.836Z,1400635828.836 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:29.161Z,1400635829.161 [HFRCMSpaceInterpolator](INFO): requested location (36.803394, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:29.163Z,1400635829.163 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:29.606Z,1400635829.606 [HFRCMSpaceInterpolator](INFO): requested location (36.803394, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:29.609Z,1400635829.609 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:29.981Z,1400635829.981 [HFRCMSpaceInterpolator](INFO): requested location (36.803394, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:29.984Z,1400635829.984 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:30.473Z,1400635830.473 [HFRCMSpaceInterpolator](INFO): requested location (36.803391, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:30.487Z,1400635830.487 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:31.035Z,1400635831.035 [HFRCMSpaceInterpolator](INFO): requested location (36.803391, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:31.037Z,1400635831.037 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:31.511Z,1400635831.511 [HFRCMSpaceInterpolator](INFO): requested location (36.803391, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:31.513Z,1400635831.513 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:31.964Z,1400635831.964 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2014-05-21T01:30:31.964Z,1400635831.964 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2014-05-21T01:30:32.038Z,1400635832.038 [HFRCMSpaceInterpolator](INFO): requested location (36.803387, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:32.040Z,1400635832.040 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:32.359Z,1400635832.359 [HFRCMSpaceInterpolator](INFO): requested location (36.803387, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:32.369Z,1400635832.369 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:32.785Z,1400635832.785 [HFRCMSpaceInterpolator](INFO): requested location (36.803387, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:32.813Z,1400635832.813 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:33.272Z,1400635833.272 [HFRCMSpaceInterpolator](INFO): requested location (36.803383, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:33.274Z,1400635833.274 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:33.586Z,1400635833.586 [HFRCMSpaceInterpolator](INFO): requested location (36.803383, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:33.588Z,1400635833.588 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:33.953Z,1400635833.953 [HFRCMSpaceInterpolator](INFO): requested location (36.803383, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:33.955Z,1400635833.955 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:34.383Z,1400635834.383 [HFRCMSpaceInterpolator](INFO): requested location (36.803379, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:34.385Z,1400635834.385 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:34.906Z,1400635834.906 [HFRCMSpaceInterpolator](INFO): requested location (36.803379, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:34.920Z,1400635834.920 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:35.265Z,1400635835.265 [HFRCMSpaceInterpolator](INFO): requested location (36.803379, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:35.267Z,1400635835.267 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:35.586Z,1400635835.586 [HFRCMSpaceInterpolator](INFO): requested location (36.803375, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:35.588Z,1400635835.588 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:36.131Z,1400635836.131 [HFRCMSpaceInterpolator](INFO): requested location (36.803375, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:36.133Z,1400635836.133 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:36.409Z,1400635836.409 [HFRCMSpaceInterpolator](INFO): requested location (36.803375, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:36.435Z,1400635836.435 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:36.806Z,1400635836.806 [HFRCMSpaceInterpolator](INFO): requested location (36.803371, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:36.821Z,1400635836.821 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:36.884Z,1400635836.884 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2014-05-21T01:30:36.885Z,1400635836.885 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2014-05-21T01:30:37.177Z,1400635837.177 [HFRCMSpaceInterpolator](INFO): requested location (36.803371, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:37.188Z,1400635837.188 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:37.281Z,1400635837.281 [CBIT](IMPORTANT): No ground fault detected
2014-05-21T01:30:37.609Z,1400635837.609 [HFRCMSpaceInterpolator](INFO): requested location (36.803368, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:37.617Z,1400635837.617 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:38.068Z,1400635838.068 [HFRCMSpaceInterpolator](INFO): requested location (36.803368, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:38.078Z,1400635838.078 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:38.403Z,1400635838.403 [HFRCMSpaceInterpolator](INFO): requested location (36.803368, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:38.405Z,1400635838.405 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:39.016Z,1400635839.016 [HFRCMSpaceInterpolator](INFO): requested location (36.803364, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:39.018Z,1400635839.018 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:39.221Z,1400635839.221 [HFRCMSpaceInterpolator](INFO): requested location (36.803364, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:39.223Z,1400635839.223 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:39.592Z,1400635839.592 [HFRCMSpaceInterpolator](INFO): requested location (36.803364, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:39.594Z,1400635839.594 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:40.072Z,1400635840.072 [HFRCMSpaceInterpolator](INFO): requested location (36.803360, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:40.074Z,1400635840.074 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:40.413Z,1400635840.413 [HFRCMSpaceInterpolator](INFO): requested location (36.803360, -121.822197) is outside grid, returning zeros
2014-05-21T01:30:40.415Z,1400635840.415 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:40.887Z,1400635840.887 [HFRCMSpaceInterpolator](INFO): requested location (36.803356, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:40.906Z,1400635840.906 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:41.281Z,1400635841.281 [HFRCMSpaceInterpolator](INFO): requested location (36.803356, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:41.291Z,1400635841.291 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:41.591Z,1400635841.591 [HFRCMSpaceInterpolator](INFO): requested location (36.803356, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:41.593Z,1400635841.593 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:41.990Z,1400635841.990 [HFRCMSpaceInterpolator](INFO): requested location (36.803352, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:42.000Z,1400635842.000 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:42.303Z,1400635842.303 [CommandLine](IMPORTANT): got command quit
2014-05-21T01:30:42.393Z,1400635842.393 [HFRCMSpaceInterpolator](INFO): requested location (36.803352, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:42.396Z,1400635842.396 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:42.817Z,1400635842.817 [HFRCMSpaceInterpolator](INFO): requested location (36.803352, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:42.820Z,1400635842.820 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:43.190Z,1400635843.190 [HFRCMSpaceInterpolator](INFO): requested location (36.803349, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:43.192Z,1400635843.192 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:43.313Z,1400635843.313 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:43.313Z,1400635843.313 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:43.421Z,1400635843.421 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-05-21T01:30:43.421Z,1400635843.421 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:43.422Z,1400635843.422 [CommandLine](INFO): Join timeout helper Thread ID is 1617
2014-05-21T01:30:43.439Z,1400635843.439 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-05-21T01:30:43.439Z,1400635843.439 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:43.439Z,1400635843.439 [NavChartDb](INFO): Join timeout helper Thread ID is 1618
2014-05-21T01:30:43.599Z,1400635843.599 [HFRCMSpaceInterpolator](INFO): requested location (36.803349, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:43.601Z,1400635843.601 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:43.997Z,1400635843.997 [HFRCMSpaceInterpolator](INFO): requested location (36.803349, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:44.008Z,1400635844.008 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:44.509Z,1400635844.509 [HFRCMSpaceInterpolator](INFO): requested location (36.803345, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:44.525Z,1400635844.525 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:44.776Z,1400635844.776 [HFRCMSpaceInterpolator](INFO): requested location (36.803345, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:44.778Z,1400635844.778 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:45.246Z,1400635845.246 [HFRCMSpaceInterpolator](INFO): requested location (36.803341, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:45.248Z,1400635845.248 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:45.291Z,1400635845.291 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:45.291Z,1400635845.291 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:45.308Z,1400635845.308 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-05-21T01:30:45.308Z,1400635845.308 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:45.309Z,1400635845.309 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1619
2014-05-21T01:30:45.461Z,1400635845.461 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:45.461Z,1400635845.461 [WetLabsBB2FL](INFO): Powering down
2014-05-21T01:30:45.462Z,1400635845.462 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:45.476Z,1400635845.476 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-05-21T01:30:45.476Z,1400635845.476 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:45.477Z,1400635845.477 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1620
2014-05-21T01:30:45.602Z,1400635845.602 [HFRCMSpaceInterpolator](INFO): requested location (36.803341, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:45.604Z,1400635845.604 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:45.873Z,1400635845.873 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:45.874Z,1400635845.874 [CTD_NeilBrown](INFO): Powering down
2014-05-21T01:30:45.874Z,1400635845.874 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:45.889Z,1400635845.889 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-05-21T01:30:45.889Z,1400635845.889 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:45.890Z,1400635845.890 [Radio_Freewave](INFO): Join timeout helper Thread ID is 1621
2014-05-21T01:30:46.102Z,1400635846.102 [HFRCMSpaceInterpolator](INFO): requested location (36.803341, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:46.108Z,1400635846.108 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:46.246Z,1400635846.246 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:46.456Z,1400635846.456 [Radio_Freewave](INFO): Powering down
2014-05-21T01:30:46.515Z,1400635846.515 [HFRCMSpaceInterpolator](INFO): requested location (36.803337, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:46.529Z,1400635846.529 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:46.859Z,1400635846.859 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:46.864Z,1400635846.864 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-05-21T01:30:46.864Z,1400635846.864 [logger ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:46.864Z,1400635846.864 [logger](INFO): Join timeout helper Thread ID is 1623
2014-05-21T01:30:46.867Z,1400635846.867 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:46.867Z,1400635846.867 [logger ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:46.870Z,1400635846.870 [HFRCMSpaceInterpolator](INFO): requested location (36.803337, -121.822189) is outside grid, returning zeros
2014-05-21T01:30:46.876Z,1400635846.876 [HFRCMSpaceInterpolator](INFO): requested location (36.806965, -121.824326) is outside grid, returning zeros
2014-05-21T01:30:46.884Z,1400635846.884 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-05-21T01:30:46.884Z,1400635846.884 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:46.885Z,1400635846.885 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-05-21T01:30:46.885Z,1400635846.885 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:46.885Z,1400635846.885 [controlThread](INFO): Join timeout helper Thread ID is 1624
2014-05-21T01:30:46.924Z,1400635846.924 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:30:46.924Z,1400635846.924 [controlThread](DEBUG): Uninitializing ControlThread
2014-05-21T01:30:46.925Z,1400635846.925 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:30:46.995Z,1400635846.995 [DVL_micro](INFO): uninitialize:Powering down
2014-05-21T01:30:46.996Z,1400635846.996 [NAL9602](INFO): Powering down
2014-05-21T01:30:46.000Z,1400635847.000 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-05-21T01:30:47.002Z,1400635847.002 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-05-21T01:30:47.009Z,1400635847.009 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-05-21T01:30:47.009Z,1400635847.009 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-05-21T01:30:47.009Z,1400635847.009 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-05-21T01:30:47.009Z,1400635847.009 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-05-21T01:30:47.010Z,1400635847.010 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-05-21T01:30:47.010Z,1400635847.010 [BuoyancyServo](INFO): Powering down
2014-05-21T01:30:47.027Z,1400635847.027 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-05-21T01:30:47.027Z,1400635847.027 [ElevatorServo](INFO): Powering down
2014-05-21T01:30:47.028Z,1400635847.028 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-05-21T01:30:47.028Z,1400635847.028 [MassServo](INFO): Powering down
2014-05-21T01:30:47.029Z,1400635847.029 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-05-21T01:30:47.029Z,1400635847.029 [RudderServo](INFO): Powering down
2014-05-21T01:30:47.030Z,1400635847.030 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-05-21T01:30:47.030Z,1400635847.030 [ThrusterServo](INFO): Powering down
2014-05-21T01:30:47.031Z,1400635847.031 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-05-21T01:30:47.032Z,1400635847.032 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-05-21T01:30:47.032Z,1400635847.032 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-05-21T01:30:47.076Z,1400635847.076 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:47.080Z,1400635847.080 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:47.230Z,1400635847.230 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:47.242Z,1400635847.242 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:47.345Z,1400635847.345 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:30:47.417Z,1400635847.417 [logger ThreadHandler](INFO): Thread cancelled.