2014-06-04T22:18:13.216Z,1401920293.216 [Supervisor](DEBUG): Initializing supervisor.
2014-06-04T22:18:13.218Z,1401920293.218 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2014-06-04T22:18:13.219Z,1401920293.219 [SyncHandler](INFO): Protected caller Thread ID is 8510
2014-06-04T22:18:13.220Z,1401920293.220 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-06-04T22:18:13.221Z,1401920293.221 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2014-06-04T22:18:13.221Z,1401920293.221 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8511
2014-06-04T22:18:13.224Z,1401920293.224 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-06-04T22:18:13.246Z,1401920293.246 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-06-04T22:18:13.247Z,1401920293.247 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2014-06-04T22:18:13.247Z,1401920293.247 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8512
2014-06-04T22:18:13.248Z,1401920293.248 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-06-04T22:18:13.249Z,1401920293.249 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2014-06-04T22:18:13.250Z,1401920293.250 [logger ThreadHandler](INFO): Protected caller Thread ID is 8513
2014-06-04T22:18:13.252Z,1401920293.252 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-06-04T22:18:13.252Z,1401920293.252 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-06-04T22:18:13.254Z,1401920293.254 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-06-04T22:18:13.610Z,1401920293.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-06-04T22:18:13.611Z,1401920293.611 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-06-04T22:18:13.895Z,1401920293.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-06-04T22:18:13.895Z,1401920293.895 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-06-04T22:18:13.985Z,1401920293.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-06-04T22:18:13.986Z,1401920293.986 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-06-04T22:18:14.166Z,1401920294.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-06-04T22:18:14.167Z,1401920294.167 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-06-04T22:18:14.331Z,1401920294.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-06-04T22:18:14.332Z,1401920294.332 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-06-04T22:18:14.633Z,1401920294.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-06-04T22:18:14.634Z,1401920294.634 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-06-04T22:18:14.822Z,1401920294.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-06-04T22:18:14.823Z,1401920294.823 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-06-04T22:18:15.198Z,1401920295.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-06-04T22:18:15.199Z,1401920295.199 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-06-04T22:18:15.310Z,1401920295.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-06-04T22:18:15.311Z,1401920295.311 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-06-04T22:18:15.809Z,1401920295.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-06-04T22:18:15.810Z,1401920295.810 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-06-04T22:18:15.926Z,1401920295.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-06-04T22:18:15.926Z,1401920295.926 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-06-04T22:18:16.014Z,1401920296.014 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-06-04T22:18:16.142Z,1401920296.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-06-04T22:18:16.142Z,1401920296.142 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-06-04T22:18:16.248Z,1401920296.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-06-04T22:18:16.248Z,1401920296.248 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-06-04T22:18:16.389Z,1401920296.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-06-04T22:18:16.391Z,1401920296.391 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-06-04T22:18:16.392Z,1401920296.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-06-04T22:18:16.504Z,1401920296.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-06-04T22:18:16.634Z,1401920296.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-06-04T22:18:16.732Z,1401920296.732 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-06-04T22:18:16.826Z,1401920296.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-06-04T22:18:16.969Z,1401920296.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-06-04T22:18:17.086Z,1401920297.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-06-04T22:18:17.174Z,1401920297.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-06-04T22:18:17.279Z,1401920297.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-06-04T22:18:17.382Z,1401920297.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2014-06-04T22:18:17.481Z,1401920297.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2014-06-04T22:18:17.570Z,1401920297.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Estimation.cfg
2014-06-04T22:18:17.670Z,1401920297.670 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2014-06-04T22:18:17.670Z,1401920297.670 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-06-04T22:18:17.672Z,1401920297.672 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-06-04T22:18:18.038Z,1401920298.038 [AHRS_sp3003D] Loaded
2014-06-04T22:18:18.038Z,1401920298.038 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-06-04T22:18:18.353Z,1401920298.353 [Batt_Ocean_Server] Loaded
2014-06-04T22:18:18.353Z,1401920298.353 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-06-04T22:18:18.368Z,1401920298.368 [Depth_Keller] Loaded
2014-06-04T22:18:18.368Z,1401920298.368 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-06-04T22:18:18.373Z,1401920298.373 [DropWeight] Loaded
2014-06-04T22:18:18.373Z,1401920298.373 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-06-04T22:18:18.521Z,1401920298.521 [DVL_micro] Loaded
2014-06-04T22:18:18.521Z,1401920298.521 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-06-04T22:18:18.628Z,1401920298.628 [NAL9602] Loaded
2014-06-04T22:18:18.628Z,1401920298.628 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-06-04T22:18:18.683Z,1401920298.683 [Onboard] Loaded
2014-06-04T22:18:18.683Z,1401920298.683 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-06-04T22:18:18.691Z,1401920298.691 [Radio_Freewave] Loaded
2014-06-04T22:18:18.691Z,1401920298.691 [ComponentRegistry](DEBUG): Component "Radio_Freewave" handled in its own thread.
2014-06-04T22:18:18.692Z,1401920298.692 [Radio_Freewave ThreadHandler](DEBUG): Created PCaller Thread at 4060F4E0
2014-06-04T22:18:18.693Z,1401920298.693 [Radio_Freewave ThreadHandler](INFO): Protected caller Thread ID is 8595
2014-06-04T22:18:18.700Z,1401920298.700 [SCPI] Loaded
2014-06-04T22:18:18.700Z,1401920298.700 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2014-06-04T22:18:18.701Z,1401920298.701 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-06-04T22:18:18.701Z,1401920298.701 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-06-04T22:18:18.872Z,1401920298.872 [InternalSim] Loaded
2014-06-04T22:18:18.872Z,1401920298.872 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-06-04T22:18:18.873Z,1401920298.873 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-06-04T22:18:18.873Z,1401920298.873 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-06-04T22:18:19.177Z,1401920299.177 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-06-04T22:18:19.178Z,1401920299.178 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-06-04T22:18:19.185Z,1401920299.185 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-06-04T22:18:19.186Z,1401920299.186 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-06-04T22:18:19.271Z,1401920299.271 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-06-04T22:18:19.272Z,1401920299.272 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-06-04T22:18:19.319Z,1401920299.319 [DeadReckonWithRespectToWater] Loaded
2014-06-04T22:18:19.320Z,1401920299.320 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread.
2014-06-04T22:18:19.368Z,1401920299.368 [DeadReckonWithRespectToSeafloor] Loaded
2014-06-04T22:18:19.369Z,1401920299.369 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2014-06-04T22:18:19.418Z,1401920299.418 [DeadReckonUsingDVLWaterTrack] Loaded
2014-06-04T22:18:19.419Z,1401920299.419 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread.
2014-06-04T22:18:19.469Z,1401920299.469 [DeadReckonUsingCompactModelForecast] Loaded
2014-06-04T22:18:19.469Z,1401920299.469 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingCompactModelForecast" handled in the control thread.
2014-06-04T22:18:19.486Z,1401920299.486 [NavChart] Loaded
2014-06-04T22:18:19.486Z,1401920299.486 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-06-04T22:18:19.491Z,1401920299.491 [UniversalFixResidualReporter] Loaded
2014-06-04T22:18:19.491Z,1401920299.491 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2014-06-04T22:18:19.492Z,1401920299.492 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-06-04T22:18:19.492Z,1401920299.492 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-06-04T22:18:19.543Z,1401920299.543 [VerticalControl](DEBUG): Construct VerticalControl.
2014-06-04T22:18:19.662Z,1401920299.662 [VerticalControl] Loaded
2014-06-04T22:18:19.663Z,1401920299.663 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-06-04T22:18:19.663Z,1401920299.663 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-06-04T22:18:19.736Z,1401920299.736 [HorizontalControl] Loaded
2014-06-04T22:18:19.736Z,1401920299.736 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-06-04T22:18:19.737Z,1401920299.737 [SpeedControl](DEBUG): Construct SpeedControl.
2014-06-04T22:18:19.738Z,1401920299.738 [SpeedControl] Loaded
2014-06-04T22:18:19.739Z,1401920299.739 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-06-04T22:18:19.739Z,1401920299.739 [LoopControl](DEBUG): Construct LoopControl.
2014-06-04T22:18:19.740Z,1401920299.740 [LoopControl] Loaded
2014-06-04T22:18:19.740Z,1401920299.740 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-06-04T22:18:19.741Z,1401920299.741 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-06-04T22:18:19.741Z,1401920299.741 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-06-04T22:18:19.772Z,1401920299.772 [DepthRateCalculator] Loaded
2014-06-04T22:18:19.772Z,1401920299.772 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-06-04T22:18:19.778Z,1401920299.778 [PitchRateCalculator] Loaded
2014-06-04T22:18:19.778Z,1401920299.778 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-06-04T22:18:19.786Z,1401920299.786 [SpeedCalculator] Loaded
2014-06-04T22:18:19.786Z,1401920299.786 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-06-04T22:18:19.804Z,1401920299.804 [TempGradientCalculator] Loaded
2014-06-04T22:18:19.804Z,1401920299.804 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-06-04T22:18:19.809Z,1401920299.809 [YawRateCalculator] Loaded
2014-06-04T22:18:19.810Z,1401920299.810 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-06-04T22:18:19.810Z,1401920299.810 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-06-04T22:18:19.811Z,1401920299.811 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-06-04T22:18:19.960Z,1401920299.960 [CTD_NeilBrown] Loaded
2014-06-04T22:18:19.961Z,1401920299.961 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-06-04T22:18:19.963Z,1401920299.963 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4075E4E0
2014-06-04T22:18:19.963Z,1401920299.963 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8596
2014-06-04T22:18:19.976Z,1401920299.976 [ISUS] Loaded
2014-06-04T22:18:19.977Z,1401920299.977 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2014-06-04T22:18:19.992Z,1401920299.992 [PAR_Licor] Loaded
2014-06-04T22:18:19.992Z,1401920299.992 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2014-06-04T22:18:20.029Z,1401920300.029 [WetLabsBB2FL] Loaded
2014-06-04T22:18:20.029Z,1401920300.029 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-06-04T22:18:20.030Z,1401920300.030 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4078E4E0
2014-06-04T22:18:20.031Z,1401920300.031 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8597
2014-06-04T22:18:20.032Z,1401920300.032 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-06-04T22:18:20.032Z,1401920300.032 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-06-04T22:18:20.161Z,1401920300.161 [SBIT](DEBUG): Construct Startup Built In Test.
2014-06-04T22:18:20.173Z,1401920300.173 [SBIT] Loaded
2014-06-04T22:18:20.173Z,1401920300.173 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-06-04T22:18:20.174Z,1401920300.174 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-06-04T22:18:20.187Z,1401920300.187 [IBIT] Loaded
2014-06-04T22:18:20.187Z,1401920300.187 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-06-04T22:18:20.189Z,1401920300.189 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-06-04T22:18:20.264Z,1401920300.264 [CBIT] Loaded
2014-06-04T22:18:20.264Z,1401920300.264 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-06-04T22:18:20.265Z,1401920300.265 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-06-04T22:18:20.265Z,1401920300.265 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-06-04T22:18:24.919Z,1401920304.919 [HFRadarModelCalc] Loaded
2014-06-04T22:18:24.919Z,1401920304.919 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2014-06-04T22:18:28.686Z,1401920308.686 [HFRadarCompactModelForecaster] Loaded
2014-06-04T22:18:28.687Z,1401920308.687 [ComponentRegistry](DEBUG): SyncComponent "HFRadarCompactModelForecaster" handled in the control thread.
2014-06-04T22:18:29.614Z,1401920309.614 [HFRCMSpaceInterpolator] Loaded
2014-06-04T22:18:29.614Z,1401920309.614 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSpaceInterpolator" handled in the control thread.
2014-06-04T22:18:29.616Z,1401920309.616 [HFRCMTimeInterpolator] Loaded
2014-06-04T22:18:29.617Z,1401920309.617 [ComponentRegistry](DEBUG): SyncComponent "HFRCMTimeInterpolator" handled in the control thread.
2014-06-04T22:18:30.665Z,1401920310.665 [HFRCMReconstructedInterpolator] Loaded
2014-06-04T22:18:30.666Z,1401920310.666 [ComponentRegistry](DEBUG): SyncComponent "HFRCMReconstructedInterpolator" handled in the control thread.
2014-06-04T22:18:30.678Z,1401920310.678 [HFRCMSurfaceCurrentAtVehicleLocation] Loaded
2014-06-04T22:18:30.678Z,1401920310.678 [ComponentRegistry](DEBUG): SyncComponent "HFRCMSurfaceCurrentAtVehicleLocation" handled in the control thread.
2014-06-04T22:18:30.707Z,1401920310.707 [HFRCMVirtualSurfaceDrifter] Loaded
2014-06-04T22:18:30.708Z,1401920310.708 [ComponentRegistry](DEBUG): SyncComponent "HFRCMVirtualSurfaceDrifter" handled in the control thread.
2014-06-04T22:18:30.708Z,1401920310.708 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-06-04T22:18:30.709Z,1401920310.709 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-06-04T22:18:30.813Z,1401920310.813 [BuoyancyServo] Loaded
2014-06-04T22:18:30.814Z,1401920310.814 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-06-04T22:18:30.827Z,1401920310.827 [ElevatorServo] Loaded
2014-06-04T22:18:30.827Z,1401920310.827 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-06-04T22:18:30.839Z,1401920310.839 [MassServo] Loaded
2014-06-04T22:18:30.839Z,1401920310.839 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-06-04T22:18:30.852Z,1401920310.852 [RudderServo] Loaded
2014-06-04T22:18:30.852Z,1401920310.852 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-06-04T22:18:30.864Z,1401920310.864 [ThrusterServo] Loaded
2014-06-04T22:18:30.864Z,1401920310.864 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-06-04T22:18:30.865Z,1401920310.865 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-06-04T22:18:30.865Z,1401920310.865 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-06-04T22:18:30.890Z,1401920310.890 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-06-04T22:18:30.892Z,1401920310.892 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-06-04T22:18:30.893Z,1401920310.893 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-06-04T22:18:30.900Z,1401920310.900 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-06-04T22:18:30.901Z,1401920310.901 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2014-06-04T22:18:30.901Z,1401920310.901 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8598
2014-06-04T22:18:30.908Z,1401920310.908 [Supervisor](INFO): Main Thread ID is 8509
2014-06-04T22:18:30.908Z,1401920310.908 [Supervisor](DEBUG): Running supervisor.
2014-06-04T22:18:30.909Z,1401920310.909 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8599
2014-06-04T22:18:30.911Z,1401920310.911 [controlThread ThreadHandler](INFO): Handler Thread ID is 8600
2014-06-04T22:18:30.912Z,1401920310.912 [controlThread](DEBUG): Initializing ControlThread
2014-06-04T22:18:30.915Z,1401920310.915 [logger ThreadHandler](INFO): Handler Thread ID is 8601
2014-06-04T22:18:30.936Z,1401920310.936 [Radio_Freewave ThreadHandler](INFO): Handler Thread ID is 8602
2014-06-04T22:18:30.952Z,1401920310.952 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8603
2014-06-04T22:18:30.953Z,1401920310.953 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-06-04T22:18:30.956Z,1401920310.956 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-06-04T22:18:30.965Z,1401920310.965 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8605
2014-06-04T22:18:30.966Z,1401920310.966 [WetLabsBB2FL](INFO): Powering down
2014-06-04T22:18:30.995Z,1401920310.995 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8606
2014-06-04T22:18:31.013Z,1401920311.013 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-06-04T22:18:31.013Z,1401920311.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-06-04T22:18:31.014Z,1401920311.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-06-04T22:18:31.014Z,1401920311.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-06-04T22:18:31.014Z,1401920311.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-06-04T22:18:31.014Z,1401920311.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-06-04T22:18:31.015Z,1401920311.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-06-04T22:18:31.015Z,1401920311.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-06-04T22:18:31.015Z,1401920311.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-06-04T22:18:32.429Z,1401920312.429 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-06-04T22:18:32.461Z,1401920312.461 [InternalSim](DEBUG):  InternalSim initializing...
2014-06-04T22:18:32.577Z,1401920312.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-06-04T22:18:32.577Z,1401920312.577 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component.
2014-06-04T22:18:32.578Z,1401920312.578 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2014-06-04T22:18:32.583Z,1401920312.583 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component.
2014-06-04T22:18:32.583Z,1401920312.583 [DeadReckonUsingCompactModelForecast](DEBUG): Initializing DeadReckonUsingCompactModelForecast component.
2014-06-04T22:18:32.583Z,1401920312.583 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-06-04T22:18:32.584Z,1401920312.584 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-06-04T22:18:32.584Z,1401920312.584 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-06-04T22:18:32.586Z,1401920312.586 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-06-04T22:18:32.587Z,1401920312.587 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-06-04T22:18:32.587Z,1401920312.587 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-06-04T22:18:32.588Z,1401920312.588 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-06-04T22:18:32.588Z,1401920312.588 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-06-04T22:18:32.588Z,1401920312.588 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-06-04T22:18:32.589Z,1401920312.589 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-06-04T22:18:32.589Z,1401920312.589 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-06-04T22:18:32.591Z,1401920312.591 [SBIT](INFO): Initialize SBIT Component.
2014-06-04T22:18:32.591Z,1401920312.591 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:11372
2014-06-04T22:18:32.592Z,1401920312.592 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-06-04T22:18:32.592Z,1401920312.592 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-06-04T22:18:32.593Z,1401920312.593 [IBIT](INFO): Initialize IBIT Component.
2014-06-04T22:18:32.593Z,1401920312.593 [CBIT](DEBUG): Initialize CBIT Component.
2014-06-04T22:18:32.594Z,1401920312.594 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-06-04T22:18:32.633Z,1401920312.633 [HFRadarCompactModelForecaster](DEBUG): Found new ECs!
2014-06-04T22:18:33.245Z,1401920313.245 [Radio_Freewave](INFO): Powering up
2014-06-04T22:18:34.054Z,1401920314.054 [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-06-04T22:18:41.195Z,1401920321.195 [HFRadarCompactModelForecaster](IMPORTANT): forecast time 201406041900: published 50 modes in rows, 61 hours in columns
2014-06-04T22:18:41.199Z,1401920321.199 [HFRadarCompactModelForecaster](IMPORTANT): expansionCoefficients_[0][48] = 4.618326 for 2014-06-04T19:00:00.000Z
2014-06-04T22:18:41.200Z,1401920321.200 [HFRCMSpaceInterpolator](DEBUG): Initializing HFRCMSpaceInterpolator component with verbosity level 0.
2014-06-04T22:18:41.202Z,1401920321.202 [HFRCMSpaceInterpolator](INFO): gridIdxRev_: 541 by 1, [25.000000, 718.000000]
2014-06-04T22:18:41.202Z,1401920321.202 [HFRCMSpaceInterpolator](INFO): gridIdx_: 25 by 29, [nan, nan]
2014-06-04T22:18:41.203Z,1401920321.203 [HFRCMSpaceInterpolator](INFO): longitude grid: 25 by 29, [-122.743156, -121.797913]
2014-06-04T22:18:41.204Z,1401920321.204 [HFRCMSpaceInterpolator](INFO): latitude grid: 25 by 29, [36.341251, 36.990059]
2014-06-04T22:18:41.212Z,1401920321.211 [HFRCMTimeInterpolator](DEBUG): Initializing HFRCMTimeInterpolator component.
2014-06-04T22:18:41.212Z,1401920321.212 [HFRCMReconstructedInterpolator](DEBUG): Initializing HFRCMReconstructedInterpolator component.
2014-06-04T22:18:41.212Z,1401920321.212 [HFRCMReconstructedInterpolator](INFO): velocityAccuracy_ = 0.300000 m/s
2014-06-04T22:18:41.214Z,1401920321.214 [HFRCMReconstructedInterpolator](INFO): gridIdxRev_: 541 by 1, [25.000000, 718.000000]
2014-06-04T22:18:41.215Z,1401920321.215 [HFRCMReconstructedInterpolator](INFO): gridIdx_: 25 by 29, [nan, nan]
2014-06-04T22:18:41.216Z,1401920321.216 [HFRCMReconstructedInterpolator](INFO): longitude grid: 25 by 29, [-122.743156, -121.797913]
2014-06-04T22:18:41.217Z,1401920321.217 [HFRCMReconstructedInterpolator](INFO): latitude grid: 25 by 29, [36.341251, 36.990059]
2014-06-04T22:18:41.222Z,1401920321.222 [HFRCMSurfaceCurrentAtVehicleLocation](DEBUG): Initializing HFRCMSurfaceCurrentAtVehicleLocation component.
2014-06-04T22:18:41.222Z,1401920321.222 [HFRCMVirtualSurfaceDrifter](DEBUG): Initializing HFRCMVirtualSurfaceDrifter component.
2014-06-04T22:18:41.238Z,1401920321.238 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-06-04T22:18:41.265Z,1401920321.265 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-04T22:18:41.294Z,1401920321.294 [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-06-04T22:18:41.295Z,1401920321.295 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-06-04T22:18:41.374Z,1401920321.374 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-06-04T22:18:41.392Z,1401920321.392 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-06-04T22:18:41.396Z,1401920321.396 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-04T22:18:41.435Z,1401920321.435 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-06-04T22:18:41.449Z,1401920321.449 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-06-04T22:18:41.468Z,1401920321.468 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-06-04T22:18:41.507Z,1401920321.507 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-06-04T22:18:41.541Z,1401920321.541 [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>
            <Progression/>
        </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>
            <Progression/>
        </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>

    </Aggregate>

</Mission>


2014-06-04T22:18:41.549Z,1401920321.549 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,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-06-04T22:18:41.697Z,1401920321.697 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-06-04T22:18:42.219Z,1401920322.219 [DVL_micro](INFO): Initializing
2014-06-04T22:18:42.244Z,1401920322.244 [NAL9602](INFO): Powering up NAL9602
2014-06-04T22:18:42.482Z,1401920322.482 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter
2014-06-04T22:18:42.484Z,1401920322.484 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2014-06-04T22:18:49.569Z,1401920329.569 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2014-06-04T22:18:49.569Z,1401920329.569 [CommandLine](FAULT): Element has no value
2014-06-04T22:18:49.789Z,1401920329.789 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-06-04T22:18:49.797Z,1401920329.797 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-06-04T22:18:49.819Z,1401920329.819 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-06-04T22:18:49.825Z,1401920329.825 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-06-04T22:18:49.847Z,1401920329.847 [MassServo](DEBUG): Initializing EZServoServo.
2014-06-04T22:18:49.853Z,1401920329.853 [MassServo](DEBUG): Initializing MassServo.
2014-06-04T22:18:49.859Z,1401920329.859 [RudderServo](DEBUG): Initializing EZServoServo.
2014-06-04T22:18:49.865Z,1401920329.865 [RudderServo](DEBUG): Initializing RudderServo.
2014-06-04T22:18:49.884Z,1401920329.884 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-06-04T22:18:49.893Z,1401920329.893 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-06-04T22:18:49.976Z,1401920329.976 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-06-04T22:18:50.931Z,1401920330.931 [SBIT](IMPORTANT): Beginning Startup BIT
2014-06-04T22:18:50.934Z,1401920330.934 [CBIT](IMPORTANT): Beginning GF scan
2014-06-04T22:18:52.822Z,1401920332.822 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2014-06-04T22:18:52.822Z,1401920332.822 [CommandLine](FAULT): Element has no value
2014-06-04T22:18:53.074Z,1401920333.074 [NAL9602](INFO): NAL9602 initialized
2014-06-04T22:18:56.487Z,1401920336.487 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2014-06-04T22:18:56.488Z,1401920336.488 [CommandLine](IMPORTANT): platform_buoyancy_position 105.818304 cc
2014-06-04T22:18:59.448Z,1401920339.448 [CommandLine](IMPORTANT): got command maintain 
2014-06-04T22:18:59.449Z,1401920339.449 [Maintain_VerticalControl.buoyancyAction](INFO): VerticalControl.buoyancyAction maintaining 99.999997 cc
2014-06-04T22:19:05.706Z,1401920345.706 [CommandLine](IMPORTANT): got command show variable thruster
2014-06-04T22:19:05.721Z,1401920345.721 [CommandLine](IMPORTANT): ThrusterServo.loadControl (none)
2014-06-04T22:19:05.722Z,1401920345.722 [CommandLine](IMPORTANT): ThrusterServo.uart (none)
2014-06-04T22:19:05.722Z,1401920345.722 [CommandLine](IMPORTANT): ThrusterServo.baud (bit_per_second)
2014-06-04T22:19:05.745Z,1401920345.745 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup (bool)
2014-06-04T22:19:05.762Z,1401920345.762 [CommandLine](IMPORTANT): ThrusterServo.simulateHardware (bool)
2014-06-04T22:19:05.762Z,1401920345.762 [CommandLine](IMPORTANT): ThrusterServo.powerOnTimeout (second)
2014-06-04T22:19:05.762Z,1401920345.762 [CommandLine](IMPORTANT): ThrusterServo.currLimit (percent)
2014-06-04T22:19:05.763Z,1401920345.763 [CommandLine](IMPORTANT): ThrusterServo.pidW (count)
2014-06-04T22:19:05.763Z,1401920345.763 [CommandLine](IMPORTANT): ThrusterServo.pidX (count)
2014-06-04T22:19:05.764Z,1401920345.764 [CommandLine](IMPORTANT): ThrusterServo.pidY (count)
2014-06-04T22:19:05.764Z,1401920345.764 [CommandLine](IMPORTANT): ThrusterServo.overloadTimeout (millisecond)
2014-06-04T22:19:05.764Z,1401920345.764 [CommandLine](IMPORTANT): ThrusterServo.accel (none)
2014-06-04T22:19:05.765Z,1401920345.765 [CommandLine](IMPORTANT): ThrusterServo.encoderTks (count_per_second)
2014-06-04T22:19:05.765Z,1401920345.765 [CommandLine](IMPORTANT): ThrusterServo.tksPerRev (count_per_revolution)
2014-06-04T22:19:05.774Z,1401920345.774 [CommandLine](IMPORTANT): ThrusterServo.deviation (count)
2014-06-04T22:19:05.774Z,1401920345.774 [CommandLine](IMPORTANT): ThrusterServo.allowableBadVelocity (count)
2014-06-04T22:19:05.967Z,1401920345.967 [CommandLine](IMPORTANT): ThrusterServo.platform_propeller_rotation_rate (radian_per_second)
2014-06-04T22:19:05.986Z,1401920345.986 [CommandLine](IMPORTANT): ThrusterServo.durationOfLastRun (second)
2014-06-04T22:19:05.989Z,1401920345.989 [CommandLine](IMPORTANT): ThrusterServo.component_voltage (volt)
2014-06-04T22:19:06.006Z,1401920346.006 [CommandLine](IMPORTANT): ThrusterServo.component_avgVoltage (volt)
2014-06-04T22:19:06.006Z,1401920346.006 [CommandLine](IMPORTANT): ThrusterServo.component_current (milliampere)
2014-06-04T22:19:06.007Z,1401920346.007 [CommandLine](IMPORTANT): ThrusterServo.component_avgCurrent (milliampere)
2014-06-04T22:19:13.067Z,1401920353.067 [CommandLine](IMPORTANT): got command report mod ThrusterServo.component_avgCurrent
2014-06-04T22:19:13.203Z,1401920353.203 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA
2014-06-04T22:19:18.528Z,1401920358.528 [CBIT](FAULT): Low side GF detected
mA:
CHAN 5 (24V): 0.391695
CHAN 4 (Batt): 0.391695
CHAN 2 (12V): 0.391695
CHAN 1 (5V): 0.391695
CHAN 0 (3.3V): 0.338608
OPEN: 0.000957
Full Scale Calc: 0.392
2014-06-04T22:19:21.334Z,1401920361.334 [CommandLine](IMPORTANT): got command report mod VerticalControl.verticalMode
2014-06-04T22:19:21.589Z,1401920361.589 [Reporter](INFO): VerticalControl.verticalMode 7 enum
2014-06-04T22:19:27.199Z,1401920367.199 [CommandLine](IMPORTANT): got command show variable speed
2014-06-04T22:19:27.203Z,1401920367.203 [CommandLine](IMPORTANT): platform_speed_wrt_ground (meter_per_second)
2014-06-04T22:19:27.203Z,1401920367.203 [CommandLine](IMPORTANT): platform_speed_wrt_sea_water (meter_per_second)
2014-06-04T22:19:27.205Z,1401920367.205 [CommandLine](IMPORTANT): sea_water_speed (unknown)
2014-06-04T22:19:27.222Z,1401920367.222 [CommandLine](IMPORTANT): SpeedControl.loadAtStartup (bool)
2014-06-04T22:19:27.247Z,1401920367.247 [CommandLine](IMPORTANT): SpeedControl.propPitch (meter_per_radian)
2014-06-04T22:19:27.256Z,1401920367.256 [CommandLine](IMPORTANT): Config/Simulator.designSpeed (meter_per_second)
2014-06-04T22:19:27.264Z,1401920367.264 [CommandLine](IMPORTANT): Config/Simulator.speedRud (angular_degree_per_second)
2014-06-04T22:19:27.265Z,1401920367.265 [CommandLine](IMPORTANT): Config/Simulator.speedElev (angular_degree_per_second)
2014-06-04T22:19:27.266Z,1401920367.266 [CommandLine](IMPORTANT): Config/Simulator.soundSpeed (meter_per_second)
2014-06-04T22:19:27.272Z,1401920367.272 [CommandLine](IMPORTANT): Config/Simulator.speedMovableMass (millimeter_per_second)
2014-06-04T22:19:27.272Z,1401920367.272 [CommandLine](IMPORTANT): Config/Simulator.speedBuoyancy (cubic_centimeter_per_second)
2014-06-04T22:19:27.273Z,1401920367.273 [CommandLine](IMPORTANT): SpeedCalculator.loadAtStartup (bool)
2014-06-04T22:19:27.274Z,1401920367.274 [CommandLine](IMPORTANT): SpeedCalculator.speedAccuracy (meter_per_second)
2014-06-04T22:19:27.326Z,1401920367.326 [CommandLine](IMPORTANT): DVL_micro.platform_speed_wrt_ground (meter_per_second)
2014-06-04T22:19:27.339Z,1401920367.339 [CommandLine](IMPORTANT): DVL_micro.platform_speed_wrt_sea_water (meter_per_second)
2014-06-04T22:19:27.342Z,1401920367.342 [CommandLine](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second)
2014-06-04T22:19:27.353Z,1401920367.353 [CommandLine](IMPORTANT): SpeedCalculator.platform_speed_wrt_sea_water (meter_per_second)
2014-06-04T22:19:27.369Z,1401920367.369 [CommandLine](IMPORTANT): SpeedControl.speedCmd (meter_per_second)
2014-06-04T22:19:27.370Z,1401920367.370 [CommandLine](IMPORTANT): SpeedCalculator.platform_x_velocity_wrt_sea_water (meter_per_second)
2014-06-04T22:19:27.400Z,1401920367.400 [CommandLine](IMPORTANT): SpeedCalculator.durationOfLastRun (second)
2014-06-04T22:19:27.402Z,1401920367.402 [CommandLine](IMPORTANT): SpeedControl.durationOfLastRun (second)
2014-06-04T22:19:44.214Z,1401920384.214 [CommandLine](IMPORTANT): got command report mod SpeedControl.propOmegaAction
2014-06-04T22:19:44.215Z,1401920384.215 [CommandLine](IMPORTANT): got command report mod SpeedControl.speedCmd
2014-06-04T22:19:44.355Z,1401920384.355 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:19:44.355Z,1401920384.355 [Reporter](INFO): SpeedControl.speedCmd no_value
2014-06-04T22:19:45.177Z,1401920385.177 [SBIT](IMPORTANT): SBIT PASSED
2014-06-04T22:19:45.634Z,1401920385.634 [MissionManager](IMPORTANT): Started mission Startup
2014-06-04T22:19:45.634Z,1401920385.634 [Startup] Running Loop=1
2014-06-04T22:19:45.635Z,1401920385.635 [Startup](INFO): Aggregate::initialize Startup
2014-06-04T22:19:45.635Z,1401920385.635 [Startup:A.GoToSurface] Running Loop=1
2014-06-04T22:19:45.635Z,1401920385.635 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-04T22:19:45.636Z,1401920385.636 [Startup:A.GoToSurface](ERROR): No speed setting specified. Using default value of 0.500000
2014-06-04T22:19:45.642Z,1401920385.642 [Startup:StartupSatComms] Running Loop=1
2014-06-04T22:19:45.642Z,1401920385.642 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-06-04T22:19:45.642Z,1401920385.642 [Startup:StartupSatComms:A] Running Loop=1
2014-06-04T22:19:45.674Z,1401920385.674 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:19:45.675Z,1401920385.675 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:19:46.078Z,1401920386.078 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-06-04T22:19:52.883Z,1401920392.883 [CommandLine](IMPORTANT): got command get depth
2014-06-04T22:19:52.884Z,1401920392.884 [CommandLine](IMPORTANT): depth 0.381329 m
2014-06-04T22:19:55.976Z,1401920395.976 [Reporter](INFO): VerticalControl.verticalMode 1 enum
2014-06-04T22:19:55.978Z,1401920395.978 [Reporter](INFO): SpeedControl.propOmegaAction 15.708007 rad/s
2014-06-04T22:19:55.978Z,1401920395.978 [Reporter](INFO): SpeedControl.speedCmd 0.500000 m/s
2014-06-04T22:19:56.202Z,1401920396.202 [Radio_Freewave](INFO): Powering down
2014-06-04T22:19:57.906Z,1401920397.906 [Reporter](INFO): ThrusterServo.component_avgCurrent 51.380001 mA
2014-06-04T22:20:01.433Z,1401920401.433 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:20:04.150Z,1401920404.150 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:20:06.912Z,1401920406.912 [Reporter](INFO): ThrusterServo.component_avgCurrent 99.090002 mA
2014-06-04T22:20:08.916Z,1401920408.916 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:20:11.175Z,1401920411.175 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:20:13.683Z,1401920413.683 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:20:19.051Z,1401920419.051 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:20:23.674Z,1401920423.674 [Reporter](INFO): ThrusterServo.component_avgCurrent 99.090002 mA
2014-06-04T22:20:25.906Z,1401920425.906 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:20:28.158Z,1401920428.158 [CommandLine](IMPORTANT): got command get platform_pitch_angle
2014-06-04T22:20:28.158Z,1401920428.158 [CommandLine](IMPORTANT): platform_pitch_angle -7.512812 arcdeg
2014-06-04T22:20:29.208Z,1401920429.208 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:20:34.154Z,1401920434.154 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119998 mA
2014-06-04T22:20:36.097Z,1401920436.097 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:20:40.721Z,1401920440.721 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:20:43.409Z,1401920443.409 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:20:45.681Z,1401920445.681 [Startup:StartupSatComms:A](INFO): Timed out from 2014-06-04T22:19:45.6Z
2014-06-04T22:20:45.682Z,1401920445.682 [Startup:StartupSatComms:A] Stopped
2014-06-04T22:20:45.682Z,1401920445.682 [Startup:StartupSatComms:B] Running Loop=1
2014-06-04T22:20:46.098Z,1401920446.098 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-06-04T22:20:46.169Z,1401920446.169 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:20:48.546Z,1401920448.546 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:20:51.826Z,1401920451.826 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:20:54.238Z,1401920454.238 [Reporter](INFO): ThrusterServo.component_avgCurrent 95.419995 mA
2014-06-04T22:20:57.326Z,1401920457.326 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:21:00.791Z,1401920460.791 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:21:01.642Z,1401920461.642 [CommandLine](IMPORTANT): got command get platform_mass_position
2014-06-04T22:21:01.642Z,1401920461.642 [CommandLine](IMPORTANT): platform_mass_position -0.014154 m
2014-06-04T22:21:03.224Z,1401920463.224 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119998 mA
2014-06-04T22:21:04.069Z,1401920464.069 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter
2014-06-04T22:21:04.070Z,1401920464.070 [CommandLine](IMPORTANT): platform_mass_position -1.415353 cm
2014-06-04T22:21:05.719Z,1401920465.719 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:21:08.564Z,1401920468.564 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:21:09.986Z,1401920469.986 [CommandLine](IMPORTANT): got command get platform_pitch_angle
2014-06-04T22:21:09.987Z,1401920469.987 [CommandLine](IMPORTANT): platform_pitch_angle 0.573125 arcdeg
2014-06-04T22:21:11.917Z,1401920471.917 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:21:14.910Z,1401920474.910 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:21:17.987Z,1401920477.987 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:21:20.787Z,1401920480.787 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:21:26.140Z,1401920486.140 [Reporter](INFO): ThrusterServo.component_avgCurrent 95.419995 mA
2014-06-04T22:21:28.804Z,1401920488.804 [Reporter](INFO): ThrusterServo.component_avgCurrent 135.790005 mA
2014-06-04T22:21:31.604Z,1401920491.604 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:21:32.333Z,1401920492.333 [CommandLine](IMPORTANT): got command maintain clear
2014-06-04T22:21:32.516Z,1401920492.516 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,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-06-04T22:21:34.348Z,1401920494.348 [Reporter](INFO): ThrusterServo.component_avgCurrent 102.760002 mA
2014-06-04T22:21:35.230Z,1401920495.230 [CommandLine](IMPORTANT): got command report mod depth
2014-06-04T22:21:35.812Z,1401920495.812 [Reporter](INFO): depth 0.814608 m
2014-06-04T22:21:36.361Z,1401920496.361 [Reporter](INFO): depth 0.817641 m
2014-06-04T22:21:36.869Z,1401920496.869 [Reporter](INFO): depth 0.824140 m
2014-06-04T22:21:37.326Z,1401920497.326 [Reporter](INFO): depth 0.825873 m
2014-06-04T22:21:37.811Z,1401920497.811 [Reporter](INFO): depth 0.805509 m
2014-06-04T22:21:38.275Z,1401920498.275 [Reporter](INFO): depth 0.812875 m
2014-06-04T22:21:38.752Z,1401920498.752 [Reporter](INFO): depth 0.833672 m
2014-06-04T22:21:39.191Z,1401920499.191 [Reporter](INFO): depth 0.820674 m
2014-06-04T22:21:39.688Z,1401920499.688 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:21:41.184Z,1401920501.184 [Reporter](INFO): depth 0.828906 m
2014-06-04T22:21:41.695Z,1401920501.695 [Reporter](INFO): depth 0.820674 m
2014-06-04T22:21:42.181Z,1401920502.181 [Reporter](INFO): depth 0.828473 m
2014-06-04T22:21:42.645Z,1401920502.645 [Reporter](INFO): ThrusterServo.component_avgCurrent 102.760002 mA
2014-06-04T22:21:42.646Z,1401920502.646 [Reporter](INFO): depth 0.815041 m
2014-06-04T22:21:43.167Z,1401920503.167 [Reporter](INFO): depth 0.832806 m
2014-06-04T22:21:43.616Z,1401920503.616 [Reporter](INFO): depth 0.841904 m
2014-06-04T22:21:44.088Z,1401920504.088 [Reporter](INFO): depth 0.831939 m
2014-06-04T22:21:44.545Z,1401920504.545 [Reporter](INFO): depth 0.837138 m
2014-06-04T22:21:45.044Z,1401920505.044 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:21:45.046Z,1401920505.046 [Reporter](INFO): depth 0.824573 m
2014-06-04T22:21:45.933Z,1401920505.933 [Startup:StartupSatComms:B](INFO): Timed out from 2014-06-04T22:20:45.7Z
2014-06-04T22:21:45.933Z,1401920505.933 [Startup:StartupSatComms:B] Stopped
2014-06-04T22:21:45.934Z,1401920505.934 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-06-04T22:21:45.934Z,1401920505.934 [Startup:StartupSatComms] Stopped
2014-06-04T22:21:45.934Z,1401920505.934 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-06-04T22:21:45.935Z,1401920505.935 [Startup](INFO): Completed Startup
2014-06-04T22:21:45.935Z,1401920505.935 [Startup] Stopped
2014-06-04T22:21:45.935Z,1401920505.935 [Startup](INFO): Aggregate::uninitialize Startup
2014-06-04T22:21:45.935Z,1401920505.935 [Startup:A.GoToSurface] Stopped
2014-06-04T22:21:45.935Z,1401920505.935 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-04T22:21:46.008Z,1401920506.008 [Reporter](INFO): depth 0.827173 m
2014-06-04T22:21:46.490Z,1401920506.490 [MissionManager](IMPORTANT): Started mission Default
2014-06-04T22:21:46.490Z,1401920506.490 [Default] Running Loop=1
2014-06-04T22:21:46.490Z,1401920506.490 [Default](INFO): Aggregate::initialize Default
2014-06-04T22:21:46.490Z,1401920506.490 [Default:Iridium] Running Loop=1
2014-06-04T22:21:46.491Z,1401920506.491 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-06-04T22:21:46.491Z,1401920506.491 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-06-04T22:21:46.491Z,1401920506.491 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-06-04T22:21:46.491Z,1401920506.491 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-06-04T22:21:46.491Z,1401920506.491 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-04T22:21:46.491Z,1401920506.491 [Default:Iridium:B.GoToSurface](ERROR): No speed setting specified. Using default value of 0.500000
2014-06-04T22:21:46.492Z,1401920506.492 [Default:CallIridium] Running Loop=1
2014-06-04T22:21:46.492Z,1401920506.492 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-06-04T22:21:46.492Z,1401920506.492 [Default:CallIridium:A] Running Loop=1
2014-06-04T22:21:46.494Z,1401920506.494 [Default:CallIridium:A] Stopped
2014-06-04T22:21:46.494Z,1401920506.494 [Default:CallIridium:B] Running Loop=1
2014-06-04T22:21:46.495Z,1401920506.495 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-06-04T22:21:46.500Z,1401920506.500 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-06-04T22:21:46.518Z,1401920506.518 [Default:GPS] Running Loop=1
2014-06-04T22:21:46.518Z,1401920506.518 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-06-04T22:21:46.518Z,1401920506.518 [Default:GPS:A.SetSpeed] Running Loop=1
2014-06-04T22:21:46.518Z,1401920506.518 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-06-04T22:21:46.518Z,1401920506.518 [Default:GPS:B.GoToSurface] Running Loop=1
2014-06-04T22:21:46.518Z,1401920506.518 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-06-04T22:21:46.523Z,1401920506.523 [Default:GPS:B.GoToSurface](ERROR): No speed setting specified. Using default value of 0.500000
2014-06-04T22:21:46.533Z,1401920506.533 [Default:GPS:A.SetSpeed] Running Loop=1
2014-06-04T22:21:46.620Z,1401920506.620 [Reporter](INFO): depth 0.823707 m
2014-06-04T22:21:47.113Z,1401920507.113 [Reporter](INFO): depth 0.819807 m
2014-06-04T22:21:47.606Z,1401920507.606 [Reporter](INFO): depth 0.834105 m
2014-06-04T22:21:48.070Z,1401920508.070 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:21:48.072Z,1401920508.072 [Reporter](INFO): depth 0.830639 m
2014-06-04T22:21:48.555Z,1401920508.555 [Reporter](INFO): depth 0.826306 m
2014-06-04T22:21:49.016Z,1401920509.016 [Reporter](INFO): depth 0.825440 m
2014-06-04T22:21:49.532Z,1401920509.532 [Reporter](INFO): depth 0.823707 m
2014-06-04T22:21:49.965Z,1401920509.965 [Reporter](INFO): depth 0.827173 m
2014-06-04T22:21:50.454Z,1401920510.454 [Reporter](INFO): depth 0.825007 m
2014-06-04T22:21:50.921Z,1401920510.921 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:21:50.923Z,1401920510.923 [Reporter](INFO): depth 0.805509 m
2014-06-04T22:21:51.404Z,1401920511.404 [Reporter](INFO): depth 0.816341 m
2014-06-04T22:21:51.893Z,1401920511.893 [Reporter](INFO): depth 0.800743 m
2014-06-04T22:21:52.361Z,1401920512.361 [Reporter](INFO): depth 0.819807 m
2014-06-04T22:21:53.341Z,1401920513.341 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:21:53.343Z,1401920513.343 [Reporter](INFO): depth 0.803776 m
2014-06-04T22:21:53.823Z,1401920513.823 [Reporter](INFO): depth 0.805076 m
2014-06-04T22:21:54.316Z,1401920514.316 [Reporter](INFO): depth 0.812441 m
2014-06-04T22:21:54.768Z,1401920514.768 [Reporter](INFO): depth 0.809842 m
2014-06-04T22:21:55.248Z,1401920515.248 [Reporter](INFO): depth 0.798577 m
2014-06-04T22:21:55.682Z,1401920515.682 [Reporter](INFO): depth 0.779512 m
2014-06-04T22:21:56.223Z,1401920516.223 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:21:56.225Z,1401920516.225 [Reporter](INFO): depth 0.794677 m
2014-06-04T22:21:56.688Z,1401920516.688 [Reporter](INFO): depth 0.784712 m
2014-06-04T22:21:57.256Z,1401920517.256 [Reporter](INFO): depth 0.796843 m
2014-06-04T22:21:57.705Z,1401920517.705 [Reporter](INFO): depth 0.776046 m
2014-06-04T22:21:58.154Z,1401920518.154 [Reporter](INFO): depth 0.784278 m
2014-06-04T22:21:58.605Z,1401920518.605 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:21:58.607Z,1401920518.607 [Reporter](INFO): depth 0.772147 m
2014-06-04T22:21:59.112Z,1401920519.112 [Reporter](INFO): depth 0.780812 m
2014-06-04T22:21:59.629Z,1401920519.629 [Reporter](INFO): depth 0.778212 m
2014-06-04T22:22:00.197Z,1401920520.197 [Reporter](INFO): depth 0.769114 m
2014-06-04T22:22:00.709Z,1401920520.709 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:22:00.711Z,1401920520.711 [Reporter](INFO): depth 0.778212 m
2014-06-04T22:22:01.283Z,1401920521.283 [Reporter](INFO): depth 0.775180 m
2014-06-04T22:22:01.740Z,1401920521.740 [Reporter](INFO): depth 0.763481 m
2014-06-04T22:22:02.307Z,1401920522.307 [Reporter](INFO): depth 0.751349 m
2014-06-04T22:22:02.806Z,1401920522.806 [Reporter](INFO): depth 0.748316 m
2014-06-04T22:22:03.298Z,1401920523.298 [Reporter](INFO): depth 0.768247 m
2014-06-04T22:22:03.749Z,1401920523.749 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:22:03.751Z,1401920523.751 [Reporter](INFO): depth 0.747450 m
2014-06-04T22:22:04.207Z,1401920524.207 [Reporter](INFO): depth 0.757415 m
2014-06-04T22:22:04.668Z,1401920524.668 [Reporter](INFO): depth 0.760448 m
2014-06-04T22:22:05.161Z,1401920525.161 [Reporter](INFO): depth 0.747883 m
2014-06-04T22:22:05.722Z,1401920525.722 [Reporter](INFO): depth 0.756982 m
2014-06-04T22:22:06.305Z,1401920526.305 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:22:06.307Z,1401920526.307 [Reporter](INFO): depth 0.751782 m
2014-06-04T22:22:06.882Z,1401920526.882 [Reporter](INFO): depth 0.742684 m
2014-06-04T22:22:07.409Z,1401920527.409 [Reporter](INFO): depth 0.749616 m
2014-06-04T22:22:07.861Z,1401920527.861 [Reporter](INFO): depth 0.728819 m
2014-06-04T22:22:08.362Z,1401920528.362 [Reporter](INFO): depth 0.748316 m
2014-06-04T22:22:08.866Z,1401920528.866 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:22:08.867Z,1401920528.867 [Reporter](INFO): depth 0.726652 m
2014-06-04T22:22:09.416Z,1401920529.416 [Reporter](INFO): depth 0.737484 m
2014-06-04T22:22:10.249Z,1401920530.249 [Reporter](INFO): depth 0.740517 m
2014-06-04T22:22:10.762Z,1401920530.762 [Reporter](INFO): depth 0.722753 m
2014-06-04T22:22:11.252Z,1401920531.252 [Reporter](INFO): depth 0.726652 m
2014-06-04T22:22:11.798Z,1401920531.798 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:22:11.800Z,1401920531.800 [Reporter](INFO): depth 0.724053 m
2014-06-04T22:22:12.541Z,1401920532.541 [Reporter](INFO): depth 0.730119 m
2014-06-04T22:22:13.113Z,1401920533.113 [Reporter](INFO): depth 0.714954 m
2014-06-04T22:22:13.690Z,1401920533.690 [Reporter](INFO): depth 0.714087 m
2014-06-04T22:22:14.139Z,1401920534.139 [Reporter](INFO): depth 0.712354 m
2014-06-04T22:22:14.574Z,1401920534.574 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:22:14.576Z,1401920534.576 [Reporter](INFO): depth 0.723186 m
2014-06-04T22:22:15.037Z,1401920535.037 [Reporter](INFO): depth 0.723619 m
2014-06-04T22:22:15.545Z,1401920535.545 [Reporter](INFO): depth 0.715387 m
2014-06-04T22:22:16.050Z,1401920536.050 [Reporter](INFO): depth 0.720586 m
2014-06-04T22:22:16.511Z,1401920536.511 [Reporter](INFO): depth 0.721020 m
2014-06-04T22:22:16.958Z,1401920536.958 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:22:16.960Z,1401920536.960 [Reporter](INFO): depth 0.708021 m
2014-06-04T22:22:17.520Z,1401920537.520 [Reporter](INFO): depth 0.702822 m
2014-06-04T22:22:18.357Z,1401920538.357 [Reporter](INFO): depth 0.713654 m
2014-06-04T22:22:18.911Z,1401920538.911 [Reporter](INFO): depth 0.709754 m
2014-06-04T22:22:19.454Z,1401920539.454 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:22:19.456Z,1401920539.456 [Reporter](INFO): depth 0.705422 m
2014-06-04T22:22:19.952Z,1401920539.952 [Reporter](INFO): depth 0.704555 m
2014-06-04T22:22:20.545Z,1401920540.545 [Reporter](INFO): depth 0.711921 m
2014-06-04T22:22:20.985Z,1401920540.985 [Reporter](INFO): depth 0.698056 m
2014-06-04T22:22:21.458Z,1401920541.458 [Reporter](INFO): depth 0.718420 m
2014-06-04T22:22:21.934Z,1401920541.934 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:22:21.936Z,1401920541.936 [Reporter](INFO): depth 0.712787 m
2014-06-04T22:22:22.492Z,1401920542.492 [Reporter](INFO): depth 0.714954 m
2014-06-04T22:22:22.969Z,1401920542.969 [Reporter](INFO): depth 0.704555 m
2014-06-04T22:22:23.466Z,1401920543.466 [Reporter](INFO): depth 0.705422 m
2014-06-04T22:22:23.926Z,1401920543.926 [Reporter](INFO): depth 0.700656 m
2014-06-04T22:22:24.379Z,1401920544.379 [Reporter](INFO): depth 0.704555 m
2014-06-04T22:22:24.834Z,1401920544.834 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:22:24.836Z,1401920544.836 [Reporter](INFO): depth 0.712787 m
2014-06-04T22:22:25.381Z,1401920545.381 [Reporter](INFO): depth 0.714521 m
2014-06-04T22:22:25.850Z,1401920545.850 [Reporter](INFO): depth 0.704988 m
2014-06-04T22:22:26.337Z,1401920546.337 [Reporter](INFO): depth 0.714521 m
2014-06-04T22:22:26.831Z,1401920546.831 [Reporter](INFO): depth 0.705855 m
2014-06-04T22:22:27.686Z,1401920547.686 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:22:27.688Z,1401920547.688 [Reporter](INFO): depth 0.705422 m
2014-06-04T22:22:28.282Z,1401920548.282 [Reporter](INFO): depth 0.703255 m
2014-06-04T22:22:28.817Z,1401920548.817 [Reporter](INFO): depth 0.682025 m
2014-06-04T22:22:29.322Z,1401920549.322 [Reporter](INFO): depth 0.697623 m
2014-06-04T22:22:29.931Z,1401920549.931 [Reporter](INFO): depth 0.693723 m
2014-06-04T22:22:30.388Z,1401920550.388 [Reporter](INFO): depth 0.701522 m
2014-06-04T22:22:30.849Z,1401920550.849 [Reporter](INFO): depth 0.694156 m
2014-06-04T22:22:31.310Z,1401920551.310 [Reporter](INFO): depth 0.691990 m
2014-06-04T22:22:31.830Z,1401920551.830 [Reporter](INFO): depth 0.685058 m
2014-06-04T22:22:32.303Z,1401920552.303 [Reporter](INFO): depth 0.682458 m
2014-06-04T22:22:32.865Z,1401920552.865 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:22:32.867Z,1401920552.867 [Reporter](INFO): depth 0.680725 m
2014-06-04T22:22:33.314Z,1401920553.314 [Reporter](INFO): depth 0.682458 m
2014-06-04T22:22:33.764Z,1401920553.764 [Reporter](INFO): depth 0.675525 m
2014-06-04T22:22:34.198Z,1401920554.198 [Reporter](INFO): depth 0.675959 m
2014-06-04T22:22:35.155Z,1401920555.155 [Reporter](INFO): depth 0.661227 m
2014-06-04T22:22:35.635Z,1401920555.635 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:22:35.637Z,1401920555.637 [Reporter](INFO): depth 0.682458 m
2014-06-04T22:22:36.157Z,1401920556.157 [Reporter](INFO): depth 0.673359 m
2014-06-04T22:22:36.638Z,1401920556.638 [Reporter](INFO): depth 0.677692 m
2014-06-04T22:22:37.138Z,1401920557.138 [Reporter](INFO): depth 0.674226 m
2014-06-04T22:22:38.064Z,1401920558.064 [Reporter](INFO): depth 0.666860 m
2014-06-04T22:22:38.612Z,1401920558.612 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:22:38.613Z,1401920558.613 [Reporter](INFO): depth 0.674659 m
2014-06-04T22:22:39.170Z,1401920559.170 [Reporter](INFO): depth 0.676825 m
2014-06-04T22:22:39.662Z,1401920559.662 [Reporter](INFO): depth 0.669893 m
2014-06-04T22:22:40.247Z,1401920560.247 [Reporter](INFO): depth 0.658194 m
2014-06-04T22:22:40.704Z,1401920560.704 [Reporter](INFO): depth 0.649095 m
2014-06-04T22:22:41.252Z,1401920561.252 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:22:41.253Z,1401920561.253 [Reporter](INFO): depth 0.665560 m
2014-06-04T22:22:41.714Z,1401920561.714 [Reporter](INFO): depth 0.650395 m
2014-06-04T22:22:42.211Z,1401920562.211 [Reporter](INFO): depth 0.643463 m
2014-06-04T22:22:42.659Z,1401920562.659 [Reporter](INFO): depth 0.652562 m
2014-06-04T22:22:43.204Z,1401920563.204 [Reporter](INFO): depth 0.650395 m
2014-06-04T22:22:43.653Z,1401920563.653 [Reporter](INFO): depth 0.639997 m
2014-06-04T22:22:44.148Z,1401920564.148 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:22:44.150Z,1401920564.150 [Reporter](INFO): depth 0.627865 m
2014-06-04T22:22:44.662Z,1401920564.662 [Reporter](INFO): depth 0.623532 m
2014-06-04T22:22:45.155Z,1401920565.155 [Reporter](INFO): depth 0.626998 m
2014-06-04T22:22:45.608Z,1401920565.608 [Reporter](INFO): depth 0.614866 m
2014-06-04T22:22:46.088Z,1401920566.088 [Reporter](INFO): depth 0.617899 m
2014-06-04T22:22:46.521Z,1401920566.521 [Reporter](INFO): depth 0.620932 m
2014-06-04T22:22:47.012Z,1401920567.012 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:22:47.014Z,1401920567.014 [Reporter](INFO): depth 0.600135 m
2014-06-04T22:22:47.527Z,1401920567.527 [Reporter](INFO): depth 0.607501 m
2014-06-04T22:22:48.104Z,1401920568.104 [Reporter](INFO): depth 0.578471 m
2014-06-04T22:22:48.535Z,1401920568.535 [Reporter](INFO): depth 0.597102 m
2014-06-04T22:22:48.981Z,1401920568.981 [Reporter](INFO): depth 0.588870 m
2014-06-04T22:22:49.432Z,1401920569.432 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:22:49.434Z,1401920569.434 [Reporter](INFO): depth 0.577171 m
2014-06-04T22:22:49.902Z,1401920569.902 [Reporter](INFO): depth 0.571972 m
2014-06-04T22:22:50.371Z,1401920570.371 [Reporter](INFO): depth 0.596235 m
2014-06-04T22:22:50.956Z,1401920570.956 [Reporter](INFO): depth 0.578038 m
2014-06-04T22:22:51.793Z,1401920571.793 [Reporter](INFO): depth 0.578471 m
2014-06-04T22:22:52.290Z,1401920572.290 [Reporter](INFO): depth 0.559840 m
2014-06-04T22:22:52.827Z,1401920572.827 [Reporter](INFO): depth 0.572838 m
2014-06-04T22:22:53.460Z,1401920573.460 [Reporter](INFO): depth 0.554208 m
2014-06-04T22:22:54.594Z,1401920574.594 [Reporter](INFO): depth 0.539476 m
2014-06-04T22:22:55.075Z,1401920575.075 [Reporter](INFO): depth 0.530810 m
2014-06-04T22:22:55.554Z,1401920575.554 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:22:55.556Z,1401920575.556 [Reporter](INFO): depth 0.522145 m
2014-06-04T22:22:56.019Z,1401920576.019 [Reporter](INFO): depth 0.522578 m
2014-06-04T22:22:56.530Z,1401920576.530 [Reporter](INFO): depth 0.500048 m
2014-06-04T22:22:56.981Z,1401920576.981 [Default:Iridium:Read_Iridium] Running Loop=1
2014-06-04T22:22:56.982Z,1401920576.982 [Default:GPS:Read_GPS] Running Loop=1
2014-06-04T22:22:57.066Z,1401920577.066 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:22:57.067Z,1401920577.067 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:22:57.068Z,1401920577.068 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:22:57.069Z,1401920577.069 [Reporter](INFO): depth 0.486183 m
2014-06-04T22:22:57.150Z,1401920577.150 [Radio_Freewave](INFO): Powering up
2014-06-04T22:22:57.533Z,1401920577.533 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-06-04T22:22:57.565Z,1401920577.565 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-06-04T22:22:57.738Z,1401920577.738 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA
2014-06-04T22:22:57.739Z,1401920577.739 [Reporter](INFO): VerticalControl.verticalMode 1 enum
2014-06-04T22:22:57.740Z,1401920577.740 [Reporter](INFO): SpeedControl.propOmegaAction 15.708007 rad/s
2014-06-04T22:22:57.740Z,1401920577.740 [Reporter](INFO): SpeedControl.speedCmd 0.500000 m/s
2014-06-04T22:22:57.758Z,1401920577.758 [Reporter](INFO): depth 0.500048 m
2014-06-04T22:22:57.957Z,1401920577.957 [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-06-04T22:22:58.553Z,1401920578.553 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:22:58.554Z,1401920578.554 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:22:58.555Z,1401920578.555 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:22:58.555Z,1401920578.555 [Reporter](INFO): depth 0.483583 m
2014-06-04T22:22:59.482Z,1401920579.482 [Reporter](INFO): depth 0.472751 m
2014-06-04T22:23:00.516Z,1401920580.516 [Reporter](INFO): depth 0.450654 m
2014-06-04T22:23:01.117Z,1401920581.117 [Reporter](INFO): depth 0.438089 m
2014-06-04T22:23:01.561Z,1401920581.561 [Reporter](INFO): depth 0.427690 m
2014-06-04T22:23:02.066Z,1401920582.066 [Reporter](INFO): depth 0.415991 m
2014-06-04T22:23:02.543Z,1401920582.543 [Reporter](INFO): depth 0.428990 m
2014-06-04T22:23:03.055Z,1401920583.055 [Reporter](INFO): depth 0.396927 m
2014-06-04T22:23:03.609Z,1401920583.609 [Reporter](INFO): depth 0.405593 m
2014-06-04T22:23:04.061Z,1401920584.061 [Reporter](INFO): depth 0.390428 m
2014-06-04T22:23:04.556Z,1401920584.556 [Reporter](INFO): depth 0.377863 m
2014-06-04T22:23:05.554Z,1401920585.554 [Reporter](INFO): depth 0.364865 m
2014-06-04T22:23:06.055Z,1401920586.055 [Reporter](INFO): depth 0.341467 m
2014-06-04T22:23:06.517Z,1401920586.517 [Reporter](INFO): depth 0.328902 m
2014-06-04T22:23:07.015Z,1401920587.015 [Reporter](INFO): depth 0.328036 m
2014-06-04T22:23:07.519Z,1401920587.519 [Reporter](INFO): depth 0.301173 m
2014-06-04T22:23:08.015Z,1401920588.015 [Reporter](INFO): depth 0.313738 m
2014-06-04T22:23:08.556Z,1401920588.556 [Reporter](INFO): depth 0.285574 m
2014-06-04T22:23:09.021Z,1401920589.021 [Reporter](INFO): depth 0.281675 m
2014-06-04T22:23:09.161Z,1401920589.161 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter
2014-06-04T22:23:09.162Z,1401920589.162 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2014-06-04T22:23:09.478Z,1401920589.478 [Reporter](INFO): depth 0.260878 m
2014-06-04T22:23:09.486Z,1401920589.486 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-06-04T22:23:10.006Z,1401920590.006 [Reporter](INFO): depth 0.259144 m
2014-06-04T22:23:11.057Z,1401920591.057 [Reporter](INFO): depth 0.240947 m
2014-06-04T22:23:11.570Z,1401920591.570 [Reporter](INFO): depth 0.219716 m
2014-06-04T22:23:12.097Z,1401920592.097 [Reporter](INFO): depth 0.205418 m
2014-06-04T22:23:12.570Z,1401920592.570 [Reporter](INFO): depth 0.188520 m
2014-06-04T22:23:13.084Z,1401920593.084 [Reporter](INFO): depth 0.198052 m
2014-06-04T22:23:13.599Z,1401920593.599 [Reporter](INFO): depth 0.207151 m
2014-06-04T22:23:14.045Z,1401920594.045 [Reporter](INFO): depth 0.204118 m
2014-06-04T22:23:14.458Z,1401920594.458 [Reporter](INFO): depth 0.187653 m
2014-06-04T22:23:14.951Z,1401920594.951 [Reporter](INFO): depth 0.200652 m
2014-06-04T22:23:15.872Z,1401920595.872 [Reporter](INFO): depth 0.199352 m
2014-06-04T22:23:16.361Z,1401920596.361 [Reporter](INFO): depth 0.196319 m
2014-06-04T22:23:16.814Z,1401920596.814 [Reporter](INFO): depth 0.187653 m
2014-06-04T22:23:17.290Z,1401920597.290 [Reporter](INFO): depth 0.213650 m
2014-06-04T22:23:17.719Z,1401920597.719 [Reporter](INFO): depth 0.203251 m
2014-06-04T22:23:18.188Z,1401920598.188 [Reporter](INFO): depth 0.217550 m
2014-06-04T22:23:18.669Z,1401920598.669 [Reporter](INFO): depth 0.195886 m
2014-06-04T22:23:19.117Z,1401920599.117 [Reporter](INFO): depth 0.207151 m
2014-06-04T22:23:19.566Z,1401920599.566 [Reporter](INFO): depth 0.211917 m
2014-06-04T22:23:20.035Z,1401920600.035 [Reporter](INFO): depth 0.217116 m
2014-06-04T22:23:20.462Z,1401920600.462 [Reporter](INFO): depth 0.224482 m
2014-06-04T22:23:20.880Z,1401920600.880 [Reporter](INFO): depth 0.230981 m
2014-06-04T22:23:21.292Z,1401920601.292 [Reporter](INFO): depth 0.213217 m
2014-06-04T22:23:21.729Z,1401920601.729 [Reporter](INFO): depth 0.228815 m
2014-06-04T22:23:22.145Z,1401920602.145 [Reporter](INFO): depth 0.221449 m
2014-06-04T22:23:22.599Z,1401920602.599 [Reporter](INFO): depth 0.223615 m
2014-06-04T22:23:23.560Z,1401920603.560 [Reporter](INFO): depth 0.225349 m
2014-06-04T22:23:24.149Z,1401920604.149 [Reporter](INFO): depth 0.216683 m
2014-06-04T22:23:24.581Z,1401920604.581 [Reporter](INFO): depth 0.242680 m
2014-06-04T22:23:25.062Z,1401920605.062 [Reporter](INFO): depth 0.238780 m
2014-06-04T22:23:25.499Z,1401920605.499 [Reporter](INFO): depth 0.210184 m
2014-06-04T22:23:26.006Z,1401920606.006 [Reporter](INFO): depth 0.225349 m
2014-06-04T22:23:26.074Z,1401920606.074 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2014-06-04T22:23:26.074Z,1401920606.074 [CommandLine](IMPORTANT): platform_buoyancy_position 374.149764 cc
2014-06-04T22:23:26.470Z,1401920606.470 [Reporter](INFO): depth 0.243546 m
2014-06-04T22:23:27.318Z,1401920607.318 [Reporter](INFO): depth 0.247446 m
2014-06-04T22:23:27.791Z,1401920607.791 [Reporter](INFO): depth 0.260444 m
2014-06-04T22:23:28.202Z,1401920608.202 [Reporter](INFO): depth 0.243980 m
2014-06-04T22:23:28.728Z,1401920608.728 [Reporter](INFO): depth 0.266077 m
2014-06-04T22:23:29.139Z,1401920609.139 [Reporter](INFO): depth 0.245279 m
2014-06-04T22:23:29.634Z,1401920609.634 [Reporter](INFO): depth 0.247013 m
2014-06-04T22:23:30.034Z,1401920610.034 [Reporter](INFO): depth 0.244846 m
2014-06-04T22:23:30.464Z,1401920610.464 [Reporter](INFO): depth 0.259578 m
2014-06-04T22:23:30.895Z,1401920610.895 [Reporter](INFO): depth 0.263477 m
2014-06-04T22:23:31.847Z,1401920611.847 [Reporter](INFO): depth 0.252645 m
2014-06-04T22:23:32.305Z,1401920612.305 [Reporter](INFO): depth 0.283408 m
2014-06-04T22:23:32.738Z,1401920612.738 [Reporter](INFO): depth 0.303339 m
2014-06-04T22:23:33.279Z,1401920613.279 [Reporter](INFO): depth 0.278209 m
2014-06-04T22:23:33.820Z,1401920613.820 [Reporter](INFO): depth 0.293373 m
2014-06-04T22:23:33.854Z,1401920613.854 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2014-06-04T22:23:33.854Z,1401920613.854 [CommandLine](IMPORTANT): platform_buoyancy_position 348.364149 cc
2014-06-04T22:23:34.253Z,1401920614.253 [Reporter](INFO): depth 0.316337 m
2014-06-04T22:23:34.697Z,1401920614.697 [Reporter](INFO): depth 0.310271 m
2014-06-04T22:23:35.113Z,1401920615.113 [Reporter](INFO): depth 0.302906 m
2014-06-04T22:23:35.587Z,1401920615.587 [Reporter](INFO): depth 0.318070 m
2014-06-04T22:23:36.059Z,1401920616.059 [Reporter](INFO): depth 0.325003 m
2014-06-04T22:23:36.468Z,1401920616.468 [Reporter](INFO): depth 0.315037 m
2014-06-04T22:23:36.881Z,1401920616.881 [Reporter](INFO): depth 0.321970 m
2014-06-04T22:23:37.326Z,1401920617.326 [Reporter](INFO): depth 0.318937 m
2014-06-04T22:23:37.735Z,1401920617.735 [Reporter](INFO): depth 0.331502 m
2014-06-04T22:23:38.191Z,1401920618.191 [Reporter](INFO): depth 0.334102 m
2014-06-04T22:23:38.622Z,1401920618.622 [Reporter](INFO): depth 0.308538 m
2014-06-04T22:23:39.120Z,1401920619.120 [Reporter](INFO): depth 0.323270 m
2014-06-04T22:23:39.565Z,1401920619.565 [Reporter](INFO): depth 0.337568 m
2014-06-04T22:23:40.513Z,1401920620.513 [Reporter](INFO): depth 0.341034 m
2014-06-04T22:23:40.960Z,1401920620.960 [Reporter](INFO): depth 0.345800 m
2014-06-04T22:23:41.404Z,1401920621.404 [Reporter](INFO): depth 0.352299 m
2014-06-04T22:23:41.844Z,1401920621.844 [Reporter](INFO): depth 0.332802 m
2014-06-04T22:23:42.321Z,1401920622.321 [Reporter](INFO): depth 0.357932 m
2014-06-04T22:23:42.810Z,1401920622.810 [Reporter](INFO): depth 0.355332 m
2014-06-04T22:23:43.663Z,1401920623.663 [Reporter](INFO): depth 0.360098 m
2014-06-04T22:23:44.164Z,1401920624.164 [Reporter](INFO): depth 0.356632 m
2014-06-04T22:23:44.577Z,1401920624.577 [Reporter](INFO): depth 0.338435 m
2014-06-04T22:23:45.046Z,1401920625.046 [Reporter](INFO): depth 0.362265 m
2014-06-04T22:23:45.971Z,1401920625.971 [Reporter](INFO): depth 0.345800 m
2014-06-04T22:23:46.520Z,1401920626.520 [Reporter](INFO): depth 0.357066 m
2014-06-04T22:23:46.932Z,1401920626.932 [Reporter](INFO): depth 0.357499 m
2014-06-04T22:23:47.409Z,1401920627.409 [Reporter](INFO): depth 0.355332 m
2014-06-04T22:23:47.830Z,1401920627.830 [Reporter](INFO): depth 0.377430 m
2014-06-04T22:23:48.322Z,1401920628.322 [Reporter](INFO): depth 0.373963 m
2014-06-04T22:23:49.280Z,1401920629.280 [Reporter](INFO): depth 0.360098 m
2014-06-04T22:23:49.722Z,1401920629.722 [Reporter](INFO): depth 0.376563 m
2014-06-04T22:23:50.673Z,1401920630.673 [Reporter](INFO): depth 0.351000 m
2014-06-04T22:23:51.126Z,1401920631.126 [Reporter](INFO): depth 0.370064 m
2014-06-04T22:23:51.547Z,1401920631.547 [Reporter](INFO): depth 0.375263 m
2014-06-04T22:23:51.992Z,1401920631.992 [Reporter](INFO): depth 0.379596 m
2014-06-04T22:23:52.510Z,1401920632.510 [Reporter](INFO): depth 0.383062 m
2014-06-04T22:23:52.946Z,1401920632.946 [Reporter](INFO): depth 0.399960 m
2014-06-04T22:23:53.394Z,1401920633.394 [Reporter](INFO): depth 0.389995 m
2014-06-04T22:23:53.807Z,1401920633.807 [Reporter](INFO): depth 0.393894 m
2014-06-04T22:23:54.301Z,1401920634.301 [Reporter](INFO): depth 0.411225 m
2014-06-04T22:23:54.744Z,1401920634.744 [Reporter](INFO): depth 0.388695 m
2014-06-04T22:23:55.694Z,1401920635.694 [Reporter](INFO): depth 0.410359 m
2014-06-04T22:23:56.201Z,1401920636.201 [Reporter](INFO): depth 0.406459 m
2014-06-04T22:23:56.638Z,1401920636.638 [Reporter](INFO): depth 0.418158 m
2014-06-04T22:23:57.061Z,1401920637.061 [Reporter](INFO): depth 0.416425 m
2014-06-04T22:23:57.537Z,1401920637.537 [Reporter](INFO): depth 0.426823 m
2014-06-04T22:23:57.957Z,1401920637.957 [Reporter](INFO): depth 0.419024 m
2014-06-04T22:23:58.402Z,1401920638.402 [Reporter](INFO): depth 0.417725 m
2014-06-04T22:23:58.866Z,1401920638.866 [Reporter](INFO): depth 0.431590 m
2014-06-04T22:23:59.376Z,1401920639.376 [Reporter](INFO): depth 0.435056 m
2014-06-04T22:23:59.816Z,1401920639.816 [Reporter](INFO): depth 0.447621 m
2014-06-04T22:24:00.769Z,1401920640.769 [Reporter](INFO): depth 0.427257 m
2014-06-04T22:24:01.215Z,1401920641.215 [Reporter](INFO): depth 0.433323 m
2014-06-04T22:24:01.660Z,1401920641.660 [Reporter](INFO): depth 0.424224 m
2014-06-04T22:24:02.118Z,1401920642.118 [Reporter](INFO): depth 0.435056 m
2014-06-04T22:24:02.625Z,1401920642.625 [Reporter](INFO): depth 0.457586 m
2014-06-04T22:24:03.065Z,1401920643.065 [Reporter](INFO): depth 0.441122 m
2014-06-04T22:24:03.502Z,1401920643.502 [Reporter](INFO): depth 0.438955 m
2014-06-04T22:24:03.934Z,1401920643.934 [Reporter](INFO): depth 0.436789 m
2014-06-04T22:24:04.420Z,1401920644.420 [Reporter](INFO): depth 0.458453 m
2014-06-04T22:24:04.856Z,1401920644.856 [Reporter](INFO): depth 0.447188 m
2014-06-04T22:24:05.806Z,1401920645.806 [Reporter](INFO): depth 0.465819 m
2014-06-04T22:24:06.311Z,1401920646.311 [Reporter](INFO): depth 0.468852 m
2014-06-04T22:24:06.763Z,1401920646.763 [Reporter](INFO): depth 0.479684 m
2014-06-04T22:24:07.200Z,1401920647.200 [Reporter](INFO): depth 0.480117 m
2014-06-04T22:24:07.656Z,1401920647.656 [Reporter](INFO): depth 0.483150 m
2014-06-04T22:24:08.110Z,1401920648.110 [Reporter](INFO): depth 0.475784 m
2014-06-04T22:24:08.551Z,1401920648.551 [CommandLine](IMPORTANT): got command report 
2014-06-04T22:24:08.552Z,1401920648.552 [Reporter](INFO): ThrusterServo.component_avgCurrent reporting changes
2014-06-04T22:24:08.552Z,1401920648.552 [Reporter](INFO): VerticalControl.verticalMode reporting changes
2014-06-04T22:24:08.552Z,1401920648.552 [Reporter](INFO): SpeedControl.propOmegaAction reporting changes
2014-06-04T22:24:08.552Z,1401920648.552 [Reporter](INFO): SpeedControl.speedCmd reporting changes
2014-06-04T22:24:08.553Z,1401920648.553 [Reporter](INFO): depth reporting changes
2014-06-04T22:24:08.573Z,1401920648.573 [Reporter](INFO): depth 0.491815 m
2014-06-04T22:24:08.998Z,1401920648.998 [Reporter](INFO): depth 0.484450 m
2014-06-04T22:24:09.540Z,1401920649.540 [Reporter](INFO): depth 0.490515 m
2014-06-04T22:24:10.165Z,1401920650.165 [Radio_Freewave](INFO): Powering down
2014-06-04T22:24:10.474Z,1401920650.474 [Reporter](INFO): VerticalControl.verticalMode 1 enum
2014-06-04T22:24:10.475Z,1401920650.475 [Reporter](INFO): SpeedControl.propOmegaAction 15.708007 rad/s
2014-06-04T22:24:10.476Z,1401920650.476 [Reporter](INFO): SpeedControl.speedCmd 0.500000 m/s
2014-06-04T22:24:10.477Z,1401920650.477 [Reporter](INFO): depth 0.513046 m
2014-06-04T22:24:10.974Z,1401920650.974 [Reporter](INFO): depth 0.518245 m
2014-06-04T22:24:11.416Z,1401920651.416 [Reporter](INFO): depth 0.531677 m
2014-06-04T22:24:12.002Z,1401920652.002 [Reporter](INFO): depth 0.535577 m
2014-06-04T22:24:12.498Z,1401920652.498 [Reporter](INFO): depth 0.553341 m
2014-06-04T22:24:13.091Z,1401920653.091 [Reporter](INFO): depth 0.549441 m
2014-06-04T22:24:13.948Z,1401920653.948 [Reporter](INFO): depth 0.546408 m
2014-06-04T22:24:14.521Z,1401920654.521 [Reporter](INFO): ThrusterServo.component_avgCurrent 95.419995 mA
2014-06-04T22:24:14.522Z,1401920654.522 [Reporter](INFO): depth 0.564173 m
2014-06-04T22:24:15.050Z,1401920655.050 [Reporter](INFO): depth 0.588436 m
2014-06-04T22:24:16.200Z,1401920656.200 [Reporter](INFO): depth 0.595802 m
2014-06-04T22:24:16.745Z,1401920656.745 [Reporter](INFO): depth 0.601002 m
2014-06-04T22:24:17.326Z,1401920657.326 [Reporter](INFO): depth 0.619633 m
2014-06-04T22:24:17.935Z,1401920657.935 [Reporter](INFO): depth 0.625698 m
2014-06-04T22:24:18.641Z,1401920658.641 [Reporter](INFO): depth 0.633497 m
2014-06-04T22:24:19.157Z,1401920659.157 [Reporter](INFO): depth 0.633064 m
2014-06-04T22:24:19.782Z,1401920659.782 [Reporter](INFO): depth 0.646496 m
2014-06-04T22:24:20.272Z,1401920660.272 [Reporter](INFO): depth 0.641730 m
2014-06-04T22:24:20.879Z,1401920660.879 [Reporter](INFO): depth 0.661661 m
2014-06-04T22:24:21.961Z,1401920661.961 [Reporter](INFO): depth 0.659927 m
2014-06-04T22:24:22.758Z,1401920662.758 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:24:22.760Z,1401920662.760 [Reporter](INFO): depth 0.680292 m
2014-06-04T22:24:23.215Z,1401920663.215 [Reporter](INFO): depth 0.688957 m
2014-06-04T22:24:23.720Z,1401920663.720 [Reporter](INFO): depth 0.710188 m
2014-06-04T22:24:24.180Z,1401920664.180 [Reporter](INFO): depth 0.722753 m
2014-06-04T22:24:24.820Z,1401920664.820 [Reporter](INFO): ThrusterServo.component_avgCurrent 88.080004 mA
2014-06-04T22:24:24.822Z,1401920664.822 [Reporter](INFO): depth 0.721453 m
2014-06-04T22:24:25.667Z,1401920665.667 [Reporter](INFO): depth 0.746583 m
2014-06-04T22:24:26.179Z,1401920666.179 [Reporter](INFO): depth 0.763481 m
2014-06-04T22:24:26.672Z,1401920666.672 [Reporter](INFO): depth 0.774746 m
2014-06-04T22:24:27.206Z,1401920667.206 [Reporter](INFO): depth 0.778212 m
2014-06-04T22:24:28.303Z,1401920668.303 [Reporter](INFO): depth 0.776479 m
2014-06-04T22:24:28.968Z,1401920668.968 [Reporter](INFO): depth 0.803343 m
2014-06-04T22:24:29.593Z,1401920669.593 [Reporter](INFO): depth 0.796410 m
2014-06-04T22:24:30.229Z,1401920670.229 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:24:30.231Z,1401920670.231 [Reporter](INFO): depth 0.782112 m
2014-06-04T22:24:30.759Z,1401920670.759 [Reporter](INFO): depth 0.808975 m
2014-06-04T22:24:31.340Z,1401920671.340 [Reporter](INFO): depth 0.804209 m
2014-06-04T22:24:31.824Z,1401920671.824 [Reporter](INFO): depth 0.808975 m
2014-06-04T22:24:32.477Z,1401920672.477 [Reporter](INFO): ThrusterServo.component_avgCurrent 91.750003 mA
2014-06-04T22:24:32.479Z,1401920672.479 [Reporter](INFO): depth 0.801176 m
2014-06-04T22:24:33.002Z,1401920673.002 [Reporter](INFO): depth 0.818507 m
2014-06-04T22:24:34.104Z,1401920674.104 [Reporter](INFO): depth 0.826306 m
2014-06-04T22:24:34.637Z,1401920674.637 [Reporter](INFO): depth 0.832806 m
2014-06-04T22:24:35.217Z,1401920675.217 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119998 mA
2014-06-04T22:24:35.218Z,1401920675.218 [Reporter](INFO): depth 0.836272 m
2014-06-04T22:24:36.067Z,1401920676.067 [Reporter](INFO): depth 0.841904 m
2014-06-04T22:24:36.608Z,1401920676.608 [Reporter](INFO): depth 0.832372 m
2014-06-04T22:24:37.125Z,1401920677.125 [Reporter](INFO): depth 0.839738 m
2014-06-04T22:24:37.764Z,1401920677.764 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:24:37.766Z,1401920677.766 [Reporter](INFO): depth 0.860969 m
2014-06-04T22:24:38.326Z,1401920678.326 [Reporter](INFO): depth 0.858802 m
2014-06-04T22:24:38.979Z,1401920678.979 [Reporter](INFO): depth 0.848404 m
2014-06-04T22:24:39.982Z,1401920679.982 [Reporter](INFO): depth 0.867901 m
2014-06-04T22:24:40.697Z,1401920680.697 [Reporter](INFO): ThrusterServo.component_avgCurrent 102.760002 mA
2014-06-04T22:24:40.699Z,1401920680.699 [Reporter](INFO): depth 0.863568 m
2014-06-04T22:24:41.199Z,1401920681.199 [Reporter](INFO): depth 0.875700 m
2014-06-04T22:24:41.684Z,1401920681.684 [Reporter](INFO): depth 0.886532 m
2014-06-04T22:24:42.164Z,1401920682.164 [Reporter](INFO): depth 0.876133 m
2014-06-04T22:24:42.653Z,1401920682.653 [Reporter](INFO): depth 0.865301 m
2014-06-04T22:24:42.941Z,1401920682.941 [CommandLine](IMPORTANT): got command maintain clear
2014-06-04T22:24:43.157Z,1401920683.157 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:24:43.158Z,1401920683.158 [Reporter](INFO): depth 0.881333 m
2014-06-04T22:24:43.166Z,1401920683.166 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,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-06-04T22:24:44.091Z,1401920684.091 [Reporter](INFO): depth 0.893464 m
2014-06-04T22:24:45.182Z,1401920685.182 [Reporter](INFO): depth 0.894331 m
2014-06-04T22:24:45.794Z,1401920685.794 [Reporter](INFO): depth 0.873534 m
2014-06-04T22:24:46.362Z,1401920686.362 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:24:46.363Z,1401920686.363 [Reporter](INFO): depth 0.872667 m
2014-06-04T22:24:46.936Z,1401920686.936 [Reporter](INFO): depth 0.876133 m
2014-06-04T22:24:47.493Z,1401920687.493 [Reporter](INFO): depth 0.868334 m
2014-06-04T22:24:48.098Z,1401920688.098 [Reporter](INFO): depth 0.880899 m
2014-06-04T22:24:48.546Z,1401920688.546 [Reporter](INFO): depth 0.885232 m
2014-06-04T22:24:49.054Z,1401920689.054 [Reporter](INFO): ThrusterServo.component_avgCurrent 91.750003 mA
2014-06-04T22:24:49.056Z,1401920689.056 [Reporter](INFO): depth 0.862268 m
2014-06-04T22:24:49.892Z,1401920689.892 [Reporter](INFO): depth 0.858802 m
2014-06-04T22:24:50.469Z,1401920690.469 [Reporter](INFO): depth 0.869201 m
2014-06-04T22:24:51.511Z,1401920691.511 [Reporter](INFO): depth 0.867034 m
2014-06-04T22:24:52.142Z,1401920692.142 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:24:52.144Z,1401920692.144 [Reporter](INFO): depth 0.861835 m
2014-06-04T22:24:52.604Z,1401920692.604 [Reporter](INFO): depth 0.849703 m
2014-06-04T22:24:53.093Z,1401920693.093 [Reporter](INFO): depth 0.844937 m
2014-06-04T22:24:53.575Z,1401920693.575 [Reporter](INFO): depth 0.853170 m
2014-06-04T22:24:54.087Z,1401920694.087 [Reporter](INFO): depth 0.847104 m
2014-06-04T22:24:54.523Z,1401920694.523 [Reporter](INFO): depth 0.848837 m
2014-06-04T22:24:54.995Z,1401920694.995 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449991 mA
2014-06-04T22:24:54.997Z,1401920694.997 [Reporter](INFO): depth 0.851870 m
2014-06-04T22:24:55.493Z,1401920695.493 [Reporter](INFO): depth 0.845371 m
2014-06-04T22:24:56.031Z,1401920696.031 [Reporter](INFO): depth 0.835405 m
2014-06-04T22:24:57.360Z,1401920697.360 [Reporter](INFO): depth 0.828039 m
2014-06-04T22:24:57.944Z,1401920697.944 [Reporter](INFO): ThrusterServo.component_avgCurrent 99.090002 mA
2014-06-04T22:24:57.945Z,1401920697.945 [Reporter](INFO): depth 0.806809 m
2014-06-04T22:24:58.434Z,1401920698.434 [Reporter](INFO): depth 0.817641 m
2014-06-04T22:24:58.970Z,1401920698.970 [Reporter](INFO): depth 0.823707 m
2014-06-04T22:24:59.615Z,1401920699.615 [Reporter](INFO): depth 0.813308 m
2014-06-04T22:25:00.187Z,1401920700.187 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:25:00.189Z,1401920700.189 [Reporter](INFO): depth 0.802476 m
2014-06-04T22:25:00.866Z,1401920700.866 [Reporter](INFO): depth 0.802909 m
2014-06-04T22:25:01.899Z,1401920701.899 [Reporter](INFO): depth 0.795110 m
2014-06-04T22:25:02.953Z,1401920702.953 [Reporter](INFO): depth 0.798143 m
2014-06-04T22:25:03.460Z,1401920703.460 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:25:03.462Z,1401920703.462 [Reporter](INFO): depth 0.789478 m
2014-06-04T22:25:03.976Z,1401920703.976 [Reporter](INFO): depth 0.797710 m
2014-06-04T22:25:04.427Z,1401920704.427 [Reporter](INFO): depth 0.785145 m
2014-06-04T22:25:04.975Z,1401920704.975 [Reporter](INFO): depth 0.781245 m
2014-06-04T22:25:05.525Z,1401920705.525 [Reporter](INFO): depth 0.781679 m
2014-06-04T22:25:06.210Z,1401920706.210 [Reporter](INFO): depth 0.765647 m
2014-06-04T22:25:06.955Z,1401920706.955 [Reporter](INFO): depth 0.781679 m
2014-06-04T22:25:08.053Z,1401920708.053 [Reporter](INFO): depth 0.769547 m
2014-06-04T22:25:08.718Z,1401920708.718 [Reporter](INFO): depth 0.763914 m
2014-06-04T22:25:09.367Z,1401920709.367 [Reporter](INFO): depth 0.755682 m
2014-06-04T22:25:10.384Z,1401920710.384 [Reporter](INFO): depth 0.760881 m
2014-06-04T22:25:11.217Z,1401920711.217 [Reporter](INFO): depth 0.746583 m
2014-06-04T22:25:11.819Z,1401920711.819 [Reporter](INFO): depth 0.756115 m
2014-06-04T22:25:12.491Z,1401920712.491 [Reporter](INFO): depth 0.747450 m
2014-06-04T22:25:12.992Z,1401920712.992 [Reporter](INFO): depth 0.752649 m
2014-06-04T22:25:13.633Z,1401920713.633 [Reporter](INFO): depth 0.749616 m
2014-06-04T22:25:14.138Z,1401920714.138 [Reporter](INFO): depth 0.761748 m
2014-06-04T22:25:15.163Z,1401920715.163 [Reporter](INFO): depth 0.752649 m
2014-06-04T22:25:15.773Z,1401920715.773 [Reporter](INFO): depth 0.758715 m
2014-06-04T22:25:16.973Z,1401920716.973 [Reporter](INFO): depth 0.757415 m
2014-06-04T22:25:17.531Z,1401920717.531 [Reporter](INFO): depth 0.755249 m
2014-06-04T22:25:18.124Z,1401920718.124 [Reporter](INFO): depth 0.746150 m
2014-06-04T22:25:18.597Z,1401920718.597 [Reporter](INFO): depth 0.750049 m
2014-06-04T22:25:19.020Z,1401920719.020 [Reporter](INFO): depth 0.749183 m
2014-06-04T22:25:19.510Z,1401920719.510 [Reporter](INFO): depth 0.754815 m
2014-06-04T22:25:20.521Z,1401920720.521 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:25:20.523Z,1401920720.523 [Reporter](INFO): depth 0.743983 m
2014-06-04T22:25:21.097Z,1401920721.097 [Reporter](INFO): depth 0.747450 m
2014-06-04T22:25:21.549Z,1401920721.549 [Reporter](INFO): depth 0.742684 m
2014-06-04T22:25:22.042Z,1401920722.042 [Reporter](INFO): depth 0.737918 m
2014-06-04T22:25:22.515Z,1401920722.515 [Reporter](INFO): depth 0.742250 m
2014-06-04T22:25:23.043Z,1401920723.043 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:25:23.045Z,1401920723.045 [Reporter](INFO): depth 0.743550 m
2014-06-04T22:25:23.477Z,1401920723.477 [Reporter](INFO): depth 0.738784 m
2014-06-04T22:25:23.921Z,1401920723.921 [Reporter](INFO): depth 0.744417 m
2014-06-04T22:25:24.378Z,1401920724.378 [Reporter](INFO): depth 0.722753 m
2014-06-04T22:25:24.878Z,1401920724.878 [Reporter](INFO): depth 0.730985 m
2014-06-04T22:25:25.831Z,1401920725.831 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:25:25.833Z,1401920725.833 [Reporter](INFO): depth 0.725352 m
2014-06-04T22:25:26.457Z,1401920726.457 [Reporter](INFO): depth 0.727519 m
2014-06-04T22:25:26.898Z,1401920726.898 [Reporter](INFO): depth 0.718853 m
2014-06-04T22:25:27.401Z,1401920727.401 [Reporter](INFO): depth 0.706721 m
2014-06-04T22:25:27.839Z,1401920727.839 [Reporter](INFO): depth 0.721020 m
2014-06-04T22:25:28.332Z,1401920728.332 [Reporter](INFO): depth 0.723619 m
2014-06-04T22:25:28.784Z,1401920728.784 [Reporter](INFO): ThrusterServo.component_avgCurrent 102.760002 mA
2014-06-04T22:25:28.786Z,1401920728.786 [Reporter](INFO): depth 0.710188 m
2014-06-04T22:25:29.254Z,1401920729.254 [Reporter](INFO): depth 0.715387 m
2014-06-04T22:25:29.759Z,1401920729.759 [Reporter](INFO): depth 0.716687 m
2014-06-04T22:25:30.800Z,1401920730.800 [Reporter](INFO): depth 0.707588 m
2014-06-04T22:25:31.427Z,1401920731.427 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:25:31.429Z,1401920731.429 [Reporter](INFO): depth 0.697189 m
2014-06-04T22:25:32.006Z,1401920732.006 [Reporter](INFO): depth 0.690690 m
2014-06-04T22:25:32.490Z,1401920732.490 [Reporter](INFO): depth 0.701955 m
2014-06-04T22:25:33.010Z,1401920733.010 [Reporter](INFO): depth 0.690257 m
2014-06-04T22:25:33.458Z,1401920733.458 [Reporter](INFO): depth 0.685058 m
2014-06-04T22:25:33.908Z,1401920733.908 [Reporter](INFO): depth 0.680725 m
2014-06-04T22:25:34.357Z,1401920734.357 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:25:34.359Z,1401920734.359 [Reporter](INFO): depth 0.683325 m
2014-06-04T22:25:34.854Z,1401920734.854 [Reporter](INFO): depth 0.671193 m
2014-06-04T22:25:35.803Z,1401920735.803 [Reporter](INFO): depth 0.680292 m
2014-06-04T22:25:36.364Z,1401920736.364 [Reporter](INFO): depth 0.646496 m
2014-06-04T22:25:36.860Z,1401920736.860 [Reporter](INFO): depth 0.671626 m
2014-06-04T22:25:37.417Z,1401920737.417 [Reporter](INFO): ThrusterServo.component_avgCurrent 106.430002 mA
2014-06-04T22:25:37.418Z,1401920737.418 [Reporter](INFO): depth 0.661227 m
2014-06-04T22:25:37.969Z,1401920737.969 [Reporter](INFO): depth 0.659494 m
2014-06-04T22:25:38.548Z,1401920738.548 [Reporter](INFO): depth 0.661227 m
2014-06-04T22:25:39.143Z,1401920739.143 [Reporter](INFO): depth 0.643896 m
2014-06-04T22:25:39.644Z,1401920739.644 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:25:39.646Z,1401920739.646 [Reporter](INFO): depth 0.646496 m
2014-06-04T22:25:40.603Z,1401920740.603 [Reporter](INFO): depth 0.633931 m
2014-06-04T22:25:41.148Z,1401920741.148 [Reporter](INFO): depth 0.633064 m
2014-06-04T22:25:41.669Z,1401920741.669 [Reporter](INFO): depth 0.646929 m
2014-06-04T22:25:42.153Z,1401920742.153 [Reporter](INFO): depth 0.629165 m
2014-06-04T22:25:42.665Z,1401920742.665 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:25:42.667Z,1401920742.667 [Reporter](INFO): depth 0.627865 m
2014-06-04T22:25:43.211Z,1401920743.211 [Reporter](INFO): depth 0.631331 m
2014-06-04T22:25:44.040Z,1401920744.040 [Reporter](INFO): depth 0.616166 m
2014-06-04T22:25:44.541Z,1401920744.541 [Reporter](INFO): depth 0.599702 m
2014-06-04T22:25:45.058Z,1401920745.058 [Reporter](INFO): depth 0.606201 m
2014-06-04T22:25:46.108Z,1401920746.108 [Reporter](INFO): depth 0.601002 m
2014-06-04T22:25:46.775Z,1401920746.775 [Reporter](INFO): depth 0.583237 m
2014-06-04T22:25:47.298Z,1401920747.298 [Reporter](INFO): depth 0.580637 m
2014-06-04T22:25:47.833Z,1401920747.833 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:25:47.835Z,1401920747.835 [Reporter](INFO): depth 0.595369 m
2014-06-04T22:25:48.419Z,1401920748.419 [Reporter](INFO): depth 0.576305 m
2014-06-04T22:25:49.044Z,1401920749.044 [Reporter](INFO): depth 0.575438 m
2014-06-04T22:25:49.531Z,1401920749.531 [Reporter](INFO): depth 0.574572 m
2014-06-04T22:25:49.965Z,1401920749.965 [Reporter](INFO): depth 0.558107 m
2014-06-04T22:25:50.437Z,1401920750.437 [Reporter](INFO): ThrusterServo.component_avgCurrent 102.760002 mA
2014-06-04T22:25:50.439Z,1401920750.439 [Reporter](INFO): depth 0.577171 m
2014-06-04T22:25:50.903Z,1401920750.903 [Reporter](INFO): depth 0.561140 m
2014-06-04T22:25:51.924Z,1401920751.924 [Reporter](INFO): depth 0.549875 m
2014-06-04T22:25:52.890Z,1401920752.890 [Reporter](INFO): depth 0.542942 m
2014-06-04T22:25:53.406Z,1401920753.406 [Reporter](INFO): ThrusterServo.component_avgCurrent 117.440000 mA
2014-06-04T22:25:53.407Z,1401920753.407 [Reporter](INFO): depth 0.536010 m
2014-06-04T22:25:53.916Z,1401920753.916 [Reporter](INFO): depth 0.534710 m
2014-06-04T22:25:54.448Z,1401920754.448 [Reporter](INFO): depth 0.528211 m
2014-06-04T22:25:55.041Z,1401920755.041 [Reporter](INFO): depth 0.516945 m
2014-06-04T22:25:55.979Z,1401920755.979 [Reporter](INFO): depth 0.525611 m
2014-06-04T22:25:56.434Z,1401920756.434 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:25:56.435Z,1401920756.435 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:25:56.436Z,1401920756.436 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:25:56.437Z,1401920756.437 [Reporter](INFO): depth 0.498314 m
2014-06-04T22:25:56.589Z,1401920756.589 [Radio_Freewave](INFO): Powering up
2014-06-04T22:25:57.092Z,1401920757.092 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA
2014-06-04T22:25:57.093Z,1401920757.093 [Reporter](INFO): VerticalControl.verticalMode 1 enum
2014-06-04T22:25:57.094Z,1401920757.094 [Reporter](INFO): SpeedControl.propOmegaAction 15.708007 rad/s
2014-06-04T22:25:57.095Z,1401920757.095 [Reporter](INFO): SpeedControl.speedCmd 0.500000 m/s
2014-06-04T22:25:57.096Z,1401920757.096 [Reporter](INFO): depth 0.513913 m
2014-06-04T22:25:57.797Z,1401920757.797 [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-06-04T22:25:58.111Z,1401920758.111 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:25:58.112Z,1401920758.112 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:25:58.113Z,1401920758.113 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:25:58.114Z,1401920758.114 [Reporter](INFO): depth 0.497015 m
2014-06-04T22:25:59.064Z,1401920759.064 [Reporter](INFO): depth 0.490515 m
2014-06-04T22:25:59.609Z,1401920759.609 [Reporter](INFO): depth 0.465385 m
2014-06-04T22:26:00.126Z,1401920760.126 [Reporter](INFO): depth 0.474917 m
2014-06-04T22:26:00.711Z,1401920760.711 [Reporter](INFO): depth 0.458020 m
2014-06-04T22:26:01.228Z,1401920761.228 [Reporter](INFO): depth 0.474917 m
2014-06-04T22:26:01.808Z,1401920761.808 [Reporter](INFO): depth 0.445454 m
2014-06-04T22:26:02.378Z,1401920762.378 [Reporter](INFO): depth 0.436789 m
2014-06-04T22:26:02.963Z,1401920762.963 [Reporter](INFO): depth 0.439822 m
2014-06-04T22:26:03.943Z,1401920763.943 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 250.000000 cubic_centimeter
2014-06-04T22:26:03.944Z,1401920763.944 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2014-06-04T22:26:03.977Z,1401920763.977 [Reporter](INFO): depth 0.438089 m
2014-06-04T22:26:03.984Z,1401920763.984 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,HFRCMSurfaceCurrentAtVehicleLocation,HFRCMVirtualSurfaceDrifter,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonWithRespectToSeafloor,DeadReckonUsingDVLWaterTrack,DeadReckonUsingCompactModelForecast,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-06-04T22:26:04.614Z,1401920764.614 [Reporter](INFO): depth 0.417291 m
2014-06-04T22:26:05.218Z,1401920765.218 [Reporter](INFO): depth 0.399527 m
2014-06-04T22:26:05.807Z,1401920765.807 [Reporter](INFO): depth 0.411659 m
2014-06-04T22:26:06.272Z,1401920766.272 [Reporter](INFO): depth 0.396927 m
2014-06-04T22:26:06.789Z,1401920766.789 [Reporter](INFO): depth 0.390428 m
2014-06-04T22:26:07.282Z,1401920767.282 [Reporter](INFO): depth 0.387395 m
2014-06-04T22:26:07.758Z,1401920767.758 [Reporter](INFO): depth 0.367898 m
2014-06-04T22:26:08.270Z,1401920768.270 [Reporter](INFO): depth 0.363998 m
2014-06-04T22:26:08.804Z,1401920768.804 [Reporter](INFO): depth 0.359232 m
2014-06-04T22:26:09.774Z,1401920769.774 [Reporter](INFO): depth 0.351000 m
2014-06-04T22:26:10.310Z,1401920770.310 [Reporter](INFO): depth 0.341901 m
2014-06-04T22:26:10.766Z,1401920770.766 [Reporter](INFO): depth 0.315037 m
2014-06-04T22:26:11.319Z,1401920771.319 [Reporter](INFO): depth 0.302039 m
2014-06-04T22:26:11.788Z,1401920771.788 [Reporter](INFO): depth 0.309838 m
2014-06-04T22:26:12.369Z,1401920772.369 [Reporter](INFO): depth 0.300739 m
2014-06-04T22:26:12.656Z,1401920772.656 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter
2014-06-04T22:26:12.829Z,1401920772.829 [Reporter](INFO): depth 0.299439 m
2014-06-04T22:26:13.287Z,1401920773.287 [Reporter](INFO): depth 0.294673 m
2014-06-04T22:26:13.778Z,1401920773.778 [Reporter](INFO): depth 0.294240 m
2014-06-04T22:26:14.764Z,1401920774.764 [Reporter](INFO): depth 0.295107 m
2014-06-04T22:26:15.282Z,1401920775.282 [Reporter](INFO): depth 0.296840 m
2014-06-04T22:26:15.701Z,1401920775.701 [Reporter](INFO): depth 0.293373 m
2014-06-04T22:26:16.148Z,1401920776.148 [Reporter](INFO): depth 0.292507 m
2014-06-04T22:26:16.628Z,1401920776.628 [Reporter](INFO): depth 0.287308 m
2014-06-04T22:26:17.060Z,1401920777.060 [Reporter](INFO): depth 0.296840 m
2014-06-04T22:26:17.582Z,1401920777.582 [Reporter](INFO): depth 0.289907 m
2014-06-04T22:26:18.057Z,1401920778.057 [Reporter](INFO): depth 0.294673 m
2014-06-04T22:26:18.519Z,1401920778.519 [Reporter](INFO): depth 0.310271 m
2014-06-04T22:26:19.458Z,1401920779.458 [Reporter](INFO): depth 0.296840 m
2014-06-04T22:26:19.930Z,1401920779.930 [Reporter](INFO): depth 0.303339 m
2014-06-04T22:26:20.339Z,1401920780.339 [Reporter](INFO): depth 0.325436 m
2014-06-04T22:26:20.803Z,1401920780.803 [Reporter](INFO): depth 0.327603 m
2014-06-04T22:26:21.366Z,1401920781.366 [Reporter](INFO): depth 0.317637 m
2014-06-04T22:26:21.885Z,1401920781.885 [Reporter](INFO): depth 0.331069 m
2014-06-04T22:26:22.305Z,1401920782.305 [Reporter](INFO): depth 0.333668 m
2014-06-04T22:26:22.798Z,1401920782.798 [Reporter](INFO): depth 0.338435 m
2014-06-04T22:26:23.211Z,1401920783.211 [Reporter](INFO): depth 0.341901 m
2014-06-04T22:26:24.658Z,1401920784.658 [Reporter](INFO): depth 0.368331 m
2014-06-04T22:26:25.150Z,1401920785.150 [Reporter](INFO): depth 0.383496 m
2014-06-04T22:26:25.569Z,1401920785.569 [Reporter](INFO): depth 0.385662 m
2014-06-04T22:26:26.031Z,1401920786.031 [Reporter](INFO): depth 0.386962 m
2014-06-04T22:26:26.941Z,1401920786.941 [Reporter](INFO): depth 0.392594 m
2014-06-04T22:26:27.393Z,1401920787.393 [Reporter](INFO): depth 0.424224 m
2014-06-04T22:26:27.934Z,1401920787.934 [Reporter](INFO): depth 0.402127 m
2014-06-04T22:26:28.359Z,1401920788.359 [Reporter](INFO): depth 0.422057 m
2014-06-04T22:26:28.835Z,1401920788.835 [Reporter](INFO): depth 0.428557 m
2014-06-04T22:26:29.785Z,1401920789.785 [Reporter](INFO): depth 0.425524 m
2014-06-04T22:26:30.286Z,1401920790.286 [Reporter](INFO): depth 0.428123 m
2014-06-04T22:26:30.742Z,1401920790.742 [Reporter](INFO): depth 0.422057 m
2014-06-04T22:26:31.239Z,1401920791.239 [Reporter](INFO): depth 0.432889 m
2014-06-04T22:26:31.676Z,1401920791.676 [Reporter](INFO): depth 0.442422 m
2014-06-04T22:26:32.140Z,1401920792.140 [Reporter](INFO): depth 0.436356 m
2014-06-04T22:26:32.549Z,1401920792.549 [Reporter](INFO): depth 0.453253 m
2014-06-04T22:26:33.046Z,1401920793.046 [Reporter](INFO): depth 0.437655 m
2014-06-04T22:26:33.491Z,1401920793.491 [Reporter](INFO): depth 0.466685 m
2014-06-04T22:26:34.479Z,1401920794.479 [Reporter](INFO): depth 0.458886 m
2014-06-04T22:26:34.943Z,1401920794.943 [Reporter](INFO): depth 0.463219 m
2014-06-04T22:26:35.376Z,1401920795.376 [Reporter](INFO): depth 0.456720 m
2014-06-04T22:26:35.797Z,1401920795.797 [Reporter](INFO): depth 0.469718 m
2014-06-04T22:26:36.263Z,1401920796.263 [Reporter](INFO): depth 0.448487 m
2014-06-04T22:26:36.696Z,1401920796.696 [Reporter](INFO): depth 0.469285 m
2014-06-04T22:26:37.188Z,1401920797.188 [Reporter](INFO): depth 0.487049 m
2014-06-04T22:26:37.594Z,1401920797.594 [Reporter](INFO): depth 0.487482 m
2014-06-04T22:26:38.098Z,1401920798.098 [Reporter](INFO): depth 0.473184 m
2014-06-04T22:26:38.511Z,1401920798.511 [Reporter](INFO): depth 0.483583 m
2014-06-04T22:26:38.975Z,1401920798.975 [Reporter](INFO): depth 0.491382 m
2014-06-04T22:26:39.901Z,1401920799.901 [Reporter](INFO): depth 0.478384 m
2014-06-04T22:26:40.409Z,1401920800.409 [Reporter](INFO): depth 0.489649 m
2014-06-04T22:26:40.873Z,1401920800.873 [Reporter](INFO): VerticalControl.verticalMode 1 enum
2014-06-04T22:26:40.874Z,1401920800.874 [Reporter](INFO): SpeedControl.propOmegaAction 15.708007 rad/s
2014-06-04T22:26:40.879Z,1401920800.879 [Reporter](INFO): SpeedControl.speedCmd 0.500000 m/s
2014-06-04T22:26:40.880Z,1401920800.880 [Reporter](INFO): depth 0.507847 m
2014-06-04T22:26:40.986Z,1401920800.986 [Radio_Freewave](INFO): Powering down
2014-06-04T22:26:41.382Z,1401920801.382 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:26:41.383Z,1401920801.383 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:26:41.384Z,1401920801.384 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:26:41.385Z,1401920801.385 [Reporter](INFO): depth 0.496581 m
2014-06-04T22:26:41.919Z,1401920801.919 [Reporter](INFO): depth 0.488349 m
2014-06-04T22:26:41.991Z,1401920801.991 [Radio_Freewave](INFO): Powering up
2014-06-04T22:26:42.598Z,1401920802.598 [Reporter](INFO): VerticalControl.verticalMode 1 enum
2014-06-04T22:26:42.599Z,1401920802.599 [Reporter](INFO): SpeedControl.propOmegaAction 15.708007 rad/s
2014-06-04T22:26:42.600Z,1401920802.600 [Reporter](INFO): SpeedControl.speedCmd 0.500000 m/s
2014-06-04T22:26:42.601Z,1401920802.601 [Reporter](INFO): depth 0.519112 m
2014-06-04T22:26:42.799Z,1401920802.799 [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-06-04T22:26:43.165Z,1401920803.165 [Reporter](INFO): depth 0.512613 m
2014-06-04T22:26:43.419Z,1401920803.419 [Radio_Freewave](INFO): Powering down
2014-06-04T22:26:43.815Z,1401920803.815 [Reporter](INFO): depth 0.510446 m
2014-06-04T22:26:44.786Z,1401920804.786 [Reporter](INFO): depth 0.516079 m
2014-06-04T22:26:45.306Z,1401920805.306 [Reporter](INFO): ThrusterServo.component_avgCurrent 55.050004 mA
2014-06-04T22:26:45.313Z,1401920805.313 [Reporter](INFO): depth 0.527777 m
2014-06-04T22:26:45.745Z,1401920805.745 [Reporter](INFO): depth 0.538609 m
2014-06-04T22:26:46.281Z,1401920806.281 [Reporter](INFO): depth 0.526911 m
2014-06-04T22:26:47.146Z,1401920807.146 [Reporter](INFO): depth 0.542076 m
2014-06-04T22:26:47.695Z,1401920807.695 [Reporter](INFO): depth 0.540343 m
2014-06-04T22:26:48.244Z,1401920808.244 [Reporter](INFO): depth 0.558973 m
2014-06-04T22:26:48.885Z,1401920808.885 [Reporter](INFO): depth 0.549441 m
2014-06-04T22:26:49.950Z,1401920809.950 [Reporter](INFO): depth 0.568506 m
2014-06-04T22:26:50.586Z,1401920810.586 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:26:50.588Z,1401920810.588 [Reporter](INFO): depth 0.578904 m
2014-06-04T22:26:51.088Z,1401920811.088 [Reporter](INFO): depth 0.560707 m
2014-06-04T22:26:51.625Z,1401920811.625 [Reporter](INFO): depth 0.576305 m
2014-06-04T22:26:52.138Z,1401920812.138 [Reporter](INFO): depth 0.597535 m
2014-06-04T22:26:52.669Z,1401920812.669 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:26:52.671Z,1401920812.671 [Reporter](INFO): depth 0.581504 m
2014-06-04T22:26:52.946Z,1401920812.946 [CommandLine](IMPORTANT): got command maintain clear
2014-06-04T22:26:53.336Z,1401920813.336 [Reporter](INFO): depth 0.596669 m
2014-06-04T22:26:53.343Z,1401920813.343 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,HFRadarModelCalc,HFRadarCompactModelForecaster,HFRCMSpaceInterpolator,HFRCMTimeInterpolator,HFRCMReconstructedInterpolator,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-06-04T22:26:53.949Z,1401920813.949 [Reporter](INFO): depth 0.604468 m
2014-06-04T22:26:55.022Z,1401920815.022 [Reporter](INFO): depth 0.609667 m
2014-06-04T22:26:55.586Z,1401920815.586 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:26:55.588Z,1401920815.588 [Reporter](INFO): depth 0.638697 m
2014-06-04T22:26:56.424Z,1401920816.424 [Reporter](INFO): depth 0.637830 m
2014-06-04T22:26:56.985Z,1401920816.985 [Reporter](INFO): depth 0.643463 m
2014-06-04T22:26:57.486Z,1401920817.486 [Reporter](INFO): depth 0.649962 m
2014-06-04T22:26:58.066Z,1401920818.066 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119998 mA
2014-06-04T22:26:58.068Z,1401920818.068 [Reporter](INFO): depth 0.664694 m
2014-06-04T22:26:58.693Z,1401920818.693 [Reporter](INFO): depth 0.667293 m
2014-06-04T22:26:59.293Z,1401920819.293 [Reporter](INFO): depth 0.669026 m
2014-06-04T22:26:59.818Z,1401920819.818 [Reporter](INFO): depth 0.675525 m
2014-06-04T22:27:00.930Z,1401920820.930 [Reporter](INFO): ThrusterServo.component_avgCurrent 110.100009 mA
2014-06-04T22:27:00.932Z,1401920820.932 [Reporter](INFO): depth 0.685924 m
2014-06-04T22:27:01.408Z,1401920821.408 [Reporter](INFO): depth 0.691557 m
2014-06-04T22:27:01.893Z,1401920821.893 [Reporter](INFO): depth 0.701089 m
2014-06-04T22:27:02.366Z,1401920822.366 [Reporter](INFO): depth 0.703689 m
2014-06-04T22:27:02.923Z,1401920822.923 [Reporter](INFO): depth 0.704555 m
2014-06-04T22:27:03.847Z,1401920823.847 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:27:03.849Z,1401920823.849 [Reporter](INFO): depth 0.699789 m
2014-06-04T22:27:04.361Z,1401920824.361 [Reporter](INFO): depth 0.704988 m
2014-06-04T22:27:04.865Z,1401920824.865 [Reporter](INFO): depth 0.705855 m
2014-06-04T22:27:05.923Z,1401920825.923 [Reporter](INFO): depth 0.714087 m
2014-06-04T22:27:06.499Z,1401920826.499 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:27:06.501Z,1401920826.501 [Reporter](INFO): depth 0.717120 m
2014-06-04T22:27:07.013Z,1401920827.013 [Reporter](INFO): depth 0.705422 m
2014-06-04T22:27:07.514Z,1401920827.514 [Reporter](INFO): depth 0.716687 m
2014-06-04T22:27:07.879Z,1401920827.879 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:73 Calculated:92
2014-06-04T22:27:07.879Z,1401920827.879 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 0.6 0.6 0.2 0.7 1493.4 983.0 -1115.5 756.1 0 0 0 0 0 0 0 0 3481.9 302.8 570.8 1 3328.182.7 1 0 0 0 0 0 0 0 0 -2.95 -7.14 6.2 0.2 18.7 0.005 35.0 1489 73
2014-06-04T22:27:08.022Z,1401920828.022 [Reporter](INFO): depth 0.702822 m
2014-06-04T22:27:08.682Z,1401920828.682 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:27:08.684Z,1401920828.684 [Reporter](INFO): depth 0.716254 m
2014-06-04T22:27:09.160Z,1401920829.160 [Reporter](INFO): depth 0.702389 m
2014-06-04T22:27:09.673Z,1401920829.673 [Reporter](INFO): depth 0.695890 m
2014-06-04T22:27:10.666Z,1401920830.666 [Reporter](INFO): depth 0.698056 m
2014-06-04T22:27:11.166Z,1401920831.166 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:27:11.168Z,1401920831.168 [Reporter](INFO): depth 0.691557 m
2014-06-04T22:27:11.636Z,1401920831.636 [Reporter](INFO): depth 0.685491 m
2014-06-04T22:27:12.129Z,1401920832.129 [Reporter](INFO): depth 0.679425 m
2014-06-04T22:27:13.054Z,1401920833.054 [Reporter](INFO): depth 0.678992 m
2014-06-04T22:27:13.627Z,1401920833.627 [Reporter](INFO): depth 0.682025 m
2014-06-04T22:27:14.144Z,1401920834.144 [Reporter](INFO): depth 0.669460 m
2014-06-04T22:27:14.617Z,1401920834.617 [Reporter](INFO): depth 0.679425 m
2014-06-04T22:27:15.237Z,1401920835.237 [Reporter](INFO): depth 0.670326 m
2014-06-04T22:27:16.230Z,1401920836.230 [Reporter](INFO): depth 0.652128 m
2014-06-04T22:27:16.752Z,1401920836.752 [Reporter](INFO): depth 0.656895 m
2014-06-04T22:27:17.193Z,1401920837.193 [Reporter](INFO): depth 0.649962 m
2014-06-04T22:27:17.740Z,1401920837.740 [Reporter](INFO): depth 0.643896 m
2014-06-04T22:27:18.559Z,1401920838.559 [Reporter](INFO): depth 0.626998 m
2014-06-04T22:27:19.193Z,1401920839.193 [Reporter](INFO): ThrusterServo.component_avgCurrent 124.779999 mA
2014-06-04T22:27:19.676Z,1401920839.676 [Reporter](INFO): depth 0.621366 m
2014-06-04T22:27:20.177Z,1401920840.177 [Reporter](INFO): depth 0.610967 m
2014-06-04T22:27:20.754Z,1401920840.754 [Reporter](INFO): depth 0.591036 m
2014-06-04T22:27:21.243Z,1401920841.243 [Reporter](INFO): depth 0.599268 m
2014-06-04T22:27:22.275Z,1401920842.275 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770001 mA
2014-06-04T22:27:22.277Z,1401920842.277 [Reporter](INFO): depth 0.582371 m
2014-06-04T22:27:22.930Z,1401920842.930 [Reporter](INFO): depth 0.580637 m
2014-06-04T22:27:23.466Z,1401920843.466 [Reporter](INFO): depth 0.581504 m
2014-06-04T22:27:24.020Z,1401920844.020 [Reporter](INFO): depth 0.551608 m
2014-06-04T22:27:24.488Z,1401920844.488 [Reporter](INFO): depth 0.558107 m
2014-06-04T22:27:24.987Z,1401920844.987 [Reporter](INFO): ThrusterServo.component_avgCurrent 99.090002 mA
2014-06-04T22:27:24.989Z,1401920844.989 [Reporter](INFO): depth 0.553774 m
2014-06-04T22:27:25.482Z,1401920845.482 [Reporter](INFO): depth 0.545975 m
2014-06-04T22:27:25.970Z,1401920845.970 [Reporter](INFO): depth 0.530810 m
2014-06-04T22:27:26.415Z,1401920846.415 [Reporter](INFO): depth 0.538609 m
2014-06-04T22:27:26.904Z,1401920846.904 [Reporter](INFO): depth 0.511313 m
2014-06-04T22:27:27.844Z,1401920847.844 [Reporter](INFO): ThrusterServo.component_avgCurrent 121.110000 mA
2014-06-04T22:27:27.846Z,1401920847.846 [Reporter](INFO): depth 0.516945 m
2014-06-04T22:27:28.374Z,1401920848.374 [Reporter](INFO): depth 0.509146 m
2014-06-04T22:27:28.968Z,1401920848.968 [Reporter](INFO): VerticalControl.verticalMode 8 enum
2014-06-04T22:27:28.969Z,1401920848.969 [Reporter](INFO): SpeedControl.propOmegaAction 0.000000 rad/s
2014-06-04T22:27:28.971Z,1401920848.971 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2014-06-04T22:27:28.971Z,1401920848.971 [Reporter](INFO): depth 0.485749 m
2014-06-04T22:27:29.080Z,1401920849.080 [Radio_Freewave](INFO): Powering up
2014-06-04T22:27:29.639Z,1401920849.639 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA
2014-06-04T22:27:29.640Z,1401920849.640 [Reporter](INFO): depth 0.467552 m
2014-06-04T22:27:30.194Z,1401920850.194 [Reporter](INFO): depth 0.466252 m
2014-06-04T22:27:30.285Z,1401920850.285 [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-06-04T22:27:31.079Z,1401920851.079 [Reporter](INFO): depth 0.458453 m
2014-06-04T22:27:31.613Z,1401920851.613 [Reporter](INFO): depth 0.431590 m
2014-06-04T22:27:32.145Z,1401920852.145 [Reporter](INFO): depth 0.445888 m
2014-06-04T22:27:32.618Z,1401920852.618 [Reporter](INFO): depth 0.440688 m
2014-06-04T22:27:33.126Z,1401920853.126 [Reporter](INFO): depth 0.413392 m
2014-06-04T22:27:34.096Z,1401920854.096 [Reporter](INFO): depth 0.408626 m
2014-06-04T22:27:34.701Z,1401920854.701 [Reporter](INFO): depth 0.379596 m
2014-06-04T22:27:35.210Z,1401920855.210 [Reporter](INFO): depth 0.401260 m
2014-06-04T22:27:35.719Z,1401920855.719 [Reporter](INFO): depth 0.372230 m
2014-06-04T22:27:36.176Z,1401920856.176 [Reporter](INFO): depth 0.387395 m
2014-06-04T22:27:36.696Z,1401920856.696 [Reporter](INFO): depth 0.382196 m
2014-06-04T22:27:37.145Z,1401920857.145 [Reporter](INFO): depth 0.354466 m
2014-06-04T22:27:37.637Z,1401920857.637 [Reporter](INFO): depth 0.351866 m
2014-06-04T22:27:38.595Z,1401920858.595 [Reporter](INFO): depth 0.333668 m
2014-06-04T22:27:39.200Z,1401920859.200 [Reporter](INFO): depth 0.329336 m
2014-06-04T22:27:39.649Z,1401920859.648 [Reporter](INFO): depth 0.306372 m
2014-06-04T22:27:40.153Z,1401920860.153 [Reporter](INFO): depth 0.308972 m
2014-06-04T22:27:40.639Z,1401920860.639 [Reporter](INFO): depth 0.281675 m
2014-06-04T22:27:41.147Z,1401920861.147 [Reporter](INFO): depth 0.276909 m
2014-06-04T22:27:41.647Z,1401920861.647 [Reporter](INFO): depth 0.286874 m
2014-06-04T22:27:42.116Z,1401920862.116 [Reporter](INFO): depth 0.292940 m
2014-06-04T22:27:42.540Z,1401920862.540 [CommandLine](IMPORTANT): got command report clear
2014-06-04T22:28:07.326Z,1401920887.326 [NAL9602](INFO): SBD MO Status=2, MOMSN=33264, MT Status=2, MTMSN=0
2014-06-04T22:28:07.326Z,1401920887.326 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2014-06-04T22:28:15.114Z,1401920895.114 [CommandLine](IMPORTANT): got command quit
2014-06-04T22:28:16.123Z,1401920896.123 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:16.124Z,1401920896.124 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.275Z,1401920896.275 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2014-06-04T22:28:16.275Z,1401920896.275 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.276Z,1401920896.276 [CommandLine](INFO): Join timeout helper Thread ID is 8745
2014-06-04T22:28:16.303Z,1401920896.303 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2014-06-04T22:28:16.303Z,1401920896.303 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.304Z,1401920896.304 [NavChartDb](INFO): Join timeout helper Thread ID is 8746
2014-06-04T22:28:16.504Z,1401920896.504 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:16.504Z,1401920896.504 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.519Z,1401920896.519 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2014-06-04T22:28:16.519Z,1401920896.519 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.520Z,1401920896.520 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8747
2014-06-04T22:28:16.784Z,1401920896.784 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:16.785Z,1401920896.785 [WetLabsBB2FL](INFO): Powering down
2014-06-04T22:28:16.786Z,1401920896.786 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.800Z,1401920896.800 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2014-06-04T22:28:16.800Z,1401920896.800 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:16.801Z,1401920896.801 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8748
2014-06-04T22:28:17.033Z,1401920897.033 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:17.033Z,1401920897.033 [CTD_NeilBrown](INFO): Powering down
2014-06-04T22:28:17.034Z,1401920897.034 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.052Z,1401920897.052 [ComponentRegistry](INFO): Shutting down Radio_Freewave ThreadHandler
2014-06-04T22:28:17.052Z,1401920897.052 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.053Z,1401920897.053 [Radio_Freewave](INFO): Join timeout helper Thread ID is 8749
2014-06-04T22:28:17.317Z,1401920897.317 [Radio_Freewave ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:17.432Z,1401920897.432 [Radio_Freewave](INFO): Powering down
2014-06-04T22:28:17.834Z,1401920897.834 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.843Z,1401920897.843 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2014-06-04T22:28:17.843Z,1401920897.843 [logger ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.843Z,1401920897.843 [logger](INFO): Join timeout helper Thread ID is 8751
2014-06-04T22:28:17.846Z,1401920897.846 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:17.846Z,1401920897.846 [logger ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.862Z,1401920897.862 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2014-06-04T22:28:17.862Z,1401920897.862 [CommandLine ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.863Z,1401920897.863 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2014-06-04T22:28:17.863Z,1401920897.863 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:17.863Z,1401920897.863 [controlThread](INFO): Join timeout helper Thread ID is 8752
2014-06-04T22:28:18.120Z,1401920898.120 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2014-06-04T22:28:18.120Z,1401920898.120 [controlThread](DEBUG): Uninitializing ControlThread
2014-06-04T22:28:18.120Z,1401920898.120 [AHRS_sp3003D](INFO): Powering down
2014-06-04T22:28:18.121Z,1401920898.121 [DVL_micro](INFO): uninitialize:Powering down
2014-06-04T22:28:18.123Z,1401920898.123 [NAL9602](INFO): Powering down
2014-06-04T22:28:18.125Z,1401920898.125 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-06-04T22:28:18.128Z,1401920898.128 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2014-06-04T22:28:18.129Z,1401920898.129 [Default] Stopped
2014-06-04T22:28:18.129Z,1401920898.129 [Default](INFO): Aggregate::uninitialize Default
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS] Stopped
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS:A.SetSpeed] Stopped
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS:B.GoToSurface] Stopped
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-04T22:28:18.129Z,1401920898.129 [Default:GPS:Read_GPS] Stopped
2014-06-04T22:28:18.129Z,1401920898.129 [Default:Iridium] Stopped
2014-06-04T22:28:18.130Z,1401920898.130 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-06-04T22:28:18.130Z,1401920898.130 [Default:Iridium:A.SetSpeed] Stopped
2014-06-04T22:28:18.130Z,1401920898.130 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-06-04T22:28:18.130Z,1401920898.130 [Default:Iridium:B.GoToSurface] Stopped
2014-06-04T22:28:18.130Z,1401920898.130 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-06-04T22:28:18.130Z,1401920898.130 [Default:Iridium:Read_Iridium] Stopped
2014-06-04T22:28:18.130Z,1401920898.130 [Default:CallIridium] Stopped
2014-06-04T22:28:18.130Z,1401920898.130 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-06-04T22:28:18.130Z,1401920898.130 [Default:CallIridium:B] Stopped
2014-06-04T22:28:18.130Z,1401920898.130 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-06-04T22:28:18.134Z,1401920898.134 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2014-06-04T22:28:18.134Z,1401920898.134 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2014-06-04T22:28:18.135Z,1401920898.135 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2014-06-04T22:28:18.135Z,1401920898.135 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2014-06-04T22:28:18.135Z,1401920898.135 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2014-06-04T22:28:18.136Z,1401920898.136 [BuoyancyServo](INFO): Powering down
2014-06-04T22:28:18.154Z,1401920898.154 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2014-06-04T22:28:18.155Z,1401920898.155 [ElevatorServo](INFO): Powering down
2014-06-04T22:28:18.156Z,1401920898.156 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-06-04T22:28:18.156Z,1401920898.156 [MassServo](INFO): Powering down
2014-06-04T22:28:18.157Z,1401920898.157 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2014-06-04T22:28:18.157Z,1401920898.157 [RudderServo](INFO): Powering down
2014-06-04T22:28:18.158Z,1401920898.158 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2014-06-04T22:28:18.158Z,1401920898.158 [ThrusterServo](INFO): Powering down
2014-06-04T22:28:18.159Z,1401920898.159 [SBIT](DEBUG): Uninitialize SBIT Component.
2014-06-04T22:28:18.159Z,1401920898.159 [IBIT](DEBUG): Uninitialize IBIT Component.
2014-06-04T22:28:18.159Z,1401920898.159 [CBIT](DEBUG): Uninitialize CBIT Component.
2014-06-04T22:28:18.203Z,1401920898.203 [controlThread ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:18.205Z,1401920898.205 [Radio_Freewave ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:18.282Z,1401920898.282 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:18.285Z,1401920898.285 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:18.335Z,1401920898.335 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2014-06-04T22:28:18.369Z,1401920898.369 [logger ThreadHandler](INFO): Thread cancelled.