2014-05-21T01:21:26.100Z,1400635286.100 [Supervisor](DEBUG): Initializing supervisor.
2014-05-21T01:21:26.103Z,1400635286.103 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-05-21T01:21:26.103Z,1400635286.103 [SyncHandler](INFO): Protected caller Thread ID is 1030
2014-05-21T01:21:26.104Z,1400635286.104 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-05-21T01:21:26.105Z,1400635286.105 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-05-21T01:21:26.105Z,1400635286.105 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1031
2014-05-21T01:21:26.109Z,1400635286.109 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-05-21T01:21:26.122Z,1400635286.122 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-05-21T01:21:26.123Z,1400635286.123 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-05-21T01:21:26.124Z,1400635286.124 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1032
2014-05-21T01:21:26.125Z,1400635286.125 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-05-21T01:21:26.126Z,1400635286.126 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-05-21T01:21:26.126Z,1400635286.126 [logger ThreadHandler](INFO): Protected caller Thread ID is 1033
2014-05-21T01:21:26.128Z,1400635286.128 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-05-21T01:21:26.129Z,1400635286.129 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-05-21T01:21:26.130Z,1400635286.130 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-05-21T01:21:26.543Z,1400635286.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-05-21T01:21:26.543Z,1400635286.543 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-05-21T01:21:26.846Z,1400635286.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-05-21T01:21:26.847Z,1400635286.847 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-05-21T01:21:26.935Z,1400635286.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-05-21T01:21:26.936Z,1400635286.936 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-05-21T01:21:27.133Z,1400635287.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-05-21T01:21:27.134Z,1400635287.134 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-05-21T01:21:27.306Z,1400635287.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-05-21T01:21:27.306Z,1400635287.306 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-05-21T01:21:27.633Z,1400635287.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-05-21T01:21:27.634Z,1400635287.634 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-05-21T01:21:27.827Z,1400635287.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-05-21T01:21:27.828Z,1400635287.828 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-05-21T01:21:28.194Z,1400635288.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-05-21T01:21:28.195Z,1400635288.195 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-05-21T01:21:28.310Z,1400635288.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-05-21T01:21:28.310Z,1400635288.310 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-05-21T01:21:28.847Z,1400635288.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-05-21T01:21:28.848Z,1400635288.848 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-05-21T01:21:28.960Z,1400635288.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-05-21T01:21:28.961Z,1400635288.961 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-05-21T01:21:29.049Z,1400635289.049 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-05-21T01:21:29.166Z,1400635289.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-05-21T01:21:29.166Z,1400635289.166 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-05-21T01:21:29.273Z,1400635289.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-05-21T01:21:29.274Z,1400635289.274 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-05-21T01:21:29.420Z,1400635289.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-05-21T01:21:29.422Z,1400635289.422 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-05-21T01:21:29.423Z,1400635289.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-05-21T01:21:29.537Z,1400635289.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-05-21T01:21:29.677Z,1400635289.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-05-21T01:21:29.779Z,1400635289.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-05-21T01:21:29.876Z,1400635289.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-05-21T01:21:30.032Z,1400635290.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-05-21T01:21:30.158Z,1400635290.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-05-21T01:21:30.247Z,1400635290.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-05-21T01:21:30.358Z,1400635290.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-05-21T01:21:30.464Z,1400635290.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-05-21T01:21:30.573Z,1400635290.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-05-21T01:21:30.664Z,1400635290.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-05-21T01:21:30.770Z,1400635290.770 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2014-05-21T01:21:30.770Z,1400635290.770 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-05-21T01:21:30.775Z,1400635290.775 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-05-21T01:21:31.166Z,1400635291.166 [AHRS_sp3003D] Loaded
2014-05-21T01:21:31.166Z,1400635291.166 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-05-21T01:21:31.483Z,1400635291.483 [Batt_Ocean_Server] Loaded
2014-05-21T01:21:31.484Z,1400635291.484 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-05-21T01:21:31.558Z,1400635291.558 [DataOverHttps] Loaded
2014-05-21T01:21:31.558Z,1400635291.558 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2014-05-21T01:21:31.573Z,1400635291.573 [Depth_Keller] Loaded
2014-05-21T01:21:31.573Z,1400635291.573 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-05-21T01:21:31.726Z,1400635291.726 [DVL_micro] Loaded
2014-05-21T01:21:31.726Z,1400635291.726 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-05-21T01:21:31.836Z,1400635291.836 [NAL9602] Loaded
2014-05-21T01:21:31.837Z,1400635291.837 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-05-21T01:21:31.896Z,1400635291.896 [Onboard] Loaded
2014-05-21T01:21:31.896Z,1400635291.896 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-05-21T01:21:31.904Z,1400635291.904 [Radio_Freewave] Loaded
2014-05-21T01:21:31.904Z,1400635291.904 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-05-21T01:21:31.905Z,1400635291.905 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 4060F4E0
2014-05-21T01:21:31.906Z,1400635291.906 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 1115
2014-05-21T01:21:31.913Z,1400635291.913 [SCPI] Loaded
2014-05-21T01:21:31.913Z,1400635291.913 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-05-21T01:21:31.914Z,1400635291.914 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-05-21T01:21:31.914Z,1400635291.914 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-05-21T01:21:32.087Z,1400635292.087 [InternalSim] Loaded
2014-05-21T01:21:32.088Z,1400635292.088 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-05-21T01:21:32.088Z,1400635292.088 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-05-21T01:21:32.089Z,1400635292.089 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-05-21T01:21:32.352Z,1400635292.352 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-05-21T01:21:32.352Z,1400635292.352 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-05-21T01:21:32.358Z,1400635292.358 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-05-21T01:21:32.359Z,1400635292.359 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-05-21T01:21:32.446Z,1400635292.446 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-05-21T01:21:32.446Z,1400635292.446 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-05-21T01:21:32.497Z,1400635292.497 [DeadReckonWithRespectToWater] Loaded
2014-05-21T01:21:32.498Z,1400635292.498 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-05-21T01:21:32.591Z,1400635292.591 [DeadReckonWithRespectToSeafloor] Loaded
2014-05-21T01:21:32.592Z,1400635292.592 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-05-21T01:21:32.644Z,1400635292.644 [DeadReckonUsingDVLWaterTrack] Loaded
2014-05-21T01:21:32.645Z,1400635292.645 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-05-21T01:21:32.718Z,1400635292.718 [DeadReckonUsingCompactModelForecast] Loaded
2014-05-21T01:21:32.718Z,1400635292.718 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-05-21T01:21:32.735Z,1400635292.735 [NavChart] Loaded
2014-05-21T01:21:32.736Z,1400635292.736 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-05-21T01:21:32.740Z,1400635292.740 [UniversalFixResidualReporter] Loaded
2014-05-21T01:21:32.741Z,1400635292.741 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-05-21T01:21:32.741Z,1400635292.741 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-05-21T01:21:32.742Z,1400635292.742 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-05-21T01:21:32.795Z,1400635292.795 [VerticalControl](DEBUG): Construct VerticalControl.
2014-05-21T01:21:32.918Z,1400635292.918 [VerticalControl] Loaded
2014-05-21T01:21:32.919Z,1400635292.919 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-05-21T01:21:32.919Z,1400635292.919 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-05-21T01:21:32.993Z,1400635292.993 [HorizontalControl] Loaded
2014-05-21T01:21:32.993Z,1400635292.993 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-05-21T01:21:32.994Z,1400635292.994 [SpeedControl](DEBUG): Construct SpeedControl.
2014-05-21T01:21:32.996Z,1400635292.996 [SpeedControl] Loaded
2014-05-21T01:21:32.996Z,1400635292.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-05-21T01:21:32.997Z,1400635292.997 [LoopControl](DEBUG): Construct LoopControl.
2014-05-21T01:21:32.998Z,1400635292.998 [LoopControl] Loaded
2014-05-21T01:21:32.998Z,1400635292.998 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-05-21T01:21:32.998Z,1400635292.998 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-05-21T01:21:32.999Z,1400635292.999 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-05-21T01:21:33.019Z,1400635293.019 [DepthRateCalculator] Loaded
2014-05-21T01:21:33.019Z,1400635293.019 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-05-21T01:21:33.025Z,1400635293.025 [PitchRateCalculator] Loaded
2014-05-21T01:21:33.025Z,1400635293.025 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-05-21T01:21:33.033Z,1400635293.033 [SpeedCalculator] Loaded
2014-05-21T01:21:33.033Z,1400635293.033 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-05-21T01:21:33.052Z,1400635293.052 [TempGradientCalculator] Loaded
2014-05-21T01:21:33.052Z,1400635293.052 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-05-21T01:21:33.058Z,1400635293.058 [YawRateCalculator] Loaded
2014-05-21T01:21:33.058Z,1400635293.058 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-05-21T01:21:33.059Z,1400635293.059 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-05-21T01:21:33.059Z,1400635293.059 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-05-21T01:21:33.176Z,1400635293.176 [CTD_NeilBrown] Loaded
2014-05-21T01:21:33.176Z,1400635293.176 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-05-21T01:21:33.177Z,1400635293.177 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4075E4E0
2014-05-21T01:21:33.177Z,1400635293.177 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1116
2014-05-21T01:21:33.191Z,1400635293.191 [ISUS] Loaded
2014-05-21T01:21:33.192Z,1400635293.192 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2014-05-21T01:21:33.208Z,1400635293.208 [PAR_Licor] Loaded
2014-05-21T01:21:33.208Z,1400635293.208 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-05-21T01:21:33.246Z,1400635293.246 [WetLabsBB2FL] Loaded
2014-05-21T01:21:33.247Z,1400635293.247 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-05-21T01:21:33.248Z,1400635293.248 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4078E4E0
2014-05-21T01:21:33.248Z,1400635293.248 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1117
2014-05-21T01:21:33.249Z,1400635293.249 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-05-21T01:21:33.249Z,1400635293.249 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-05-21T01:21:33.355Z,1400635293.355 [SBIT](DEBUG): Construct Startup Built In Test.
2014-05-21T01:21:33.368Z,1400635293.368 [SBIT] Loaded
2014-05-21T01:21:33.368Z,1400635293.368 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-05-21T01:21:33.369Z,1400635293.369 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-05-21T01:21:33.383Z,1400635293.383 [IBIT] Loaded
2014-05-21T01:21:33.383Z,1400635293.383 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-05-21T01:21:33.385Z,1400635293.385 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-05-21T01:21:33.461Z,1400635293.461 [CBIT] Loaded
2014-05-21T01:21:33.462Z,1400635293.462 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-05-21T01:21:33.462Z,1400635293.462 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-05-21T01:21:33.463Z,1400635293.463 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-05-21T01:21:39.130Z,1400635299.130 [HFRadarModelCalc] Loaded
2014-05-21T01:21:39.130Z,1400635299.130 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2014-05-21T01:21:43.587Z,1400635303.587 [HFRadarCompactModelForecaster] Loaded
2014-05-21T01:21:43.587Z,1400635303.587 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-05-21T01:21:44.746Z,1400635304.746 [HFRCMSpaceInterpolator] Loaded
2014-05-21T01:21:44.746Z,1400635304.746 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-05-21T01:21:44.748Z,1400635304.748 [HFRCMTimeInterpolator] Loaded
2014-05-21T01:21:44.749Z,1400635304.749 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-05-21T01:21:44.763Z,1400635304.763 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-05-21T01:21:44.763Z,1400635304.763 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-05-21T01:21:44.793Z,1400635304.793 [HFRCMVirtualSurfaceDrifter] Loaded
2014-05-21T01:21:44.794Z,1400635304.794 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-05-21T01:21:44.794Z,1400635304.794 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-05-21T01:21:44.795Z,1400635304.795 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-05-21T01:21:44.901Z,1400635304.901 [BuoyancyServo] Loaded
2014-05-21T01:21:44.901Z,1400635304.901 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-05-21T01:21:44.914Z,1400635304.914 [ElevatorServo] Loaded
2014-05-21T01:21:44.915Z,1400635304.915 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-05-21T01:21:44.927Z,1400635304.927 [MassServo] Loaded
2014-05-21T01:21:44.928Z,1400635304.928 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-05-21T01:21:44.941Z,1400635304.941 [RudderServo] Loaded
2014-05-21T01:21:44.941Z,1400635304.941 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-05-21T01:21:44.953Z,1400635304.953 [ThrusterServo] Loaded
2014-05-21T01:21:44.954Z,1400635304.954 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-05-21T01:21:44.954Z,1400635304.954 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-05-21T01:21:44.955Z,1400635304.955 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-05-21T01:21:44.980Z,1400635304.980 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-05-21T01:21:44.982Z,1400635304.982 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-05-21T01:21:44.983Z,1400635304.983 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-05-21T01:21:44.990Z,1400635304.990 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-05-21T01:21:44.991Z,1400635304.991 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2014-05-21T01:21:44.992Z,1400635304.992 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1118
2014-05-21T01:21:44.997Z,1400635304.997 [Supervisor](INFO): Main Thread ID is 769
2014-05-21T01:21:44.997Z,1400635304.997 [Supervisor](DEBUG): Running supervisor.
2014-05-21T01:21:44.998Z,1400635304.998 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1119
2014-05-21T01:21:45.002Z,1400635305.002 [controlThread ThreadHandler](INFO): Handler Thread ID is 1120
2014-05-21T01:21:45.003Z,1400635305.003 [controlThread](DEBUG): Initializing ControlThread
2014-05-21T01:21:45.006Z,1400635305.006 [logger ThreadHandler](INFO): Handler Thread ID is 1121
2014-05-21T01:21:45.027Z,1400635305.027 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 1122
2014-05-21T01:21:45.043Z,1400635305.043 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1123
2014-05-21T01:21:45.043Z,1400635305.043 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-05-21T01:21:45.047Z,1400635305.047 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-05-21T01:21:45.056Z,1400635305.056 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1125
2014-05-21T01:21:45.057Z,1400635305.057 [WetLabsBB2FL](INFO): Powering down
2014-05-21T01:21:45.085Z,1400635305.085 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1126
2014-05-21T01:21:45.088Z,1400635305.088 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-05-21T01:21:45.089Z,1400635305.089 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-05-21T01:21:45.089Z,1400635305.089 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-05-21T01:21:45.090Z,1400635305.090 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-05-21T01:21:45.090Z,1400635305.090 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-05-21T01:21:45.090Z,1400635305.090 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-05-21T01:21:45.091Z,1400635305.091 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-05-21T01:21:45.091Z,1400635305.091 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-05-21T01:21:45.091Z,1400635305.091 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-05-21T01:21:46.475Z,1400635306.475 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-05-21T01:21:46.506Z,1400635306.506 [InternalSim](DEBUG):  InternalSim initializing...
2014-05-21T01:21:46.605Z,1400635306.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-05-21T01:21:46.621Z,1400635306.621 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-05-21T01:21:46.621Z,1400635306.621 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-05-21T01:21:46.622Z,1400635306.622 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-05-21T01:21:46.622Z,1400635306.622 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-05-21T01:21:46.623Z,1400635306.623 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-05-21T01:21:46.623Z,1400635306.623 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-05-21T01:21:46.623Z,1400635306.623 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-05-21T01:21:46.625Z,1400635306.625 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-05-21T01:21:46.626Z,1400635306.626 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-05-21T01:21:46.626Z,1400635306.626 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-05-21T01:21:46.627Z,1400635306.627 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-05-21T01:21:46.627Z,1400635306.627 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-05-21T01:21:46.627Z,1400635306.627 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-05-21T01:21:46.628Z,1400635306.628 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-05-21T01:21:46.628Z,1400635306.628 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-05-21T01:21:46.690Z,1400635306.690 [SBIT](INFO): Initialize SBIT Component.
2014-05-21T01:21:46.690Z,1400635306.690 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:11320
2014-05-21T01:21:46.691Z,1400635306.691 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-05-21T01:21:46.691Z,1400635306.691 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-05-21T01:21:46.692Z,1400635306.692 [IBIT](INFO): Initialize IBIT Component.
2014-05-21T01:21:46.706Z,1400635306.706 [CBIT](DEBUG): Initialize CBIT Component.
2014-05-21T01:21:46.707Z,1400635306.707 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-05-21T01:21:46.741Z,1400635306.741 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-05-21T01:21:46.945Z,1400635306.945 [Radio_Freewave](INFO): Powering up
2014-05-21T01:21:47.756Z,1400635307.756 [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:21:56.910Z,1400635316.910 [HFRadarCompactModelForecaster](IMPORTANT): Forecast time 201405202200: published 50 modes in rows, 61 hours in columns, 3050 elements.
2014-05-21T01:21:56.921Z,1400635316.921 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component.
2014-05-21T01:21:56.923Z,1400635316.923 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-05-21T01:21:56.923Z,1400635316.923 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-05-21T01:21:56.924Z,1400635316.924 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-05-21T01:21:56.926Z,1400635316.926 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-05-21T01:21:56.962Z,1400635316.962 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:21:56.987Z,1400635316.987 [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:21:56.988Z,1400635316.988 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-05-21T01:21:57.078Z,1400635317.078 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-05-21T01:21:57.081Z,1400635317.081 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-05-21T01:21:57.096Z,1400635317.096 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:21:57.138Z,1400635317.138 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-05-21T01:21:57.142Z,1400635317.142 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:21:57.157Z,1400635317.157 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-05-21T01:21:57.195Z,1400635317.195 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-05-21T01:21:57.214Z,1400635317.214 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-05-21T01:21:57.224Z,1400635317.224 [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:21:57.232Z,1400635317.232 [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:21:57.323Z,1400635317.323 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-05-21T01:21:58.038Z,1400635318.038 [DVL_micro](INFO): Initializing
2014-05-21T01:21:58.069Z,1400635318.069 [NAL9602](INFO): Powering up NAL9602
2014-05-21T01:21:58.602Z,1400635318.602 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:21:58.817Z,1400635318.817 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:21:58.824Z,1400635318.824 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-05-21T01:21:58.847Z,1400635318.847 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:21:58.848Z,1400635318.848 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-05-21T01:21:58.878Z,1400635318.878 [MassServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:21:58.884Z,1400635318.884 [MassServo](DEBUG): Initializing MassServo.
2014-05-21T01:21:58.898Z,1400635318.898 [RudderServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:21:58.904Z,1400635318.904 [RudderServo](DEBUG): Initializing RudderServo.
2014-05-21T01:21:58.927Z,1400635318.927 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:21:58.932Z,1400635318.932 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-05-21T01:21:59.393Z,1400635319.393 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:21:59.745Z,1400635319.745 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2014-05-21T01:21:59.745Z,1400635319.745 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2014-05-21T01:21:59.746Z,1400635319.746 [BuoyancyServo] Communications Fault, FailCount= 1
2014-05-21T01:21:59.746Z,1400635319.746 [BuoyancyServo](ERROR): Communications Fault
2014-05-21T01:21:59.952Z,1400635319.952 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2014-05-21T01:22:02.164Z,1400635322.164 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:02.236Z,1400635322.236 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-05-21T01:22:02.237Z,1400635322.237 [BuoyancyServo](INFO): Powering down
2014-05-21T01:22:03.003Z,1400635323.003 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:03.160Z,1400635323.160 [SBIT](IMPORTANT): Beginning Startup BIT
2014-05-21T01:22:03.163Z,1400635323.163 [CBIT](IMPORTANT): Beginning GF scan
2014-05-21T01:22:03.702Z,1400635323.702 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:04.152Z,1400635324.152 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004206
2014-05-21T01:22:04.225Z,1400635324.225 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:04.920Z,1400635324.920 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:05.241Z,1400635325.241 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2014-05-21T01:22:05.241Z,1400635325.241 [BuoyancyServo] No Fault, FailCount= 1
2014-05-21T01:22:05.744Z,1400635325.744 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:05.810Z,1400635325.810 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-05-21T01:22:05.932Z,1400635325.932 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-05-21T01:22:06.415Z,1400635326.415 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:07.097Z,1400635327.097 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:07.704Z,1400635327.704 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:08.537Z,1400635328.537 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:09.092Z,1400635329.092 [NAL9602](INFO): NAL9602 initialized
2014-05-21T01:22:09.159Z,1400635329.159 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:09.675Z,1400635329.675 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:10.233Z,1400635330.233 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:10.886Z,1400635330.886 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:11.658Z,1400635331.658 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:12.380Z,1400635332.380 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:13.008Z,1400635333.008 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:13.594Z,1400635333.594 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:14.163Z,1400635334.163 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:14.713Z,1400635334.713 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:15.306Z,1400635335.306 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:15.843Z,1400635335.843 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:16.295Z,1400635336.295 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:16.781Z,1400635336.781 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:17.388Z,1400635337.388 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:17.887Z,1400635337.887 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:18.433Z,1400635338.433 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:19.359Z,1400635339.359 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:19.999Z,1400635339.999 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:20.549Z,1400635340.549 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:21.095Z,1400635341.095 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:21.647Z,1400635341.647 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:22.248Z,1400635342.248 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:22.916Z,1400635342.916 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:23.454Z,1400635343.454 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:24.023Z,1400635344.023 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:24.624Z,1400635344.624 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:25.260Z,1400635345.260 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:26.070Z,1400635346.070 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:26.583Z,1400635346.583 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:27.119Z,1400635347.119 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:27.756Z,1400635347.756 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:28.317Z,1400635348.317 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:29.137Z,1400635349.137 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:29.880Z,1400635349.880 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:29.997Z,1400635349.997 [CBIT](IMPORTANT): No ground fault detected
2014-05-21T01:22:30.448Z,1400635350.448 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:31.062Z,1400635351.062 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:31.638Z,1400635351.638 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:32.248Z,1400635352.248 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:32.961Z,1400635352.961 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:33.569Z,1400635353.569 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:34.188Z,1400635354.188 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:34.671Z,1400635354.671 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:35.151Z,1400635355.151 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:35.660Z,1400635355.660 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2014-05-21T01:22:35.662Z,1400635355.662 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2014-05-21T01:22:35.663Z,1400635355.663 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2014-05-21T01:22:35.664Z,1400635355.664 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2014-05-21T01:22:35.665Z,1400635355.665 [CommandLine](IMPORTANT): got command report mod HFRadarModelCalc.surface_eastward_sea_water_velocity
2014-05-21T01:22:35.666Z,1400635355.666 [CommandLine](IMPORTANT): got command report mod HFRadarModelCalc.surface_northward_sea_water_velocity
2014-05-21T01:22:35.667Z,1400635355.667 [CommandLine](IMPORTANT): got command report mod HFRCMSurfaceCurrentAtVehicleLocation.surface_eastward_sea_water_velocity
2014-05-21T01:22:35.668Z,1400635355.668 [CommandLine](IMPORTANT): got command report mod HFRCMSurfaceCurrentAtVehicleLocation.surface_northward_sea_water_velocity
2014-05-21T01:22:35.728Z,1400635355.728 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:35.804Z,1400635355.804 [Reporter](INFO): HFRadarModelCalc.surface_eastward_sea_water_velocity no_value
2014-05-21T01:22:35.804Z,1400635355.804 [Reporter](INFO): HFRadarModelCalc.surface_northward_sea_water_velocity no_value
2014-05-21T01:22:35.823Z,1400635355.823 [Reporter](INFO): HFRCMSurfaceCurrentAtVehicleLocation.surface_eastward_sea_water_velocity no_value
2014-05-21T01:22:35.824Z,1400635355.824 [Reporter](INFO): HFRCMSurfaceCurrentAtVehicleLocation.surface_northward_sea_water_velocity no_value
2014-05-21T01:22:35.832Z,1400635355.832 [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:22:36.274Z,1400635356.274 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:36.949Z,1400635356.949 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:36.951Z,1400635356.951 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:36.982Z,1400635356.982 [Reporter](INFO): HFRCMSurfaceCurrentAtVehicleLocation.surface_eastward_sea_water_velocity 0.000000 m/s
2014-05-21T01:22:36.983Z,1400635356.983 [Reporter](INFO): HFRCMSurfaceCurrentAtVehicleLocation.surface_northward_sea_water_velocity 0.000000 m/s
2014-05-21T01:22:37.473Z,1400635357.473 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:37.474Z,1400635357.474 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:38.091Z,1400635358.091 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:38.092Z,1400635358.092 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:38.584Z,1400635358.584 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:38.589Z,1400635358.589 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:39.074Z,1400635359.074 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:39.076Z,1400635359.076 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:39.603Z,1400635359.603 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:39.605Z,1400635359.605 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:40.154Z,1400635360.154 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:40.174Z,1400635360.174 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:40.656Z,1400635360.656 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:40.658Z,1400635360.658 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:41.145Z,1400635361.145 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:41.147Z,1400635361.147 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:41.780Z,1400635361.780 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:41.781Z,1400635361.781 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:42.267Z,1400635362.267 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:42.269Z,1400635362.269 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:42.795Z,1400635362.795 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:42.797Z,1400635362.797 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:43.493Z,1400635363.493 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:43.495Z,1400635363.495 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:43.962Z,1400635363.962 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:43.963Z,1400635363.963 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:44.520Z,1400635364.520 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:44.521Z,1400635364.521 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:45.023Z,1400635365.023 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:45.025Z,1400635365.025 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:45.512Z,1400635365.512 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:45.514Z,1400635365.514 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:46.187Z,1400635366.187 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:46.190Z,1400635366.190 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:46.268Z,1400635366.268 [CommandLine](IMPORTANT): got command quit
2014-05-21T01:22:47.227Z,1400635367.227 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:47.228Z,1400635367.228 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:47.274Z,1400635367.274 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:47.274Z,1400635367.274 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:47.447Z,1400635367.447 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-05-21T01:22:47.447Z,1400635367.447 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:47.448Z,1400635367.448 [CommandLine](INFO): Join timeout helper Thread ID is 1151
2014-05-21T01:22:47.464Z,1400635367.464 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-05-21T01:22:47.464Z,1400635367.464 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:47.465Z,1400635367.465 [NavChartDb](INFO): Join timeout helper Thread ID is 1152
2014-05-21T01:22:47.795Z,1400635367.795 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:47.800Z,1400635367.800 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:47.883Z,1400635367.883 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:47.884Z,1400635367.884 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:47.896Z,1400635367.896 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-05-21T01:22:47.896Z,1400635367.896 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:47.896Z,1400635367.896 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1153
2014-05-21T01:22:48.213Z,1400635368.213 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:48.213Z,1400635368.213 [WetLabsBB2FL](INFO): Powering down
2014-05-21T01:22:48.214Z,1400635368.214 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:48.220Z,1400635368.220 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-05-21T01:22:48.220Z,1400635368.220 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:48.221Z,1400635368.221 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1154
2014-05-21T01:22:48.401Z,1400635368.401 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:48.402Z,1400635368.402 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:48.461Z,1400635368.461 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:48.461Z,1400635368.461 [CTD_NeilBrown](INFO): Powering down
2014-05-21T01:22:48.462Z,1400635368.462 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:48.473Z,1400635368.473 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-05-21T01:22:48.473Z,1400635368.473 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:48.473Z,1400635368.473 [Radio_Freewave](INFO): Join timeout helper Thread ID is 1155
2014-05-21T01:22:48.677Z,1400635368.677 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:48.780Z,1400635368.780 [Radio_Freewave](INFO): Powering down
2014-05-21T01:22:49.032Z,1400635369.032 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:49.034Z,1400635369.034 [HFRCMSpaceInterpolator](ERROR): requested location outside grid, returning zeros
2014-05-21T01:22:49.186Z,1400635369.186 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.195Z,1400635369.195 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-05-21T01:22:49.195Z,1400635369.195 [logger ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.196Z,1400635369.196 [logger](INFO): Join timeout helper Thread ID is 1157
2014-05-21T01:22:49.208Z,1400635369.208 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:49.208Z,1400635369.208 [logger ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.215Z,1400635369.215 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-05-21T01:22:49.215Z,1400635369.215 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.215Z,1400635369.215 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-05-21T01:22:49.215Z,1400635369.215 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.216Z,1400635369.216 [controlThread](INFO): Join timeout helper Thread ID is 1158
2014-05-21T01:22:49.332Z,1400635369.332 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-05-21T01:22:49.333Z,1400635369.333 [controlThread](DEBUG): Uninitializing ControlThread
2014-05-21T01:22:49.333Z,1400635369.333 [AHRS_sp3003D](INFO): Powering down
2014-05-21T01:22:49.490Z,1400635369.490 [DVL_micro](INFO): uninitialize:Powering down
2014-05-21T01:22:49.492Z,1400635369.492 [NAL9602](INFO): Powering down
2014-05-21T01:22:49.495Z,1400635369.495 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-05-21T01:22:49.498Z,1400635369.498 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-05-21T01:22:49.504Z,1400635369.504 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-05-21T01:22:49.504Z,1400635369.504 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-05-21T01:22:49.505Z,1400635369.505 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-05-21T01:22:49.505Z,1400635369.505 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-05-21T01:22:49.505Z,1400635369.505 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-05-21T01:22:49.505Z,1400635369.505 [BuoyancyServo](INFO): Powering down
2014-05-21T01:22:49.518Z,1400635369.518 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-05-21T01:22:49.519Z,1400635369.519 [ElevatorServo](INFO): Powering down
2014-05-21T01:22:49.519Z,1400635369.519 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-05-21T01:22:49.520Z,1400635369.520 [MassServo](INFO): Powering down
2014-05-21T01:22:49.520Z,1400635369.520 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-05-21T01:22:49.521Z,1400635369.521 [RudderServo](INFO): Powering down
2014-05-21T01:22:49.521Z,1400635369.521 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-05-21T01:22:49.522Z,1400635369.522 [ThrusterServo](INFO): Powering down
2014-05-21T01:22:49.523Z,1400635369.523 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-05-21T01:22:49.523Z,1400635369.523 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-05-21T01:22:49.523Z,1400635369.523 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-05-21T01:22:49.568Z,1400635369.568 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.570Z,1400635369.570 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.653Z,1400635369.653 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.656Z,1400635369.656 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.824Z,1400635369.824 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-05-21T01:22:49.860Z,1400635369.860 [logger ThreadHandler](INFO): Thread cancelled.