2015-01-14T17:24:37.556Z,1421256277.556 [Supervisor](DEBUG): Initializing supervisor.
2015-01-14T17:24:37.559Z,1421256277.559 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-01-14T17:24:37.559Z,1421256277.559 [SyncHandler](INFO): Protected caller Thread ID is 765
2015-01-14T17:24:37.560Z,1421256277.560 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-01-14T17:24:37.561Z,1421256277.561 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-01-14T17:24:37.561Z,1421256277.561 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 766
2015-01-14T17:24:37.564Z,1421256277.564 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-01-14T17:24:37.576Z,1421256277.576 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-01-14T17:24:37.577Z,1421256277.577 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-01-14T17:24:37.577Z,1421256277.577 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 767
2015-01-14T17:24:37.578Z,1421256277.578 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-01-14T17:24:37.579Z,1421256277.579 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-01-14T17:24:37.579Z,1421256277.579 [logger ThreadHandler](INFO): Protected caller Thread ID is 768
2015-01-14T17:24:37.581Z,1421256277.581 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-01-14T17:24:37.582Z,1421256277.582 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-01-14T17:24:37.585Z,1421256277.585 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-01-14T17:24:38.003Z,1421256278.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-01-14T17:24:38.004Z,1421256278.004 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-01-14T17:24:38.176Z,1421256278.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-01-14T17:24:38.176Z,1421256278.176 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-01-14T17:24:38.290Z,1421256278.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-01-14T17:24:38.291Z,1421256278.291 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-01-14T17:24:38.421Z,1421256278.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-01-14T17:24:38.421Z,1421256278.421 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-01-14T17:24:38.511Z,1421256278.511 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-01-14T17:24:38.684Z,1421256278.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-01-14T17:24:38.684Z,1421256278.684 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-01-14T17:24:38.774Z,1421256278.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-01-14T17:24:38.775Z,1421256278.775 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-01-14T17:24:38.998Z,1421256278.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-01-14T17:24:38.999Z,1421256278.999 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-01-14T17:24:39.396Z,1421256279.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-01-14T17:24:39.396Z,1421256279.396 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-01-14T17:24:39.736Z,1421256279.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-01-14T17:24:39.737Z,1421256279.737 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-01-14T17:24:40.282Z,1421256280.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-01-14T17:24:40.283Z,1421256280.283 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-01-14T17:24:40.504Z,1421256280.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-01-14T17:24:40.505Z,1421256280.505 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-01-14T17:24:40.614Z,1421256280.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-01-14T17:24:40.615Z,1421256280.615 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-01-14T17:24:41.076Z,1421256281.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-01-14T17:24:41.077Z,1421256281.077 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-01-14T17:24:41.199Z,1421256281.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-01-14T17:24:41.201Z,1421256281.201 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-01-14T17:24:54.826Z,1421256294.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-01-14T17:24:55.087Z,1421256295.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-01-14T17:24:55.088Z,1421256295.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-01-14T17:24:55.214Z,1421256295.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Estimation.cfg
2015-01-14T17:24:55.316Z,1421256295.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-01-14T17:24:55.419Z,1421256295.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-01-14T17:24:55.534Z,1421256295.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-01-14T17:24:55.687Z,1421256295.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-01-14T17:24:55.874Z,1421256295.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-01-14T17:24:55.981Z,1421256295.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-01-14T17:24:56.076Z,1421256296.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-01-14T17:24:56.184Z,1421256296.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-01-14T17:24:56.286Z,1421256296.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-01-14T17:24:56.484Z,1421256296.484 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2015-01-14T17:24:56.507Z,1421256296.507 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-01-14T17:24:56.585Z,1421256296.585 [VerticalControl](DEBUG): Construct VerticalControl.
2015-01-14T17:24:56.711Z,1421256296.711 [VerticalControl] Loaded
2015-01-14T17:24:56.711Z,1421256296.711 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-01-14T17:24:56.712Z,1421256296.712 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-01-14T17:24:56.788Z,1421256296.788 [HorizontalControl] Loaded
2015-01-14T17:24:56.789Z,1421256296.789 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-01-14T17:24:56.789Z,1421256296.789 [SpeedControl](DEBUG): Construct SpeedControl.
2015-01-14T17:24:56.796Z,1421256296.796 [SpeedControl] Loaded
2015-01-14T17:24:56.796Z,1421256296.796 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-01-14T17:24:56.797Z,1421256296.797 [LoopControl](DEBUG): Construct LoopControl.
2015-01-14T17:24:56.797Z,1421256296.797 [LoopControl] Loaded
2015-01-14T17:24:56.798Z,1421256296.798 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-01-14T17:24:56.798Z,1421256296.798 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-01-14T17:24:56.799Z,1421256296.799 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-01-14T17:24:56.933Z,1421256296.933 [SBIT](DEBUG): Construct Startup Built In Test.
2015-01-14T17:24:56.945Z,1421256296.945 [SBIT] Loaded
2015-01-14T17:24:56.946Z,1421256296.946 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-01-14T17:24:56.946Z,1421256296.946 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-01-14T17:24:56.977Z,1421256296.977 [IBIT] Loaded
2015-01-14T17:24:56.977Z,1421256296.977 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-01-14T17:24:56.979Z,1421256296.979 [CBIT](DEBUG): Construct CBIT Built In Test.
2015-01-14T17:24:57.106Z,1421256297.106 [CBIT] Loaded
2015-01-14T17:24:57.107Z,1421256297.107 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-01-14T17:24:57.107Z,1421256297.107 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-01-14T17:24:57.110Z,1421256297.110 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-01-14T17:24:57.140Z,1421256297.140 [DepthRateCalculator] Loaded
2015-01-14T17:24:57.141Z,1421256297.141 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-01-14T17:24:57.147Z,1421256297.147 [PitchRateCalculator] Loaded
2015-01-14T17:24:57.147Z,1421256297.147 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-01-14T17:24:57.159Z,1421256297.159 [SpeedCalculator] Loaded
2015-01-14T17:24:57.160Z,1421256297.160 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-01-14T17:24:57.178Z,1421256297.178 [TempGradientCalculator] Loaded
2015-01-14T17:24:57.178Z,1421256297.178 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-01-14T17:24:57.184Z,1421256297.184 [YawRateCalculator] Loaded
2015-01-14T17:24:57.184Z,1421256297.184 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-01-14T17:24:57.184Z,1421256297.184 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-01-14T17:24:57.185Z,1421256297.185 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-01-14T17:24:58.642Z,1421256298.642 [HFRCMReconstructedInterpolator] Loaded
2015-01-14T17:24:58.642Z,1421256298.642 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2015-01-14T17:24:58.643Z,1421256298.643 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-01-14T17:24:58.644Z,1421256298.644 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-01-14T17:24:58.945Z,1421256298.945 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-01-14T17:24:58.945Z,1421256298.945 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-01-14T17:24:59.057Z,1421256299.057 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-01-14T17:24:59.058Z,1421256299.058 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-01-14T17:24:59.106Z,1421256299.106 [DeadReckonUsingSpeedCalculator] Loaded
2015-01-14T17:24:59.106Z,1421256299.106 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-01-14T17:24:59.156Z,1421256299.156 [DeadReckonWithRespectToWater] Loaded
2015-01-14T17:24:59.156Z,1421256299.156 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2015-01-14T17:24:59.205Z,1421256299.205 [DeadReckonWithRespectToSeafloor] Loaded
2015-01-14T17:24:59.205Z,1421256299.205 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2015-01-14T17:24:59.258Z,1421256299.258 [DeadReckonUsingDVLWaterTrack] Loaded
2015-01-14T17:24:59.259Z,1421256299.259 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2015-01-14T17:24:59.277Z,1421256299.277 [NavChart] Loaded
2015-01-14T17:24:59.277Z,1421256299.277 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-01-14T17:24:59.282Z,1421256299.282 [UniversalFixResidualReporter] Loaded
2015-01-14T17:24:59.282Z,1421256299.282 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-01-14T17:24:59.282Z,1421256299.282 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-01-14T17:24:59.283Z,1421256299.283 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-01-14T17:24:59.292Z,1421256299.292 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-01-14T17:24:59.293Z,1421256299.293 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-01-14T17:24:59.419Z,1421256299.419 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-01-14T17:24:59.419Z,1421256299.419 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-01-14T17:25:00.088Z,1421256300.088 [DataOverHttps] Loaded
2015-01-14T17:25:00.088Z,1421256300.088 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-01-14T17:25:00.171Z,1421256300.171 [Depth_Keller] Loaded
2015-01-14T17:25:00.171Z,1421256300.171 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-01-14T17:25:00.273Z,1421256300.273 [NAL9602] Loaded
2015-01-14T17:25:00.273Z,1421256300.273 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-01-14T17:25:00.317Z,1421256300.317 [Onboard] Loaded
2015-01-14T17:25:00.318Z,1421256300.318 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-01-14T17:25:00.325Z,1421256300.325 [Radio_Surface] Loaded
2015-01-14T17:25:00.325Z,1421256300.325 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-01-14T17:25:00.327Z,1421256300.327 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408024E0
2015-01-14T17:25:00.327Z,1421256300.327 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 852
2015-01-14T17:25:02.293Z,1421256302.293 [BPC1] Loaded
2015-01-14T17:25:02.293Z,1421256302.293 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-01-14T17:25:02.293Z,1421256302.293 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-01-14T17:25:02.294Z,1421256302.294 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-01-14T17:25:13.037Z,1421256313.037 [BuoyancyServo] Loaded
2015-01-14T17:25:13.037Z,1421256313.037 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-01-14T17:25:13.049Z,1421256313.049 [ElevatorServo] Loaded
2015-01-14T17:25:13.050Z,1421256313.050 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-01-14T17:25:13.062Z,1421256313.062 [MassServo] Loaded
2015-01-14T17:25:13.062Z,1421256313.062 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-01-14T17:25:13.074Z,1421256313.074 [RudderServo] Loaded
2015-01-14T17:25:13.074Z,1421256313.074 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-01-14T17:25:13.086Z,1421256313.086 [ThrusterServo] Loaded
2015-01-14T17:25:13.086Z,1421256313.086 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-01-14T17:25:13.087Z,1421256313.087 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-01-14T17:25:13.088Z,1421256313.088 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-01-14T17:25:13.379Z,1421256313.379 [ExternalSim] Loaded
2015-01-14T17:25:13.379Z,1421256313.379 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2015-01-14T17:25:13.380Z,1421256313.380 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-01-14T17:25:13.381Z,1421256313.381 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-01-14T17:25:13.423Z,1421256313.423 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-01-14T17:25:13.425Z,1421256313.425 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-01-14T17:25:13.426Z,1421256313.426 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-01-14T17:25:13.433Z,1421256313.433 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-01-14T17:25:13.434Z,1421256313.434 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408B34E0
2015-01-14T17:25:13.434Z,1421256313.434 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 854
2015-01-14T17:25:13.439Z,1421256313.439 [Supervisor](INFO): Main Thread ID is 764
2015-01-14T17:25:13.439Z,1421256313.439 [Supervisor](DEBUG): Running supervisor.
2015-01-14T17:25:13.440Z,1421256313.440 [CommandLine ThreadHandler](INFO): Handler Thread ID is 855
2015-01-14T17:25:13.453Z,1421256313.453 [controlThread ThreadHandler](INFO): Handler Thread ID is 856
2015-01-14T17:25:13.454Z,1421256313.454 [controlThread](DEBUG): Initializing ControlThread
2015-01-14T17:25:13.455Z,1421256313.455 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-01-14T17:25:13.456Z,1421256313.456 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-01-14T17:25:13.457Z,1421256313.457 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-01-14T17:25:13.458Z,1421256313.458 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-01-14T17:25:13.458Z,1421256313.458 [SBIT](INFO): Initialize SBIT Component.
2015-01-14T17:25:13.458Z,1421256313.458 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:11955
2015-01-14T17:25:13.459Z,1421256313.459 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-01-14T17:25:13.459Z,1421256313.459 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-01-14T17:25:13.459Z,1421256313.459 [IBIT](INFO): Initialize IBIT Component.
2015-01-14T17:25:13.460Z,1421256313.460 [CBIT](DEBUG): Initialize CBIT Component.
2015-01-14T17:25:13.461Z,1421256313.461 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-01-14T17:25:13.461Z,1421256313.461 [logger ThreadHandler](INFO): Handler Thread ID is 857
2015-01-14T17:25:13.480Z,1421256313.480 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 858
2015-01-14T17:25:13.486Z,1421256313.486 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-01-14T17:25:13.486Z,1421256313.486 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-01-14T17:25:13.486Z,1421256313.486 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-01-14T17:25:13.487Z,1421256313.487 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-01-14T17:25:13.487Z,1421256313.487 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-01-14T17:25:13.488Z,1421256313.488 [HFRCMReconstructedInterpolator](INFO): Initializing HFRCMReconstructedInterpolator component with velocityAccuracy_ = 0.300000 m/s.
2015-01-14T17:25:13.490Z,1421256313.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-01-14T17:25:13.490Z,1421256313.490 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-01-14T17:25:13.491Z,1421256313.491 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2015-01-14T17:25:13.491Z,1421256313.491 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2015-01-14T17:25:13.491Z,1421256313.491 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2015-01-14T17:25:13.492Z,1421256313.492 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-01-14T17:25:13.492Z,1421256313.492 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-01-14T17:25:13.498Z,1421256313.498 [ExternalSim](INFO): ExternalSim initializing...
2015-01-14T17:25:13.516Z,1421256313.516 [Radio_Surface](INFO): Powering up
2015-01-14T17:25:13.517Z,1421256313.517 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859
2015-01-14T17:25:13.532Z,1421256313.532 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2015-01-14T17:25:13.533Z,1421256313.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-01-14T17:25:13.533Z,1421256313.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-01-14T17:25:13.533Z,1421256313.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-01-14T17:25:13.534Z,1421256313.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-01-14T17:25:13.534Z,1421256313.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-01-14T17:25:13.535Z,1421256313.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-01-14T17:25:13.535Z,1421256313.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-01-14T17:25:13.535Z,1421256313.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-01-14T17:25:13.582Z,1421256313.582 [ExternalSim](ERROR): Error making socket connection to simulation server at tellum.shore.mbari.org: Can not connect to invalid network address: tellum.shore.mbari.org
2015-01-14T17:25:13.688Z,1421256313.688 [ExternalSim](DEBUG): beaconLat = 36.80340
2015-01-14T17:25:13.688Z,1421256313.688 [ExternalSim](DEBUG): beaconLon = -121.82230
2015-01-14T17:25:13.688Z,1421256313.688 [ExternalSim](DEBUG): beaconDepth = 25.00
2015-01-14T17:25:14.259Z,1421256314.259 [ExternalSim](DEBUG): Simulator initialized
2015-01-14T17:25:14.404Z,1421256314.404 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-01-14T17:25:14.431Z,1421256314.431 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-01-14T17:25:14.456Z,1421256314.456 [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>


2015-01-14T17:25:14.456Z,1421256314.456 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-01-14T17:25:14.566Z,1421256314.566 [MissionManager](INFO): DefineArg Default.TimeMissionWasStarted = 0.000000 h
2015-01-14T17:25:14.569Z,1421256314.569 [MissionManager](INFO): DefineArg Default.ElapsedSinceMissionWasStarted = 0.000000 h
2015-01-14T17:25:14.590Z,1421256314.590 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2015-01-14T17:25:14.614Z,1421256314.614 [Default:CheckIn:A.SetSpeed](DEBUG): Construct.
2015-01-14T17:25:14.620Z,1421256314.620 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-01-14T17:25:14.644Z,1421256314.644 [Default:WaitAtTheSurface:RunApplicationAtLowSpeed.SetSpeed](DEBUG): Construct.
2015-01-14T17:25:14.659Z,1421256314.659 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

    <Aggregate Id="StartClock">

        <Sequence/>

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

    </Aggregate>

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

    <Aggregate Id="CheckIn">

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

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

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

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

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

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="UpdateAndReportMinutesSinceMissionStarted">

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

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

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

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

    </Aggregate>

</Mission>


2015-01-14T17:25:14.686Z,1421256314.686 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,DataOverHttps,Depth_Keller,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRCMReconstructedInterpolator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-01-14T17:25:14.838Z,1421256314.838 [NAL9602](INFO): Start
2015-01-14T17:25:14.838Z,1421256314.838 [NAL9602](INFO): Powering up NAL9602
2015-01-14T17:25:15.163Z,1421256315.163 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:15.175Z,1421256315.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 0 cycles since last successful read, accepting data for 5 cycles.
2015-01-14T17:25:15.183Z,1421256315.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:15.409Z,1421256315.409 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-01-14T17:25:15.435Z,1421256315.435 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-01-14T17:25:15.443Z,1421256315.443 [MassServo](DEBUG): Initializing MassServo.
2015-01-14T17:25:15.459Z,1421256315.459 [RudderServo](DEBUG): Initializing RudderServo.
2015-01-14T17:25:15.471Z,1421256315.471 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-01-14T17:25:15.624Z,1421256315.624 [NAL9602](INFO): Starting
2015-01-14T17:25:15.890Z,1421256315.890 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:15.891Z,1421256315.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 1 cycles since last successful read, accepting data for 5 cycles.
2015-01-14T17:25:15.892Z,1421256315.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:15.976Z,1421256315.976 [NAL9602](INFO): Starting
2015-01-14T17:25:16.034Z,1421256316.034 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:16.035Z,1421256316.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 2 cycles since last successful read, accepting data for 5 cycles.
2015-01-14T17:25:16.036Z,1421256316.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:16.369Z,1421256316.369 [NAL9602](INFO): Starting
2015-01-14T17:25:16.393Z,1421256316.393 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:16.394Z,1421256316.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 3 cycles since last successful read, accepting data for 5 cycles.
2015-01-14T17:25:16.395Z,1421256316.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:16.770Z,1421256316.770 [NAL9602](INFO): Starting
2015-01-14T17:25:16.858Z,1421256316.858 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:16.858Z,1421256316.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 4 cycles since last successful read, accepting data for 5 cycles.
2015-01-14T17:25:16.860Z,1421256316.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:17.170Z,1421256317.170 [NAL9602](INFO): Starting
2015-01-14T17:25:17.196Z,1421256317.196 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:17.197Z,1421256317.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): No bottom track data -- 5 cycles since last successful read, accepting data for 5 cycles.
2015-01-14T17:25:17.198Z,1421256317.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:17.567Z,1421256317.567 [NAL9602](INFO): Starting
2015-01-14T17:25:17.773Z,1421256317.773 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:17.775Z,1421256317.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:17.980Z,1421256317.980 [NAL9602](INFO): Starting
2015-01-14T17:25:17.989Z,1421256317.989 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:17.991Z,1421256317.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:18.369Z,1421256318.369 [NAL9602](INFO): Starting
2015-01-14T17:25:18.382Z,1421256318.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:18.384Z,1421256318.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:18.769Z,1421256318.769 [NAL9602](INFO): Starting
2015-01-14T17:25:18.806Z,1421256318.806 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:18.812Z,1421256318.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:19.169Z,1421256319.169 [NAL9602](INFO): Starting
2015-01-14T17:25:19.230Z,1421256319.230 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:19.231Z,1421256319.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:19.578Z,1421256319.578 [NAL9602](INFO): Starting
2015-01-14T17:25:19.609Z,1421256319.609 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:19.611Z,1421256319.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:19.967Z,1421256319.967 [NAL9602](INFO): Starting
2015-01-14T17:25:20.053Z,1421256320.053 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:20.062Z,1421256320.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:20.367Z,1421256320.367 [NAL9602](INFO): Starting
2015-01-14T17:25:20.565Z,1421256320.565 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:20.567Z,1421256320.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:20.768Z,1421256320.768 [NAL9602](INFO): Starting
2015-01-14T17:25:20.823Z,1421256320.823 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:20.825Z,1421256320.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:21.168Z,1421256321.168 [NAL9602](INFO): Starting
2015-01-14T17:25:21.217Z,1421256321.217 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:21.219Z,1421256321.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:21.398Z,1421256321.398 [CommandLine](IMPORTANT): got command quit
2015-01-14T17:25:21.569Z,1421256321.569 [NAL9602](INFO): Starting
2015-01-14T17:25:21.786Z,1421256321.786 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:21.788Z,1421256321.788 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:21.970Z,1421256321.970 [NAL9602](INFO): Starting
2015-01-14T17:25:22.007Z,1421256322.007 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:22.009Z,1421256322.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:22.370Z,1421256322.370 [NAL9602](INFO): Starting
2015-01-14T17:25:22.384Z,1421256322.384 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:22.385Z,1421256322.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:22.408Z,1421256322.408 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-14T17:25:22.409Z,1421256322.409 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:22.549Z,1421256322.549 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2015-01-14T17:25:22.550Z,1421256322.550 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:22.550Z,1421256322.550 [CommandLine](INFO): Join timeout helper Thread ID is 860
2015-01-14T17:25:22.566Z,1421256322.566 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-01-14T17:25:22.566Z,1421256322.566 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:22.566Z,1421256322.566 [NavChartDb](INFO): Join timeout helper Thread ID is 861
2015-01-14T17:25:22.767Z,1421256322.767 [NAL9602](INFO): Starting
2015-01-14T17:25:22.800Z,1421256322.800 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:22.802Z,1421256322.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:23.167Z,1421256323.167 [NAL9602](INFO): Starting
2015-01-14T17:25:23.205Z,1421256323.205 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:23.206Z,1421256323.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:23.568Z,1421256323.568 [NAL9602](INFO): Starting
2015-01-14T17:25:23.605Z,1421256323.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:23.607Z,1421256323.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:23.969Z,1421256323.969 [NAL9602](INFO): Starting
2015-01-14T17:25:24.006Z,1421256324.006 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:24.008Z,1421256324.008 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:24.369Z,1421256324.369 [NAL9602](INFO): Starting
2015-01-14T17:25:24.382Z,1421256324.382 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:24.384Z,1421256324.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:24.782Z,1421256324.782 [NAL9602](INFO): Starting
2015-01-14T17:25:24.855Z,1421256324.855 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:24.865Z,1421256324.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:25.183Z,1421256325.183 [NAL9602](INFO): Starting
2015-01-14T17:25:25.244Z,1421256325.244 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:25.258Z,1421256325.258 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:25.568Z,1421256325.568 [NAL9602](INFO): ************** WAKING **************

2015-01-14T17:25:25.605Z,1421256325.605 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:25.615Z,1421256325.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:25.968Z,1421256325.968 [NAL9602](INFO): ************** CMDMODE **************

2015-01-14T17:25:25.968Z,1421256325.968 [NAL9602](INFO): NAL9602 initialized
2015-01-14T17:25:25.997Z,1421256325.997 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:26.003Z,1421256326.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:26.394Z,1421256326.394 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:26.405Z,1421256326.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:26.818Z,1421256326.818 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:26.820Z,1421256326.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:27.223Z,1421256327.223 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:27.224Z,1421256327.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:27.576Z,1421256327.576 [NAL9602](INFO): ************** NEED GSV **************

2015-01-14T17:25:27.669Z,1421256327.669 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:27.670Z,1421256327.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:27.983Z,1421256327.983 [NAL9602](INFO): ************** NEED GSV **************

2015-01-14T17:25:27.997Z,1421256327.997 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:28.007Z,1421256328.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:28.372Z,1421256328.372 [NAL9602](INFO): ************** NEED GSV **************

2015-01-14T17:25:28.425Z,1421256328.425 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:28.427Z,1421256328.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:28.769Z,1421256328.769 [NAL9602](INFO): ************** NEED GSV **************

2015-01-14T17:25:28.793Z,1421256328.793 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:28.794Z,1421256328.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:29.169Z,1421256329.169 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:29.219Z,1421256329.219 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:29.225Z,1421256329.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:29.261Z,1421256329.261 [SBIT](IMPORTANT): Beginning Startup BIT
2015-01-14T17:25:29.263Z,1421256329.263 [CBIT](IMPORTANT): Beginning GF scan
2015-01-14T17:25:29.570Z,1421256329.570 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:29.570Z,1421256329.570 [NAL9602](INFO): ************** MTQueue SIGNAL QUALITY RESPONSE RECEIVED **************

2015-01-14T17:25:29.639Z,1421256329.639 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:29.641Z,1421256329.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:29.970Z,1421256329.970 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:29.983Z,1421256329.983 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:29.985Z,1421256329.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:30.371Z,1421256330.371 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:30.380Z,1421256330.380 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:30.382Z,1421256330.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:30.768Z,1421256330.768 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:30.781Z,1421256330.781 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:30.782Z,1421256330.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:31.169Z,1421256331.169 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:31.181Z,1421256331.181 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:31.183Z,1421256331.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:31.569Z,1421256331.569 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:31.578Z,1421256331.578 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:31.580Z,1421256331.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:31.973Z,1421256331.973 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:31.983Z,1421256331.983 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:31.984Z,1421256331.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:32.370Z,1421256332.370 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:32.387Z,1421256332.387 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:32.388Z,1421256332.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:32.767Z,1421256332.767 [NAL9602](INFO): ************** GET MTQueue SIGNAL QUALITY **************

2015-01-14T17:25:32.770Z,1421256332.770 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-14T17:25:32.770Z,1421256332.770 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.789Z,1421256332.789 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-01-14T17:25:32.790Z,1421256332.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.790Z,1421256332.790 [Radio_Surface](INFO): Join timeout helper Thread ID is 862
2015-01-14T17:25:32.802Z,1421256332.802 [HFRCMReconstructedInterpolator](DEBUG): no valid forecast
2015-01-14T17:25:32.804Z,1421256332.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2015-01-14T17:25:32.910Z,1421256332.910 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-14T17:25:32.910Z,1421256332.910 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.922Z,1421256332.922 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-01-14T17:25:32.922Z,1421256332.922 [logger ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.922Z,1421256332.922 [logger](INFO): Join timeout helper Thread ID is 863
2015-01-14T17:25:32.970Z,1421256332.970 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-14T17:25:32.970Z,1421256332.970 [logger ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.982Z,1421256332.982 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-01-14T17:25:32.982Z,1421256332.982 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.982Z,1421256332.982 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-01-14T17:25:32.982Z,1421256332.982 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:32.982Z,1421256332.982 [controlThread](INFO): Join timeout helper Thread ID is 864
2015-01-14T17:25:33.158Z,1421256333.158 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-01-14T17:25:33.158Z,1421256333.158 [controlThread](DEBUG): Uninitializing ControlThread
2015-01-14T17:25:33.160Z,1421256333.160 [NAL9602](INFO): Uninitialize
2015-01-14T17:25:33.160Z,1421256333.160 [NAL9602](INFO): Powering down
2015-01-14T17:25:33.162Z,1421256333.162 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-01-14T17:25:33.163Z,1421256333.163 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-01-14T17:25:33.167Z,1421256333.167 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-01-14T17:25:33.168Z,1421256333.168 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-01-14T17:25:33.168Z,1421256333.168 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-01-14T17:25:33.168Z,1421256333.168 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-01-14T17:25:33.168Z,1421256333.168 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-01-14T17:25:33.169Z,1421256333.169 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-01-14T17:25:33.169Z,1421256333.169 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-01-14T17:25:33.169Z,1421256333.169 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-01-14T17:25:33.169Z,1421256333.169 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-01-14T17:25:33.170Z,1421256333.170 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-01-14T17:25:33.170Z,1421256333.170 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-01-14T17:25:33.170Z,1421256333.170 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-01-14T17:25:33.203Z,1421256333.203 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:33.313Z,1421256333.313 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:33.354Z,1421256333.354 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-01-14T17:25:33.393Z,1421256333.392 [logger ThreadHandler](INFO): Thread cancelled.