2012-10-02T17:43:02.481Z,1349199782.481 [Supervisor](DEBUG): Initializing supervisor. 2012-10-02T17:43:02.492Z,1349199782.492 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2012-10-02T17:43:02.493Z,1349199782.493 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-10-02T17:43:02.504Z,1349199782.504 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2012-10-02T17:43:02.508Z,1349199782.508 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-10-02T17:43:02.531Z,1349199782.531 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-10-02T17:43:02.561Z,1349199782.561 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2012-10-02T17:43:02.562Z,1349199782.562 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread. 2012-10-02T17:43:02.562Z,1349199782.562 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-10-02T17:43:02.563Z,1349199782.563 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-10-02T17:43:03.025Z,1349199783.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-10-02T17:43:03.025Z,1349199783.025 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-10-02T17:43:03.332Z,1349199783.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-10-02T17:43:03.333Z,1349199783.333 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-10-02T17:43:03.468Z,1349199783.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-10-02T17:43:03.468Z,1349199783.468 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-10-02T17:43:03.832Z,1349199783.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-10-02T17:43:03.832Z,1349199783.832 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-10-02T17:43:04.062Z,1349199784.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-10-02T17:43:04.063Z,1349199784.063 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-10-02T17:43:04.525Z,1349199784.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-10-02T17:43:04.526Z,1349199784.526 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-10-02T17:43:04.844Z,1349199784.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-10-02T17:43:04.845Z,1349199784.845 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-10-02T17:43:05.334Z,1349199785.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-10-02T17:43:05.334Z,1349199785.334 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-10-02T17:43:05.493Z,1349199785.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-10-02T17:43:05.493Z,1349199785.493 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-10-02T17:43:06.188Z,1349199786.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-10-02T17:43:06.188Z,1349199786.188 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-10-02T17:43:06.299Z,1349199786.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-10-02T17:43:06.300Z,1349199786.300 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-10-02T17:43:06.384Z,1349199786.384 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-10-02T17:43:06.385Z,1349199786.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-10-02T17:43:06.484Z,1349199786.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-10-02T17:43:06.620Z,1349199786.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-10-02T17:43:06.723Z,1349199786.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-10-02T17:43:06.821Z,1349199786.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-10-02T17:43:06.918Z,1349199786.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-10-02T17:43:07.032Z,1349199787.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-10-02T17:43:07.120Z,1349199787.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-10-02T17:43:07.205Z,1349199787.205 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2012-10-02T17:43:07.233Z,1349199787.233 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-10-02T17:43:07.372Z,1349199787.372 [InternalSim] Loaded 2012-10-02T17:43:07.372Z,1349199787.372 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-10-02T17:43:07.373Z,1349199787.373 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-10-02T17:43:07.373Z,1349199787.373 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-10-02T17:43:07.429Z,1349199787.429 [SBIT](DEBUG): Construct Startup Built In Test. 2012-10-02T17:43:07.442Z,1349199787.442 [SBIT] Loaded 2012-10-02T17:43:07.442Z,1349199787.442 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-10-02T17:43:07.443Z,1349199787.443 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-10-02T17:43:07.471Z,1349199787.471 [IBIT] Loaded 2012-10-02T17:43:07.471Z,1349199787.471 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-10-02T17:43:07.474Z,1349199787.474 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-10-02T17:43:07.589Z,1349199787.589 [CBIT] Loaded 2012-10-02T17:43:07.590Z,1349199787.589 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-10-02T17:43:07.590Z,1349199787.590 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-10-02T17:43:07.590Z,1349199787.591 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-10-02T17:43:07.754Z,1349199787.754 [BuoyancyServo] Loaded 2012-10-02T17:43:07.754Z,1349199787.754 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-10-02T17:43:07.763Z,1349199787.763 [ElevatorServo] Loaded 2012-10-02T17:43:07.763Z,1349199787.763 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-10-02T17:43:07.775Z,1349199787.775 [MassServo] Loaded 2012-10-02T17:43:07.775Z,1349199787.775 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-10-02T17:43:07.786Z,1349199787.786 [RudderServo] Loaded 2012-10-02T17:43:07.787Z,1349199787.787 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-10-02T17:43:07.800Z,1349199787.800 [ThrusterServo] Loaded 2012-10-02T17:43:07.801Z,1349199787.801 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-10-02T17:43:07.801Z,1349199787.801 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-10-02T17:43:07.802Z,1349199787.802 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-10-02T17:43:07.824Z,1349199787.824 [DepthRateCalculator] Loaded 2012-10-02T17:43:07.825Z,1349199787.825 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-10-02T17:43:11.491Z,1349199791.491 [HFRadarModelCalc] Loaded 2012-10-02T17:43:11.491Z,1349199791.491 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2012-10-02T17:43:11.507Z,1349199791.507 [NavChart] Loaded 2012-10-02T17:43:11.507Z,1349199791.507 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-10-02T17:43:11.513Z,1349199791.513 [PitchRateCalculator] Loaded 2012-10-02T17:43:11.513Z,1349199791.513 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-10-02T17:43:11.519Z,1349199791.519 [SpeedCalculator] Loaded 2012-10-02T17:43:11.520Z,1349199791.520 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-10-02T17:43:11.534Z,1349199791.534 [TempGradientCalculator] Loaded 2012-10-02T17:43:11.534Z,1349199791.534 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-10-02T17:43:11.540Z,1349199791.540 [YawRateCalculator] Loaded 2012-10-02T17:43:11.540Z,1349199791.540 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-10-02T17:43:11.559Z,1349199791.559 [Navigation] Loaded 2012-10-02T17:43:11.559Z,1349199791.559 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-10-02T17:43:11.560Z,1349199791.560 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-10-02T17:43:11.561Z,1349199791.560 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-10-02T17:43:11.776Z,1349199791.776 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-10-02T17:43:11.777Z,1349199791.777 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-10-02T17:43:11.800Z,1349199791.800 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-10-02T17:43:11.801Z,1349199791.801 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-10-02T17:43:11.841Z,1349199791.841 [VerticalControl](DEBUG): Construct VerticalControl. 2012-10-02T17:43:11.884Z,1349199791.884 [VerticalControl] Loaded 2012-10-02T17:43:11.884Z,1349199791.884 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-10-02T17:43:11.885Z,1349199791.885 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-10-02T17:43:11.905Z,1349199791.905 [HorizontalControl] Loaded 2012-10-02T17:43:11.905Z,1349199791.905 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-10-02T17:43:11.906Z,1349199791.906 [SpeedControl](DEBUG): Construct SpeedControl. 2012-10-02T17:43:11.908Z,1349199791.908 [SpeedControl] Loaded 2012-10-02T17:43:11.908Z,1349199791.908 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-10-02T17:43:11.909Z,1349199791.909 [LoopControl](DEBUG): Construct LoopControl. 2012-10-02T17:43:11.909Z,1349199791.909 [LoopControl] Loaded 2012-10-02T17:43:11.910Z,1349199791.909 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-10-02T17:43:11.910Z,1349199791.910 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-10-02T17:43:11.911Z,1349199791.911 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-10-02T17:43:11.916Z,1349199791.916 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-10-02T17:43:11.920Z,1349199791.920 [AsyncPiEstimator] Loaded 2012-10-02T17:43:11.921Z,1349199791.921 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-10-02T17:43:11.922Z,1349199791.922 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406714E0 2012-10-02T17:43:11.923Z,1349199791.923 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-10-02T17:43:11.923Z,1349199791.923 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-10-02T17:43:12.043Z,1349199792.043 [AHRS_sp3003D] Loaded 2012-10-02T17:43:12.043Z,1349199792.043 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-10-02T17:43:12.057Z,1349199792.057 [Depth_Keller] Loaded 2012-10-02T17:43:12.057Z,1349199792.057 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-10-02T17:43:12.063Z,1349199792.063 [DropWeight] Loaded 2012-10-02T17:43:12.063Z,1349199792.063 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-10-02T17:43:12.176Z,1349199792.176 [DVL_micro] Loaded 2012-10-02T17:43:12.176Z,1349199792.176 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2012-10-02T17:43:12.177Z,1349199792.177 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F14E0 2012-10-02T17:43:12.257Z,1349199792.257 [NAL9602] Loaded 2012-10-02T17:43:12.258Z,1349199792.258 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2012-10-02T17:43:12.307Z,1349199792.307 [Onboard] Loaded 2012-10-02T17:43:12.307Z,1349199792.307 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-10-02T17:43:12.314Z,1349199792.314 [Radio_Freewave] Loaded 2012-10-02T17:43:12.315Z,1349199792.315 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-10-02T17:43:12.315Z,1349199792.315 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-10-02T17:43:12.316Z,1349199792.316 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-10-02T17:43:12.386Z,1349199792.386 [CTD_NeilBrown] Loaded 2012-10-02T17:43:12.386Z,1349199792.386 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-10-02T17:43:12.387Z,1349199792.387 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407494E0 2012-10-02T17:43:12.398Z,1349199792.398 [PAR_Licor] Loaded 2012-10-02T17:43:12.398Z,1349199792.398 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2012-10-02T17:43:12.414Z,1349199792.414 [Turbulence_NPS] Loaded 2012-10-02T17:43:12.414Z,1349199792.414 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread. 2012-10-02T17:43:12.415Z,1349199792.415 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407794E0 2012-10-02T17:43:12.486Z,1349199792.486 [WetLabsBB2FL] Loaded 2012-10-02T17:43:12.486Z,1349199792.486 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2012-10-02T17:43:12.487Z,1349199792.487 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407A94E0 2012-10-02T17:43:12.488Z,1349199792.488 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-10-02T17:43:12.492Z,1349199792.492 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-10-02T17:43:12.493Z,1349199792.493 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-10-02T17:43:12.506Z,1349199792.506 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-10-02T17:43:12.507Z,1349199792.507 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407D94E0 2012-10-02T17:43:12.519Z,1349199792.519 [Supervisor](DEBUG): Running supervisor. 2012-10-02T17:43:12.520Z,1349199792.520 [CommandLine](INFO): Thread ID is 1239 2012-10-02T17:43:12.524Z,1349199792.524 [controlThread](INFO): Thread ID is 1238 2012-10-02T17:43:12.524Z,1349199792.524 [controlThread](DEBUG): Initializing ControlThread 2012-10-02T17:43:12.524Z,1349199792.524 [CycleStarter](INFO): Thread ID is 1237 2012-10-02T17:43:12.525Z,1349199792.525 [InternalSim](DEBUG): InternalSim initializing... 2012-10-02T17:43:12.548Z,1349199792.548 [AsyncPiEstimator](INFO): Thread ID is 1302 2012-10-02T17:43:12.548Z,1349199792.548 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-10-02T17:43:12.565Z,1349199792.565 [DVL_micro](INFO): Thread ID is 1303 2012-10-02T17:43:12.574Z,1349199792.574 [DVL_micro](INFO): Initializing 2012-10-02T17:43:12.575Z,1349199792.575 [DVL_micro](INFO): start:Powering up 2012-10-02T17:43:12.576Z,1349199792.576 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:43:12.576Z,1349199792.576 [DVL_micro](INFO): Cycling power to configure device. 2012-10-02T17:43:12.589Z,1349199792.589 [SBIT](INFO): Initialize SBIT Component. 2012-10-02T17:43:12.589Z,1349199792.589 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 9914 2012-10-02T17:43:12.590Z,1349199792.590 [IBIT](INFO): Initialize IBIT Component. 2012-10-02T17:43:12.591Z,1349199792.591 [CBIT](DEBUG): Initialize CBIT Component. 2012-10-02T17:43:12.591Z,1349199792.591 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-10-02T17:43:12.601Z,1349199792.601 [CTD_NeilBrown](INFO): Thread ID is 1304 2012-10-02T17:43:12.601Z,1349199792.601 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-10-02T17:43:12.604Z,1349199792.605 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-02T17:43:12.613Z,1349199792.614 [Turbulence_NPS](INFO): Thread ID is 1305 2012-10-02T17:43:12.614Z,1349199792.614 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-10-02T17:43:12.615Z,1349199792.615 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-02T17:43:12.621Z,1349199792.621 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-10-02T17:43:12.622Z,1349199792.622 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-10-02T17:43:12.622Z,1349199792.622 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-10-02T17:43:12.623Z,1349199792.623 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-10-02T17:43:12.623Z,1349199792.623 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-10-02T17:43:12.625Z,1349199792.625 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-10-02T17:43:12.625Z,1349199792.625 [Navigation](DEBUG): Initializing Navigation. 2012-10-02T17:43:12.626Z,1349199792.626 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-10-02T17:43:12.627Z,1349199792.628 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-10-02T17:43:12.633Z,1349199792.633 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-10-02T17:43:12.633Z,1349199792.633 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-10-02T17:43:12.638Z,1349199792.638 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-10-02T17:43:12.643Z,1349199792.643 [WetLabsBB2FL](INFO): Thread ID is 1306 2012-10-02T17:43:12.643Z,1349199792.643 [WetLabsBB2FL](INFO): Powering down 2012-10-02T17:43:12.674Z,1349199792.674 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-02T17:43:12.685Z,1349199792.685 [MissionManager](DEBUG): 2012-10-02T17:43:12.686Z,1349199792.686 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-10-02T17:43:12.714Z,1349199792.714 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-10-02T17:43:12.716Z,1349199792.716 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-10-02T17:43:12.719Z,1349199792.719 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-02T17:43:12.727Z,1349199792.727 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-10-02T17:43:12.730Z,1349199792.730 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-02T17:43:12.737Z,1349199792.737 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-10-02T17:43:12.746Z,1349199792.746 [Default:D.SetSpeed](DEBUG): Construct. 2012-10-02T17:43:12.749Z,1349199792.749 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-02T17:43:12.754Z,1349199792.754 [Default:F.Wait](DEBUG): Construct Wait. 2012-10-02T17:43:12.758Z,1349199792.758 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-10-02T17:43:12.762Z,1349199792.762 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-02T17:43:12.783Z,1349199792.783 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-10-02T17:43:12.817Z,1349199792.816 [NavChartDb](INFO): Thread ID is 1307 2012-10-02T17:43:12.839Z,1349199792.839 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-10-02T17:43:12.840Z,1349199792.840 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-10-02T17:43:12.840Z,1349199792.840 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-10-02T17:43:12.841Z,1349199792.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-10-02T17:43:12.841Z,1349199792.841 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-10-02T17:43:13.937Z,1349199793.937 [Radio_Freewave](INFO): Powering up 2012-10-02T17:43:14.055Z,1349199794.055 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-10-02T17:43:14.056Z,1349199794.056 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-10-02T17:43:14.063Z,1349199794.063 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-10-02T17:43:14.064Z,1349199794.064 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-10-02T17:43:14.070Z,1349199794.070 [MassServo](DEBUG): Initializing EZServoServo. 2012-10-02T17:43:14.071Z,1349199794.071 [MassServo](DEBUG): Initializing MassServo. 2012-10-02T17:43:14.077Z,1349199794.077 [RudderServo](DEBUG): Initializing RudderServo. 2012-10-02T17:43:14.082Z,1349199794.082 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-10-02T17:43:14.084Z,1349199794.084 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-10-02T17:43:15.056Z,1349199795.056 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:43:15.057Z,1349199795.057 [DVL_micro](INFO): Querying output modes 2012-10-02T17:43:15.057Z,1349199795.057 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-02T17:43:15.060Z,1349199795.060 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-02T17:43:15.060Z,1349199795.060 [DVL_micro](INFO): NQ1 output enabled 2012-10-02T17:43:15.061Z,1349199795.061 [DVL_micro](INFO): RSSI output enabled 2012-10-02T17:43:15.061Z,1349199795.061 [DVL_micro](INFO): ADCP output enabled 2012-10-02T17:43:15.061Z,1349199795.061 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-02T17:43:15.068Z,1349199795.068 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-02T17:43:15.073Z,1349199795.073 [DVL_micro](INFO): pause:Powering down 2012-10-02T17:43:17.319Z,1349199797.319 [NAL9602](INFO): Powering up NAL9602 2012-10-02T17:43:28.748Z,1349199808.748 [NAL9602](INFO): NAL9602 initialized 2012-10-02T17:43:29.808Z,1349199809.808 [SBIT](IMPORTANT): Beginning Startup BIT 2012-10-02T17:43:29.811Z,1349199809.811 [CBIT](IMPORTANT): Beginning GF scan 2012-10-02T17:43:42.477Z,1349199822.477 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.823002 degree 2012-10-02T17:43:42.478Z,1349199822.478 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2012-10-02T17:43:42.479Z,1349199822.479 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.827003 degree 2012-10-02T17:43:42.480Z,1349199822.480 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2012-10-02T17:43:42.481Z,1349199822.481 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-02T17:43:42.482Z,1349199822.482 [CommandLine](IMPORTANT): got command report touch DVL_micro.RSSIPoints 2012-10-02T17:43:42.643Z,1349199822.643 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-02T17:43:50.490Z,1349199830.490 [NAL9602](INFO): Powering down 2012-10-02T17:43:56.865Z,1349199836.865 [CBIT](IMPORTANT): No ground fault detected 2012-10-02T17:44:26.221Z,1349199866.221 [SBIT](IMPORTANT): SBIT PASSED 2012-10-02T17:44:26.617Z,1349199866.617 [MissionManager](IMPORTANT): Started mission Startup 2012-10-02T17:44:26.617Z,1349199866.617 [Startup] Running Loop=1 2012-10-02T17:44:26.617Z,1349199866.617 [Startup](INFO): Aggregate::initialize Startup 2012-10-02T17:44:26.617Z,1349199866.617 [Startup:A.GoToSurface] Running Loop=1 2012-10-02T17:44:26.617Z,1349199866.617 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:44:26.623Z,1349199866.623 [Startup:StartupSatComms] Running Loop=1 2012-10-02T17:44:26.623Z,1349199866.623 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-10-02T17:44:26.623Z,1349199866.623 [Startup:StartupSatComms:A] Running Loop=1 2012-10-02T17:44:27.014Z,1349199867.014 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-02T17:44:27.016Z,1349199867.016 [Startup:StartupSatComms:A] Stopped 2012-10-02T17:44:27.016Z,1349199867.016 [Startup:StartupSatComms:B] Running Loop=1 2012-10-02T17:44:27.462Z,1349199867.462 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-02T17:44:27.799Z,1349199867.799 [NAL9602](INFO): Powering up 2012-10-02T17:44:28.001Z,1349199868.000 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-02T17:44:28.221Z,1349199868.221 [Startup:StartupSatComms:B] Stopped 2012-10-02T17:44:28.221Z,1349199868.221 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2012-10-02T17:44:28.222Z,1349199868.222 [Startup:StartupSatComms] Stopped 2012-10-02T17:44:28.222Z,1349199868.222 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2012-10-02T17:44:28.222Z,1349199868.223 [Startup](INFO): Completed Startup 2012-10-02T17:44:28.223Z,1349199868.223 [Startup] Stopped 2012-10-02T17:44:28.223Z,1349199868.223 [Startup](INFO): Aggregate::uninitialize Startup 2012-10-02T17:44:28.223Z,1349199868.223 [Startup:A.GoToSurface] Stopped 2012-10-02T17:44:28.223Z,1349199868.223 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:44:28.617Z,1349199868.617 [MissionManager](IMPORTANT): Started mission Default 2012-10-02T17:44:28.617Z,1349199868.617 [Default] Running Loop=1 2012-10-02T17:44:28.617Z,1349199868.617 [Default](INFO): Aggregate::initialize Default 2012-10-02T17:44:28.617Z,1349199868.617 [Default:D.SetSpeed] Running Loop=1 2012-10-02T17:44:28.617Z,1349199868.617 [Default:D.SetSpeed](DEBUG): Initialize. 2012-10-02T17:44:28.618Z,1349199868.618 [Default:E.GoToSurface] Running Loop=1 2012-10-02T17:44:28.618Z,1349199868.618 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:44:28.618Z,1349199868.618 [Default:Iridium] Running Loop=1 2012-10-02T17:44:28.618Z,1349199868.618 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-10-02T17:44:28.618Z,1349199868.618 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-02T17:44:28.618Z,1349199868.618 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-10-02T17:44:28.618Z,1349199868.618 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-10-02T17:44:28.618Z,1349199868.618 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:44:28.619Z,1349199868.619 [Default:E.GoToSurface] Running Loop=1 2012-10-02T17:44:28.624Z,1349199868.624 [Default:D.SetSpeed] Running Loop=1 2012-10-02T17:44:28.634Z,1349199868.634 [Default:Iridium:B.GoToSurface] Stopped 2012-10-02T17:44:28.634Z,1349199868.634 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:44:28.635Z,1349199868.635 [Default:Iridium:Read_Iridium] Running Loop=1 2012-10-02T17:44:28.635Z,1349199868.635 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-02T17:44:28.639Z,1349199868.639 [Default:GPS] Running Loop=1 2012-10-02T17:44:28.639Z,1349199868.639 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-10-02T17:44:28.640Z,1349199868.639 [Default:GPS:A.SetSpeed] Running Loop=1 2012-10-02T17:44:28.640Z,1349199868.640 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-10-02T17:44:28.640Z,1349199868.640 [Default:GPS:B.GoToSurface] Running Loop=1 2012-10-02T17:44:28.640Z,1349199868.640 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:44:28.645Z,1349199868.645 [Default:GPS:B.GoToSurface] Stopped 2012-10-02T17:44:28.645Z,1349199868.645 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:44:28.646Z,1349199868.646 [Default:GPS:Read_GPS] Running Loop=1 2012-10-02T17:44:28.646Z,1349199868.646 [Default:GPS:A.SetSpeed] Running Loop=1 2012-10-02T17:44:29.016Z,1349199869.016 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-02T17:44:29.019Z,1349199869.019 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-02T17:44:29.021Z,1349199869.020 [Default:GPS:Read_GPS] Stopped 2012-10-02T17:44:29.021Z,1349199869.021 [Default:GPS:D] Running Loop=1 2012-10-02T17:44:29.418Z,1349199869.418 [Default:GPS:D] Stopped 2012-10-02T17:44:29.418Z,1349199869.418 [Default:GPS](INFO): Completed Default:GPS 2012-10-02T17:44:29.418Z,1349199869.418 [Default:GPS] Stopped 2012-10-02T17:44:29.419Z,1349199869.418 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-10-02T17:44:29.419Z,1349199869.419 [Default:GPS:A.SetSpeed] Stopped 2012-10-02T17:44:29.419Z,1349199869.419 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-10-02T17:44:38.224Z,1349199878.224 [NAL9602](INFO): NAL9602 initialized 2012-10-02T17:44:38.364Z,1349199878.364 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml 2012-10-02T17:44:38.364Z,1349199878.365 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml 2012-10-02T17:44:38.426Z,1349199878.425 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m 2012-10-02T17:44:38.428Z,1349199878.428 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m 2012-10-02T17:44:38.431Z,1349199878.431 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m 2012-10-02T17:44:38.434Z,1349199878.434 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m 2012-10-02T17:44:38.454Z,1349199878.454 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m 2012-10-02T17:44:38.458Z,1349199878.458 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg 2012-10-02T17:44:38.462Z,1349199878.462 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg 2012-10-02T17:44:38.465Z,1349199878.465 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg 2012-10-02T17:44:38.468Z,1349199878.468 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg 2012-10-02T17:44:38.471Z,1349199878.471 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min 2012-10-02T17:44:38.474Z,1349199878.474 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s 2012-10-02T17:44:38.478Z,1349199878.478 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h 2012-10-02T17:44:38.482Z,1349199878.482 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a 2012-10-02T17:44:38.486Z,1349199878.486 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-10-02T17:44:38.493Z,1349199878.493 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait. 2012-10-02T17:44:38.495Z,1349199878.495 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-10-02T17:44:38.496Z,1349199878.496 [science_to_67_70:B.DepthEnvelope](CRITICAL): No configuration setting for: VerticalControl.elevTurnTime 2012-10-02T17:44:38.506Z,1349199878.506 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-10-02T17:44:38.513Z,1349199878.513 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait. 2012-10-02T17:44:38.515Z,1349199878.515 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-10-02T17:44:38.533Z,1349199878.533 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-10-02T17:44:38.536Z,1349199878.536 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-10-02T17:44:38.539Z,1349199878.539 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s 2012-10-02T17:44:38.542Z,1349199878.542 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min 2012-10-02T17:44:38.543Z,1349199878.543 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-02T17:44:38.555Z,1349199878.556 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg 2012-10-02T17:44:38.562Z,1349199878.562 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-10-02T17:44:38.659Z,1349199878.659 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool 2012-10-02T17:44:38.663Z,1349199878.662 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool 2012-10-02T17:44:38.666Z,1349199878.666 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool 2012-10-02T17:44:38.673Z,1349199878.673 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool 2012-10-02T17:44:38.680Z,1349199878.680 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool 2012-10-02T17:44:38.683Z,1349199878.683 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a 2012-10-02T17:44:38.691Z,1349199878.691 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a 2012-10-02T17:44:38.699Z,1349199878.699 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a 2012-10-02T17:44:38.706Z,1349199878.706 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a 2012-10-02T17:44:38.714Z,1349199878.714 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a 2012-10-02T17:44:38.723Z,1349199878.723 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l 2012-10-02T17:44:38.730Z,1349199878.730 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m 2012-10-02T17:44:38.738Z,1349199878.738 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg 2012-10-02T17:44:38.745Z,1349199878.745 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg 2012-10-02T17:44:38.752Z,1349199878.752 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l 2012-10-02T17:44:38.756Z,1349199878.756 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m 2012-10-02T17:44:38.763Z,1349199878.763 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg 2012-10-02T17:44:38.770Z,1349199878.770 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg 2012-10-02T17:44:38.795Z,1349199878.795 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-02T17:44:38.817Z,1349199878.816 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-02T17:44:38.826Z,1349199878.826 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool 2012-10-02T17:44:38.827Z,1349199878.827 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool 2012-10-02T17:44:38.840Z,1349199878.840 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy. 2012-10-02T17:44:38.844Z,1349199878.844 [science_to_67_70:H.SetSpeed](DEBUG): Construct. 2012-10-02T17:44:38.847Z,1349199878.847 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-10-02T17:44:38.868Z,1349199878.868 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo. 2012-10-02T17:44:38.888Z,1349199878.888 [science_to_67_70:Wpt.Point](DEBUG): Construct. 2012-10-02T17:44:38.916Z,1349199878.916 [MissionManager](DEBUG): Vehicle yo-yo's to the specified waypoint, with science turned on. Minimum height above the sea floor for the entire mission. 10.0 Maximum depth for the entire mission. 105.0 Minimum offshore distance for the entire mission. 2000.0 Minimum depth while performing the YoYo behavior. 3.0 Maximum depth while performing the YoYo behavior. 100.0 Vehicle up pitch while performing the YoYo behavior. 20.0 Vehicle down pitch while performing the YoYo behavior. -15.0 Latitude of waypoint. 36.120 Longitude of waypoint. -123.486 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications, if the vehicle has a positive pitch. If the vehicle has a negative pitch, it will wait up to 10 minutes more to begin ascent. 120 Speed while performing the YoYo behavior. 1 Maximum length of mission 36 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. 0 Safety behavior that keeps vehicle off sea floor Safety behavior that keeps vehicle from going too deep 0 Safety behavior that keeps vehicle away from the shore 20 0 10 If we're executing here or below, lets' get science data! 2012-10-02T17:44:38.917Z,1349199878.917 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml 2012-10-02T17:44:39.315Z,1349199879.315 [Default] Stopped 2012-10-02T17:44:39.315Z,1349199879.315 [Default](INFO): Aggregate::uninitialize Default 2012-10-02T17:44:39.315Z,1349199879.315 [Default:Iridium] Stopped 2012-10-02T17:44:39.315Z,1349199879.316 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-10-02T17:44:39.316Z,1349199879.316 [Default:Iridium:A.SetSpeed] Stopped 2012-10-02T17:44:39.316Z,1349199879.316 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-10-02T17:44:39.316Z,1349199879.316 [Default:Iridium:Read_Iridium] Stopped 2012-10-02T17:44:39.316Z,1349199879.316 [Default:D.SetSpeed] Stopped 2012-10-02T17:44:39.316Z,1349199879.316 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-10-02T17:44:39.316Z,1349199879.316 [Default:E.GoToSurface] Stopped 2012-10-02T17:44:39.316Z,1349199879.316 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:44:39.316Z,1349199879.316 [MissionManager](IMPORTANT): Started mission science_to_67_70 2012-10-02T17:44:39.317Z,1349199879.316 [science_to_67_70] Running Loop=1 2012-10-02T17:44:39.317Z,1349199879.317 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70 2012-10-02T17:44:39.317Z,1349199879.317 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-10-02T17:44:39.317Z,1349199879.317 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science] Running Loop=1 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science:A] Running Loop=1 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science:B] Running Loop=1 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science:C] Running Loop=1 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science:D] Running Loop=1 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science:E] Running Loop=1 2012-10-02T17:44:39.318Z,1349199879.318 [science_to_67_70:Science:F] Running Loop=1 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:Science:G] Running Loop=1 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:H.SetSpeed](DEBUG): Initialize. 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-02T17:44:39.319Z,1349199879.319 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-10-02T17:44:39.320Z,1349199879.320 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-02T17:44:39.320Z,1349199879.320 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent. 2012-10-02T17:44:39.320Z,1349199879.320 [science_to_67_70:SURFACECOMMS] Running Loop=1 2012-10-02T17:44:39.321Z,1349199879.321 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS 2012-10-02T17:44:39.321Z,1349199879.321 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-10-02T17:44:39.321Z,1349199879.321 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:44:39.322Z,1349199879.322 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-02T17:44:39.327Z,1349199879.327 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-02T17:44:39.332Z,1349199879.332 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-02T17:44:39.337Z,1349199879.337 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-02T17:44:39.342Z,1349199879.342 [science_to_67_70:Science] Running Loop=1 2012-10-02T17:44:39.343Z,1349199879.342 [science_to_67_70:Science:PeakDetectChl] Running Loop=1 2012-10-02T17:44:39.343Z,1349199879.343 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl 2012-10-02T17:44:39.343Z,1349199879.343 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2012-10-02T17:44:39.343Z,1349199879.343 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize. 2012-10-02T17:44:39.349Z,1349199879.349 [science_to_67_70:Science:G] Running Loop=1 2012-10-02T17:44:39.350Z,1349199879.350 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2012-10-02T17:44:39.352Z,1349199879.352 [science_to_67_70:Science:F] Running Loop=1 2012-10-02T17:44:39.352Z,1349199879.352 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI 2012-10-02T17:44:39.354Z,1349199879.354 [science_to_67_70:Science:E] Running Loop=1 2012-10-02T17:44:39.354Z,1349199879.355 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity 2012-10-02T17:44:39.356Z,1349199879.356 [science_to_67_70:Science:D] Running Loop=1 2012-10-02T17:44:39.357Z,1349199879.357 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond 2012-10-02T17:44:39.358Z,1349199879.358 [science_to_67_70:Science:C] Running Loop=1 2012-10-02T17:44:39.359Z,1349199879.359 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-10-02T17:44:39.359Z,1349199879.359 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-10-02T17:44:39.361Z,1349199879.361 [science_to_67_70:Science:B] Running Loop=1 2012-10-02T17:44:39.362Z,1349199879.362 [science_to_67_70:Science:B](DEBUG): Initialize ReadDataComponent to sense mole_concentration_of_nitrate_in_sea_water 2012-10-02T17:44:39.368Z,1349199879.368 [science_to_67_70:SURFACECOMMS:B] Running Loop=1 2012-10-02T17:44:39.368Z,1349199879.368 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:OffshoreWrapper] Running Loop=1 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1 2012-10-02T17:44:39.369Z,1349199879.369 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-10-02T17:44:39.374Z,1349199879.374 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-10-02T17:44:39.387Z,1349199879.387 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-10-02T17:44:39.391Z,1349199879.391 [science_to_67_70:AltEnvWrapper] Running Loop=1 2012-10-02T17:44:39.391Z,1349199879.391 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper 2012-10-02T17:44:39.391Z,1349199879.391 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-10-02T17:44:39.391Z,1349199879.391 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-10-02T17:44:39.392Z,1349199879.392 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1 2012-10-02T17:44:39.392Z,1349199879.392 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-10-02T17:44:39.396Z,1349199879.397 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-10-02T17:44:39.440Z,1349199879.440 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2012-10-02T17:44:39.440Z,1349199879.441 [WetLabsBB2FL](INFO): Powering up 2012-10-02T17:44:39.453Z,1349199879.453 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-02T17:44:39.473Z,1349199879.473 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:44:39.473Z,1349199879.473 [DVL_micro](INFO): resume:Powering up 2012-10-02T17:44:39.474Z,1349199879.474 [DVL_micro](INFO): Cycling power to configure device. 2012-10-02T17:44:39.504Z,1349199879.504 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-02T17:44:40.313Z,1349199880.313 [science_to_67_70:J.YoYo] Preempted 2012-10-02T17:44:40.313Z,1349199880.313 [science_to_67_70:I.DepthEnvelope] Preempted 2012-10-02T17:44:40.313Z,1349199880.313 [science_to_67_70:H.SetSpeed] Preempted 2012-10-02T17:44:40.313Z,1349199880.313 [science_to_67_70:G.Buoyancy] Preempted 2012-10-02T17:44:40.313Z,1349199880.313 [science_to_67_70:Science] Preempted 2012-10-02T17:44:40.313Z,1349199880.313 [science_to_67_70:Science:B] Preempted 2012-10-02T17:44:40.314Z,1349199880.314 [science_to_67_70:Science:C] Preempted 2012-10-02T17:44:40.314Z,1349199880.314 [science_to_67_70:Science:D] Preempted 2012-10-02T17:44:40.314Z,1349199880.314 [science_to_67_70:Science:E] Preempted 2012-10-02T17:44:40.314Z,1349199880.314 [science_to_67_70:Science:F] Preempted 2012-10-02T17:44:40.314Z,1349199880.314 [science_to_67_70:Science:G] Preempted 2012-10-02T17:44:40.315Z,1349199880.315 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-02T17:44:40.317Z,1349199880.316 [science_to_67_70:SURFACECOMMS:B:A] Stopped 2012-10-02T17:44:40.317Z,1349199880.317 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1 2012-10-02T17:44:40.556Z,1349199880.556 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-10-02T17:44:40.581Z,1349199880.581 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-02T17:44:40.688Z,1349199880.688 [WetLabsBB2FL](INFO): Powering down 2012-10-02T17:44:41.480Z,1349199881.480 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:44:41.481Z,1349199881.481 [DVL_micro](INFO): Querying output modes 2012-10-02T17:44:41.481Z,1349199881.481 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-02T17:44:41.488Z,1349199881.488 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-02T17:44:41.488Z,1349199881.488 [DVL_micro](INFO): NQ1 output enabled 2012-10-02T17:44:41.488Z,1349199881.488 [DVL_micro](INFO): RSSI output enabled 2012-10-02T17:44:41.488Z,1349199881.489 [DVL_micro](INFO): ADCP output enabled 2012-10-02T17:44:41.489Z,1349199881.489 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-02T17:44:41.496Z,1349199881.497 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-02T17:44:41.498Z,1349199881.499 [DVL_micro](INFO): RSSI unrequested 2012-10-02T17:44:41.499Z,1349199881.499 [DVL_micro](INFO): ADCP unrequested 2012-10-02T17:44:41.499Z,1349199881.499 [DVL_micro](INFO): Cycling power to configure device. 2012-10-02T17:44:43.508Z,1349199883.508 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:44:43.509Z,1349199883.509 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-02T17:44:43.528Z,1349199883.528 [DVL_micro](DEBUG): cmdResponse: }uA%?5FtQ5Whq@.zi 2012-10-02T17:44:43.528Z,1349199883.528 [DVL_micro](INFO): Enabling NQ1 output 2012-10-02T17:44:43.528Z,1349199883.528 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-02T17:44:43.540Z,1349199883.540 [DVL_micro](DEBUG): cmdResponse: yz{njfnQ_hnLNRuraUvy 2012-10-02T17:44:43.540Z,1349199883.540 [DVL_micro](INFO): Querying output modes 2012-10-02T17:44:43.540Z,1349199883.540 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-02T17:44:43.552Z,1349199883.552 [DVL_micro](DEBUG): cmdResponse: lqtp|WhZJCgqbILdqS6ZM\Tfl 2012-10-02T17:44:43.552Z,1349199883.552 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-02T17:44:43.564Z,1349199883.564 [DVL_micro](DEBUG): cmdResponse: {whuqhZe]dfVmwXGtrniqmbjexzw_TSh 2012-10-02T17:44:44.117Z,1349199884.117 [DVL_micro](INFO): NQ1 requested 2012-10-02T17:44:44.117Z,1349199884.117 [DVL_micro](INFO): Cycling power to configure device. 2012-10-02T17:44:44.929Z,1349199884.929 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-10-02T17:44:46.124Z,1349199886.124 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:44:46.125Z,1349199886.125 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-02T17:44:46.332Z,1349199886.332 [DVL_micro](DEBUG): cmdResponse: 00 2012-10-02T17:44:46.332Z,1349199886.332 [DVL_micro](INFO): Enabling NQ1 output 2012-10-02T17:44:46.332Z,1349199886.332 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-02T17:44:46.524Z,1349199886.524 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-02T17:44:46.524Z,1349199886.524 [DVL_micro](INFO): Querying output modes 2012-10-02T17:44:46.524Z,1349199886.524 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-02T17:44:46.528Z,1349199886.528 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-02T17:44:46.528Z,1349199886.528 [DVL_micro](INFO): NQ1 output enabled 2012-10-02T17:44:46.528Z,1349199886.528 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-02T17:44:46.532Z,1349199886.532 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-02T17:44:46.737Z,1349199886.737 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-02T17:44:47.041Z,1349199887.041 [science_to_67_70:SURFACECOMMS:B:B] Stopped 2012-10-02T17:44:47.041Z,1349199887.041 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1 2012-10-02T17:44:47.296Z,1349199887.296 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-10-02T17:44:47.315Z,1349199887.315 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-02T17:44:47.317Z,1349199887.317 [science_to_67_70:SURFACECOMMS:B:C] Stopped 2012-10-02T17:44:47.317Z,1349199887.317 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B 2012-10-02T17:44:47.317Z,1349199887.317 [science_to_67_70:SURFACECOMMS:B] Stopped 2012-10-02T17:44:47.317Z,1349199887.317 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B 2012-10-02T17:44:47.318Z,1349199887.318 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS 2012-10-02T17:44:47.318Z,1349199887.318 [science_to_67_70:SURFACECOMMS] Stopped 2012-10-02T17:44:47.318Z,1349199887.318 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS 2012-10-02T17:44:47.318Z,1349199887.318 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped 2012-10-02T17:44:47.318Z,1349199887.318 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:44:47.319Z,1349199887.319 [science_to_67_70:Wpt.Point] Running Loop=1 2012-10-02T17:44:47.319Z,1349199887.319 [science_to_67_70:Wpt.Point](DEBUG): Initialize. 2012-10-02T17:44:47.722Z,1349199887.722 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-02T17:44:47.723Z,1349199887.723 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-02T17:44:47.723Z,1349199887.723 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-02T17:44:47.724Z,1349199887.724 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-02T17:44:47.724Z,1349199887.724 [science_to_67_70:Science] Running Loop=1 2012-10-02T17:44:47.725Z,1349199887.725 [science_to_67_70:Science:G] Running Loop=1 2012-10-02T17:44:47.725Z,1349199887.725 [science_to_67_70:Science:F] Running Loop=1 2012-10-02T17:44:47.725Z,1349199887.726 [science_to_67_70:Science:E] Running Loop=1 2012-10-02T17:44:47.726Z,1349199887.726 [science_to_67_70:Science:D] Running Loop=1 2012-10-02T17:44:47.726Z,1349199887.726 [science_to_67_70:Science:C] Running Loop=1 2012-10-02T17:44:47.726Z,1349199887.726 [science_to_67_70:Science:B] Running Loop=1 2012-10-02T17:44:47.961Z,1349199887.961 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2012-10-02T17:44:47.962Z,1349199887.962 [WetLabsBB2FL](INFO): Powering up 2012-10-02T17:44:48.005Z,1349199888.005 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-02T17:44:48.109Z,1349199888.109 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-02T17:44:48.601Z,1349199888.601 [DVL_micro](INFO): RSSI requested 2012-10-02T17:44:48.601Z,1349199888.601 [DVL_micro](INFO): ADCP requested 2012-10-02T17:44:48.601Z,1349199888.601 [DVL_micro](INFO): Cycling power to configure device. 2012-10-02T17:44:49.738Z,1349199889.738 [CTD_NeilBrown](ERROR): Salinity reading out of range: -12.181390 psu 2012-10-02T17:44:49.948Z,1349199889.948 [CTD_NeilBrown](ERROR): Salinity reading out of range: 3.987770 psu 2012-10-02T17:44:50.153Z,1349199890.153 [CTD_NeilBrown](ERROR): Salinity reading out of range: 10.191560 psu 2012-10-02T17:44:50.310Z,1349199890.309 [CTD_NeilBrown](ERROR): Salinity reading out of range: 10.195590 psu 2012-10-02T17:44:50.310Z,1349199890.310 [CTD_NeilBrown](ERROR): Pressure reading out of range: -13.760000 decibar 2012-10-02T17:44:50.525Z,1349199890.525 [CTD_NeilBrown](ERROR): Salinity reading out of range: -9.082520 psu 2012-10-02T17:44:50.614Z,1349199890.614 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-02T17:44:50.614Z,1349199890.614 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-02T17:44:50.733Z,1349199890.733 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.508070 psu 2012-10-02T17:44:50.811Z,1349199890.811 [DVL_micro](DEBUG): cmdResponse: 00 2012-10-02T17:44:50.811Z,1349199890.811 [DVL_micro](INFO): Enabling RSSI output 2012-10-02T17:44:50.812Z,1349199890.812 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7 2012-10-02T17:44:50.943Z,1349199890.943 [CTD_NeilBrown](ERROR): Salinity reading out of range: 38.741772 psu 2012-10-02T17:44:51.004Z,1349199891.004 [DVL_micro](DEBUG): cmdResponse: 16 2012-10-02T17:44:51.005Z,1349199891.005 [DVL_micro](INFO): Enabling ADCP output 2012-10-02T17:44:51.005Z,1349199891.005 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3 2012-10-02T17:44:51.214Z,1349199891.214 [DVL_micro](DEBUG): cmdResponse: 03 16 2012-10-02T17:44:51.215Z,1349199891.215 [DVL_micro](INFO): Enabling NQ1 output 2012-10-02T17:44:51.215Z,1349199891.215 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-02T17:44:51.357Z,1349199891.357 [CTD_NeilBrown](ERROR): Salinity reading out of range: -5253.051270 psu 2012-10-02T17:44:51.397Z,1349199891.397 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-02T17:44:51.397Z,1349199891.397 [DVL_micro](INFO): Querying output modes 2012-10-02T17:44:51.397Z,1349199891.397 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-02T17:44:51.405Z,1349199891.405 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-02T17:44:51.406Z,1349199891.406 [DVL_micro](INFO): NQ1 output enabled 2012-10-02T17:44:51.406Z,1349199891.406 [DVL_micro](INFO): RSSI output enabled 2012-10-02T17:44:51.406Z,1349199891.406 [DVL_micro](INFO): ADCP output enabled 2012-10-02T17:44:51.406Z,1349199891.406 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-02T17:44:51.413Z,1349199891.413 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-02T17:44:51.549Z,1349199891.549 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2012-10-02T17:44:51.567Z,1349199891.566 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.210079 psu 2012-10-02T17:44:51.606Z,1349199891.606 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-02T17:44:51.657Z,1349199891.657 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2012-10-02T17:44:51.710Z,1349199891.710 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-02T17:44:51.773Z,1349199891.773 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1001.483398 psu 2012-10-02T17:44:51.981Z,1349199891.981 [CTD_NeilBrown](ERROR): Salinity reading out of range: 44.875549 psu 2012-10-02T17:44:51.992Z,1349199891.992 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-02T17:44:52.036Z,1349199892.036 [Reporter](INFO): DVL_micro.RSSIPoints 310 count 2012-10-02T17:44:52.093Z,1349199892.093 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-02T17:44:52.193Z,1349199892.193 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-02T17:44:52.197Z,1349199892.197 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.121361 psu 2012-10-02T17:44:52.297Z,1349199892.297 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-02T17:44:52.398Z,1349199892.398 [CTD_NeilBrown](ERROR): Salinity reading out of range: 5.544810 psu 2012-10-02T17:44:52.433Z,1349199892.433 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2012-10-02T17:44:52.489Z,1349199892.489 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-02T17:44:52.519Z,1349199892.519 [Reporter](INFO): DVL_micro.RSSIPoints 590 count 2012-10-02T17:44:52.545Z,1349199892.545 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2012-10-02T17:44:52.597Z,1349199892.597 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-02T17:44:52.605Z,1349199892.605 [CTD_NeilBrown](ERROR): Salinity reading out of range: 58.059719 psu 2012-10-02T17:44:52.798Z,1349199892.798 [NAL9602](INFO): Powering down 2012-10-02T17:44:52.809Z,1349199892.809 [CTD_NeilBrown](ERROR): Salinity reading out of range: -80.988998 psu 2012-10-02T17:44:52.877Z,1349199892.877 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-02T17:44:52.932Z,1349199892.932 [Reporter](INFO): DVL_micro.RSSIPoints 310 count 2012-10-02T17:44:52.981Z,1349199892.981 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-02T17:44:53.081Z,1349199893.081 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-02T17:44:53.186Z,1349199893.186 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-02T17:44:53.225Z,1349199893.225 [CTD_NeilBrown](ERROR): Salinity reading out of range: 90.668221 psu 2012-10-02T17:44:53.403Z,1349199893.403 [Reporter](INFO): DVL_micro.RSSIPoints 590 count 2012-10-02T17:44:53.524Z,1349199893.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.512760 psu 2012-10-02T17:44:53.536Z,1349199893.536 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2012-10-02T17:44:53.681Z,1349199893.681 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2012-10-02T17:44:53.729Z,1349199893.729 [CTD_NeilBrown](ERROR): Salinity reading out of range: -14.922040 psu 2012-10-02T17:44:53.825Z,1349199893.825 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32 2012-10-02T17:44:53.938Z,1349199893.938 [CTD_NeilBrown](ERROR): Salinity reading out of range: 58.746521 psu 2012-10-02T17:44:53.969Z,1349199893.969 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-02T17:44:54.149Z,1349199894.149 [CTD_NeilBrown](ERROR): Salinity reading out of range: 13.908910 psu 2012-10-02T17:44:54.292Z,1349199894.293 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-02T17:44:54.329Z,1349199894.329 [Reporter](INFO): DVL_micro.RSSIPoints 830 count 2012-10-02T17:44:54.354Z,1349199894.354 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.524549 psu 2012-10-02T17:44:54.405Z,1349199894.405 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-02T17:44:54.525Z,1349199894.526 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-02T17:44:54.561Z,1349199894.561 [CTD_NeilBrown](ERROR): Salinity reading out of range: -3457.709229 psu 2012-10-02T17:44:54.641Z,1349199894.641 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-02T17:44:54.712Z,1349199894.712 [ElevatorServo](FAULT): Overload Error 2012-10-02T17:44:54.712Z,1349199894.712 [ElevatorServo] Hardware Fault, FailCount= 1 2012-10-02T17:44:54.712Z,1349199894.713 [ElevatorServo](ERROR): Hardware Fault 2012-10-02T17:44:54.769Z,1349199894.769 [CTD_NeilBrown](ERROR): Salinity reading out of range: 51.146511 psu 2012-10-02T17:44:54.803Z,1349199894.803 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2012-10-02T17:44:54.805Z,1349199894.805 [Reporter](INFO): DVL_micro.RSSIPoints 668 count 2012-10-02T17:44:54.981Z,1349199894.981 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.832069 psu 2012-10-02T17:44:55.152Z,1349199895.152 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2012-10-02T17:44:55.153Z,1349199895.153 [ElevatorServo](INFO): Powering down 2012-10-02T17:44:55.165Z,1349199895.165 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28 2012-10-02T17:44:55.191Z,1349199895.191 [CTD_NeilBrown](ERROR): Salinity reading out of range: 27.725451 psu 2012-10-02T17:44:55.212Z,1349199895.212 [CBIT](INFO): Clearing failed state for component ElevatorServo 2012-10-02T17:44:55.213Z,1349199895.213 [ElevatorServo] No Fault, FailCount= 1 2012-10-02T17:44:55.393Z,1349199895.393 [CTD_NeilBrown](ERROR): Salinity reading out of range: 43.478588 psu 2012-10-02T17:44:55.400Z,1349199895.400 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-02T17:44:55.602Z,1349199895.602 [CTD_NeilBrown](ERROR): Salinity reading out of range: 70.842392 psu 2012-10-02T17:44:55.637Z,1349199895.637 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12 2012-10-02T17:44:55.809Z,1349199895.809 [CTD_NeilBrown](ERROR): Salinity reading out of range: 69.027527 psu 2012-10-02T17:44:55.873Z,1349199895.873 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-02T17:44:56.013Z,1349199896.013 [CTD_NeilBrown](ERROR): Salinity reading out of range: -16.148529 psu 2012-10-02T17:44:56.198Z,1349199896.198 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-10-02T17:44:56.325Z,1349199896.325 [CTD_NeilBrown](ERROR): Salinity reading out of range: -923.272522 psu 2012-10-02T17:44:56.327Z,1349199896.327 [ElevatorServo](DEBUG): Initialization error opening port: port not yet opened 2012-10-02T17:44:56.327Z,1349199896.327 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-10-02T17:44:56.363Z,1349199896.363 [Reporter](INFO): DVL_micro.RSSIPoints 1356 count 2012-10-02T17:44:56.529Z,1349199896.529 [CTD_NeilBrown](ERROR): Salinity reading out of range: 65.435738 psu 2012-10-02T17:44:56.743Z,1349199896.743 [CTD_NeilBrown](ERROR): Salinity reading out of range: 183.672073 psu 2012-10-02T17:44:56.797Z,1349199896.797 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=29 2012-10-02T17:44:56.945Z,1349199896.945 [CTD_NeilBrown](ERROR): Salinity reading out of range: 14.812060 psu 2012-10-02T17:44:57.153Z,1349199897.153 [CTD_NeilBrown](ERROR): Salinity reading out of range: -297.127258 psu 2012-10-02T17:44:57.289Z,1349199897.289 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5 2012-10-02T17:44:57.361Z,1349199897.362 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1139.356201 psu 2012-10-02T17:44:57.570Z,1349199897.570 [CTD_NeilBrown](ERROR): Salinity reading out of range: 99.031761 psu 2012-10-02T17:44:57.745Z,1349199897.745 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=20 2012-10-02T17:44:57.779Z,1349199897.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.684429 psu 2012-10-02T17:44:57.993Z,1349199897.993 [CTD_NeilBrown](ERROR): Salinity reading out of range: 1685.166260 psu 2012-10-02T17:44:58.201Z,1349199898.201 [CTD_NeilBrown](ERROR): Salinity reading out of range: 90.346123 psu 2012-10-02T17:44:58.217Z,1349199898.217 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-02T17:44:58.394Z,1349199898.394 [Reporter](INFO): DVL_micro.RSSIPoints 2716 count 2012-10-02T17:44:58.409Z,1349199898.409 [CTD_NeilBrown](ERROR): Salinity reading out of range: 112.430389 psu 2012-10-02T17:44:58.717Z,1349199898.717 [CTD_NeilBrown](ERROR): Salinity reading out of range: 57.798271 psu 2012-10-02T17:44:58.927Z,1349199898.927 [CTD_NeilBrown](ERROR): Salinity reading out of range: -350.158691 psu 2012-10-02T17:44:59.101Z,1349199899.101 [DVL_micro](DEBUG): NQ1 2012-10-02T17:44:59.138Z,1349199899.138 [DVL_micro](DEBUG): ADCP 2012-10-02T17:44:59.150Z,1349199899.150 [CTD_NeilBrown](ERROR): Salinity reading out of range: 59.815842 psu 2012-10-02T17:44:59.338Z,1349199899.338 [CTD_NeilBrown](ERROR): Salinity reading out of range: 74.114761 psu 2012-10-02T17:44:59.547Z,1349199899.547 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.474430 psu 2012-10-02T17:44:59.757Z,1349199899.757 [CTD_NeilBrown](ERROR): Salinity reading out of range: 42.254829 psu 2012-10-02T17:44:59.958Z,1349199899.958 [CTD_NeilBrown](ERROR): Salinity reading out of range: -75.450737 psu 2012-10-02T17:44:59.966Z,1349199899.966 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=45 2012-10-02T17:45:00.162Z,1349199900.162 [CTD_NeilBrown](ERROR): Salinity reading out of range: -90.489738 psu 2012-10-02T17:45:00.369Z,1349199900.369 [CTD_NeilBrown](ERROR): Salinity reading out of range: -158.181671 psu 2012-10-02T17:45:00.577Z,1349199900.577 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.787540 psu 2012-10-02T17:45:00.785Z,1349199900.785 [CTD_NeilBrown](ERROR): Salinity reading out of range: 72.237350 psu 2012-10-02T17:45:00.801Z,1349199900.801 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=22 2012-10-02T17:45:00.993Z,1349199900.993 [CTD_NeilBrown](ERROR): Salinity reading out of range: 84.049728 psu 2012-10-02T17:45:01.197Z,1349199901.197 [CTD_NeilBrown](ERROR): Salinity reading out of range: -134.939484 psu 2012-10-02T17:45:01.228Z,1349199901.228 [ElevatorServo](FAULT): Overload Error 2012-10-02T17:45:01.229Z,1349199901.229 [ElevatorServo] Hardware Fault, FailCount= 2 2012-10-02T17:45:01.229Z,1349199901.229 [ElevatorServo](ERROR): Hardware Fault 2012-10-02T17:45:01.321Z,1349199901.321 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2012-10-02T17:45:01.406Z,1349199901.406 [CTD_NeilBrown](ERROR): Salinity reading out of range: 72.134148 psu 2012-10-02T17:45:01.645Z,1349199901.645 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=94 2012-10-02T17:45:01.713Z,1349199901.713 [CTD_NeilBrown](ERROR): Salinity reading out of range: 11.196140 psu 2012-10-02T17:45:01.862Z,1349199901.862 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2012-10-02T17:45:01.862Z,1349199901.862 [ElevatorServo](INFO): Powering down 2012-10-02T17:45:01.925Z,1349199901.925 [CTD_NeilBrown](ERROR): Salinity reading out of range: 640.843140 psu 2012-10-02T17:45:02.141Z,1349199902.141 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.944349 psu 2012-10-02T17:45:02.341Z,1349199902.341 [CTD_NeilBrown](ERROR): Salinity reading out of range: 8.307690 psu 2012-10-02T17:45:02.447Z,1349199902.447 [CBIT](INFO): Clearing failed state for component ElevatorServo 2012-10-02T17:45:02.447Z,1349199902.447 [ElevatorServo] No Fault, FailCount= 2 2012-10-02T17:45:02.473Z,1349199902.473 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-02T17:45:02.545Z,1349199902.545 [CTD_NeilBrown](ERROR): Salinity reading out of range: -5.707560 psu 2012-10-02T17:45:02.646Z,1349199902.646 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=392 2012-10-02T17:45:02.667Z,1349199902.667 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-02T17:45:02.725Z,1349199902.726 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2012-10-02T17:45:02.749Z,1349199902.750 [CTD_NeilBrown](ERROR): Salinity reading out of range: 38.629021 psu 2012-10-02T17:45:02.773Z,1349199902.773 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-02T17:45:02.953Z,1349199902.953 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.656630 psu 2012-10-02T17:45:03.053Z,1349199903.053 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-02T17:45:03.157Z,1349199903.157 [CTD_NeilBrown](ERROR): Salinity reading out of range: 2.636030 psu 2012-10-02T17:45:03.160Z,1349199903.160 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2012-10-02T17:45:03.257Z,1349199903.257 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-02T17:45:03.466Z,1349199903.466 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=292 2012-10-02T17:45:03.501Z,1349199903.501 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72 2012-10-02T17:45:03.506Z,1349199903.506 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.201540 psu 2012-10-02T17:45:03.553Z,1349199903.553 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-02T17:45:03.573Z,1349199903.573 [CTD_NeilBrown](ERROR): Salinity reading out of range: -199.419983 psu 2012-10-02T17:45:03.605Z,1349199903.605 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2012-10-02T17:45:03.657Z,1349199903.657 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-02T17:45:03.897Z,1349199903.898 [CTD_NeilBrown](ERROR): Salinity reading out of range: -31.243830 psu 2012-10-02T17:45:03.945Z,1349199903.945 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-02T17:45:03.982Z,1349199903.982 [Reporter](INFO): DVL_micro.RSSIPoints 310 count 2012-10-02T17:45:03.993Z,1349199903.993 [CTD_NeilBrown](ERROR): Salinity reading out of range: -172.810135 psu 2012-10-02T17:45:04.049Z,1349199904.049 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2012-10-02T17:45:04.274Z,1349199904.274 [CTD_NeilBrown](ERROR): Salinity reading out of range: -242.372345 psu 2012-10-02T17:45:04.283Z,1349199904.283 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=1184 2012-10-02T17:45:04.287Z,1349199904.287 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-02T17:45:04.397Z,1349199904.397 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.676670 psu 2012-10-02T17:45:04.594Z,1349199904.594 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-10-02T17:45:04.597Z,1349199904.597 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2012-10-02T17:45:04.605Z,1349199904.605 [CTD_NeilBrown](ERROR): Salinity reading out of range: 60.924801 psu 2012-10-02T17:45:04.714Z,1349199904.714 [ElevatorServo](DEBUG): Initialization error opening port: port not yet opened 2012-10-02T17:45:04.714Z,1349199904.714 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-10-02T17:45:04.885Z,1349199904.885 [DVL_micro](DEBUG): RSSI read bytes: 1528 of 1660, #buffered=0 2012-10-02T17:45:04.898Z,1349199904.898 [Reporter](INFO): DVL_micro.RSSIPoints 590 count 2012-10-02T17:45:04.905Z,1349199904.905 [CTD_NeilBrown](ERROR): Salinity reading out of range: -95.091103 psu 2012-10-02T17:45:05.038Z,1349199905.037 [DVL_micro](DEBUG): RSSI read bytes: 132 of 132, #buffered=3260 2012-10-02T17:45:05.050Z,1349199905.050 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=1600 2012-10-02T17:45:05.081Z,1349199905.081 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-02T17:45:05.113Z,1349199905.113 [CTD_NeilBrown](ERROR): Salinity reading out of range: 46.238708 psu 2012-10-02T17:45:05.317Z,1349199905.318 [CTD_NeilBrown](ERROR): Salinity reading out of range: 43.188831 psu 2012-10-02T17:45:05.353Z,1349199905.353 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-02T17:45:05.469Z,1349199905.469 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-02T17:45:05.531Z,1349199905.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: 89.995537 psu 2012-10-02T17:45:05.585Z,1349199905.585 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-02T17:45:05.853Z,1349199905.853 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-02T17:45:05.995Z,1349199905.995 [CTD_NeilBrown](ERROR): Salinity reading out of range: 56.522968 psu 2012-10-02T17:45:05.998Z,1349199905.998 [CTD_NeilBrown](ERROR): Salinity reading out of range: 404.796448 psu 2012-10-02T17:45:06.418Z,1349199906.418 [CTD_NeilBrown](ERROR): Bad response: 04 0.04 2012-10-02T17:45:06.426Z,1349199906.426 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=2236 2012-10-02T17:45:06.435Z,1349199906.435 [Reporter](INFO): DVL_micro.RSSIPoints 668 count 2012-10-02T17:45:06.471Z,1349199906.471 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=68 2012-10-02T17:45:06.573Z,1349199906.573 [CTD_NeilBrown](ERROR): Salinity reading out of range: 672.819092 psu 2012-10-02T17:45:06.701Z,1349199906.701 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12 2012-10-02T17:45:06.781Z,1349199906.781 [CTD_NeilBrown](ERROR): Salinity reading out of range: 48.142601 psu 2012-10-02T17:45:06.937Z,1349199906.937 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-02T17:45:07.198Z,1349199907.198 [CTD_NeilBrown](ERROR): Salinity reading out of range: 114.067802 psu 2012-10-02T17:45:07.394Z,1349199907.394 [Reporter](INFO): DVL_micro.RSSIPoints 1356 count 2012-10-02T17:45:07.710Z,1349199907.710 [CTD_NeilBrown](ERROR): Salinity reading out of range: -107.914368 psu 2012-10-02T17:45:07.914Z,1349199907.914 [CTD_NeilBrown](ERROR): Salinity reading out of range: -62.028992 psu 2012-10-02T17:45:07.925Z,1349199907.925 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=799 2012-10-02T17:45:08.121Z,1349199908.121 [CTD_NeilBrown](ERROR): Salinity reading out of range: -251.902863 psu 2012-10-02T17:45:08.366Z,1349199908.366 [CTD_NeilBrown](ERROR): Salinity reading out of range: 13.256340 psu 2012-10-02T17:45:08.409Z,1349199908.409 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=904 2012-10-02T17:45:08.537Z,1349199908.537 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1035.275146 psu 2012-10-02T17:45:08.745Z,1349199908.745 [CTD_NeilBrown](ERROR): Salinity reading out of range: 27.020309 psu 2012-10-02T17:45:08.805Z,1349199908.805 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=17 2012-10-02T17:45:08.949Z,1349199908.949 [CTD_NeilBrown](ERROR): Salinity reading out of range: -18.488279 psu 2012-10-02T17:45:09.281Z,1349199909.281 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-02T17:45:09.392Z,1349199909.392 [CTD_NeilBrown](ERROR): Salinity reading out of range: 62.954250 psu 2012-10-02T17:45:09.395Z,1349199909.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: 53.081230 psu 2012-10-02T17:45:09.570Z,1349199909.570 [CTD_NeilBrown](ERROR): Salinity reading out of range: 39.781601 psu 2012-10-02T17:45:09.611Z,1349199909.611 [Reporter](INFO): DVL_micro.RSSIPoints 2716 count 2012-10-02T17:45:09.774Z,1349199909.774 [CTD_NeilBrown](ERROR): Salinity reading out of range: -49.622292 psu 2012-10-02T17:45:09.981Z,1349199909.981 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.950630 psu 2012-10-02T17:45:10.157Z,1349199910.158 [DVL_micro](DEBUG): NQ1 2012-10-02T17:45:10.189Z,1349199910.189 [CTD_NeilBrown](ERROR): Salinity reading out of range: 49.156551 psu 2012-10-02T17:45:10.206Z,1349199910.206 [DVL_micro](DEBUG): ADCP 2012-10-02T17:45:10.494Z,1349199910.494 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.492950 psu 2012-10-02T17:45:10.705Z,1349199910.705 [CTD_NeilBrown](ERROR): Salinity reading out of range: -433.907806 psu 2012-10-02T17:45:10.906Z,1349199910.906 [CTD_NeilBrown](ERROR): Salinity reading out of range: -4.251680 psu 2012-10-02T17:45:11.017Z,1349199911.017 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13 2012-10-02T17:45:11.109Z,1349199911.109 [CTD_NeilBrown](ERROR): Salinity reading out of range: -53.151939 psu 2012-10-02T17:45:11.317Z,1349199911.317 [CTD_NeilBrown](ERROR): Salinity reading out of range: 40.831188 psu 2012-10-02T17:45:11.526Z,1349199911.526 [CTD_NeilBrown](ERROR): Salinity reading out of range: 230.901413 psu 2012-10-02T17:45:11.730Z,1349199911.730 [CTD_NeilBrown](ERROR): Salinity reading out of range: -12.333220 psu 2012-10-02T17:45:11.857Z,1349199911.857 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=59 2012-10-02T17:45:11.943Z,1349199911.943 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.911060 psu 2012-10-02T17:45:12.357Z,1349199912.357 [CTD_NeilBrown](ERROR): Salinity reading out of range: 96.925140 psu 2012-10-02T17:45:12.565Z,1349199912.565 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2692.258545 psu 2012-10-02T17:45:12.841Z,1349199912.841 [CTD_NeilBrown](ERROR): Salinity reading out of range: 162.646454 psu 2012-10-02T17:45:12.890Z,1349199912.890 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=2279 2012-10-02T17:45:12.985Z,1349199912.985 [CTD_NeilBrown](ERROR): Salinity reading out of range: 77.543068 psu 2012-10-02T17:45:13.276Z,1349199913.276 [ElevatorServo](FAULT): Overload Error 2012-10-02T17:45:13.277Z,1349199913.277 [ElevatorServo] Hardware Fault, FailCount= 3 2012-10-02T17:45:13.277Z,1349199913.277 [ElevatorServo](ERROR): Hardware Fault 2012-10-02T17:45:13.313Z,1349199913.313 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.173559 psu 2012-10-02T17:45:13.359Z,1349199913.359 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2012-10-02T17:45:13.359Z,1349199913.359 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2012-10-02T17:45:13.497Z,1349199913.497 [CTD_NeilBrown](ERROR): Salinity reading out of range: -17.808599 psu 2012-10-02T17:45:13.529Z,1349199913.529 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-02T17:45:13.665Z,1349199913.665 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2012-10-02T17:45:13.702Z,1349199913.702 [CTD_NeilBrown](ERROR): Salinity reading out of range: -22.935190 psu 2012-10-02T17:45:13.721Z,1349199913.721 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-02T17:45:13.777Z,1349199913.777 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=80 2012-10-02T17:45:13.825Z,1349199913.825 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-02T17:45:13.913Z,1349199913.912 [CTD_NeilBrown](ERROR): Salinity reading out of range: 7.379560 psu 2012-10-02T17:45:14.109Z,1349199914.109 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2012-10-02T17:45:14.121Z,1349199914.121 [CTD_NeilBrown](ERROR): Salinity reading out of range: 685.719910 psu 2012-10-02T17:45:14.400Z,1349199914.401 [CTD_NeilBrown](ERROR): Salinity reading out of range: 5.458450 psu 2012-10-02T17:45:14.425Z,1349199914.425 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=2364 2012-10-02T17:45:14.433Z,1349199914.433 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=1184 2012-10-02T17:45:14.441Z,1349199914.441 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-02T17:45:14.533Z,1349199914.533 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.994280 psu 2012-10-02T17:45:14.549Z,1349199914.549 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=9 2012-10-02T17:45:14.605Z,1349199914.605 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=30 2012-10-02T17:45:14.825Z,1349199914.825 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2012-10-02T17:45:14.825Z,1349199914.825 [ElevatorServo](INFO): Powering down 2012-10-02T17:45:14.851Z,1349199914.851 [CTD_NeilBrown](ERROR): Salinity reading out of range: -41.242619 psu 2012-10-02T17:45:14.909Z,1349199914.909 [Reporter](INFO): DVL_micro.RSSIPoints 590 count 2012-10-02T17:45:14.945Z,1349199914.945 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.680849 psu 2012-10-02T17:45:14.954Z,1349199914.954 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1360 2012-10-02T17:45:14.975Z,1349199914.975 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=997 2012-10-02T17:45:15.017Z,1349199915.017 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=282 2012-10-02T17:45:15.093Z,1349199915.093 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=37 2012-10-02T17:45:15.146Z,1349199915.145 [CTD_NeilBrown](ERROR): Salinity reading out of range: -14.415680 psu 2012-10-02T17:45:15.193Z,1349199915.193 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=10 2012-10-02T17:45:15.297Z,1349199915.297 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-02T17:45:15.349Z,1349199915.349 [CTD_NeilBrown](ERROR): Salinity reading out of range: -274.166046 psu 2012-10-02T17:45:15.557Z,1349199915.557 [CTD_NeilBrown](ERROR): Salinity reading out of range: 48.693371 psu 2012-10-02T17:45:15.645Z,1349199915.645 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2012-10-02T17:45:15.773Z,1349199915.773 [CTD_NeilBrown](ERROR): Salinity reading out of range: 674.720581 psu 2012-10-02T17:45:16.058Z,1349199916.058 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=3068 2012-10-02T17:45:16.071Z,1349199916.071 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=1536 2012-10-02T17:45:16.093Z,1349199916.093 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-02T17:45:16.324Z,1349199916.324 [science_to_67_70] Stopped 2012-10-02T17:45:16.337Z,1349199916.337 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70 2012-10-02T17:45:16.337Z,1349199916.337 [science_to_67_70:AltEnvWrapper] Stopped 2012-10-02T17:45:16.337Z,1349199916.337 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper 2012-10-02T17:45:16.337Z,1349199916.337 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped 2012-10-02T17:45:16.337Z,1349199916.337 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-10-02T17:45:16.338Z,1349199916.338 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped 2012-10-02T17:45:16.338Z,1349199916.338 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-10-02T17:45:16.338Z,1349199916.338 [science_to_67_70:B.DepthEnvelope] Stopped 2012-10-02T17:45:16.338Z,1349199916.338 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize. 2012-10-02T17:45:16.338Z,1349199916.338 [science_to_67_70:OffshoreWrapper] Stopped 2012-10-02T17:45:16.338Z,1349199916.338 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper 2012-10-02T17:45:16.338Z,1349199916.339 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science:A] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science:B] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science:C] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science:D] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science:E] Stopped 2012-10-02T17:45:16.339Z,1349199916.339 [science_to_67_70:Science:F] Stopped 2012-10-02T17:45:16.340Z,1349199916.339 [science_to_67_70:Science:G] Stopped 2012-10-02T17:45:16.340Z,1349199916.339 [science_to_67_70:Science:PeakDetectChl] Stopped 2012-10-02T17:45:16.349Z,1349199916.349 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl 2012-10-02T17:45:16.349Z,1349199916.349 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2012-10-02T17:45:16.349Z,1349199916.349 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2012-10-02T17:45:16.350Z,1349199916.349 [science_to_67_70:G.Buoyancy] Stopped 2012-10-02T17:45:16.350Z,1349199916.349 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:H.SetSpeed] Stopped 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize. 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:I.DepthEnvelope] Stopped 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize. 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:J.YoYo] Stopped 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent. 2012-10-02T17:45:16.350Z,1349199916.350 [science_to_67_70:Wpt.Point] Stopped 2012-10-02T17:45:16.401Z,1349199916.401 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=29 2012-10-02T17:45:16.449Z,1349199916.449 [WetLabsBB2FL](INFO): Powering down 2012-10-02T17:45:16.485Z,1349199916.485 [CTD_NeilBrown](ERROR): Salinity reading out of range: -58.079731 psu 2012-10-02T17:45:16.517Z,1349199916.517 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=6 2012-10-02T17:45:16.595Z,1349199916.595 [Reporter](INFO): DVL_micro.RSSIPoints 830 count 2012-10-02T17:45:16.633Z,1349199916.633 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=14 2012-10-02T17:45:16.749Z,1349199916.749 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-02T17:45:16.756Z,1349199916.756 [DVL_micro](INFO): pause:Powering down 2012-10-02T17:45:17.930Z,1349199917.930 [MissionManager](IMPORTANT): Started mission Default 2012-10-02T17:45:17.930Z,1349199917.930 [Default] Running Loop=1 2012-10-02T17:45:17.930Z,1349199917.930 [Default](INFO): Aggregate::initialize Default 2012-10-02T17:45:17.930Z,1349199917.930 [Default:D.SetSpeed] Running Loop=1 2012-10-02T17:45:17.930Z,1349199917.930 [Default:D.SetSpeed](DEBUG): Initialize. 2012-10-02T17:45:17.930Z,1349199917.930 [Default:E.GoToSurface] Running Loop=1 2012-10-02T17:45:17.930Z,1349199917.930 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:45:17.930Z,1349199917.931 [Default:Iridium] Running Loop=1 2012-10-02T17:45:17.931Z,1349199917.931 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-10-02T17:45:17.931Z,1349199917.931 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-02T17:45:17.931Z,1349199917.931 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-10-02T17:45:17.931Z,1349199917.931 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-10-02T17:45:17.931Z,1349199917.931 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-02T17:45:17.933Z,1349199917.933 [Default:Iridium:B.GoToSurface] Stopped 2012-10-02T17:45:17.933Z,1349199917.933 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:45:17.933Z,1349199917.933 [Default:Iridium:Read_Iridium] Running Loop=1 2012-10-02T17:45:18.052Z,1349199918.052 [Reporter](INFO): DVL_micro.RSSIPoints no_value 2012-10-02T17:45:18.667Z,1349199918.667 [NAL9602](INFO): Powering up 2012-10-02T17:45:29.496Z,1349199929.496 [NAL9602](INFO): NAL9602 initialized 2012-10-02T17:45:31.439Z,1349199931.439 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2012-10-02T17:45:33.224Z,1349199933.224 [CommandLine](IMPORTANT): got command restart application 2012-10-02T17:45:34.344Z,1349199934.344 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2012-10-02T17:45:34.344Z,1349199934.344 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2012-10-02T17:45:34.424Z,1349199934.424 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2012-10-02T17:45:34.680Z,1349199934.680 [WetLabsBB2FL](INFO): Powering down 2012-10-02T17:45:34.688Z,1349199934.688 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler 2012-10-02T17:45:34.708Z,1349199934.708 [Turbulence_NPS](INFO): Powering down 2012-10-02T17:45:34.728Z,1349199934.728 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2012-10-02T17:45:34.752Z,1349199934.752 [CTD_NeilBrown](INFO): Powering down 2012-10-02T17:45:34.768Z,1349199934.768 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler 2012-10-02T17:45:35.008Z,1349199935.008 [DVL_micro](INFO): uninitialize:Powering down 2012-10-02T17:45:35.013Z,1349199935.013 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2012-10-02T17:45:35.276Z,1349199935.276 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2012-10-02T17:45:35.293Z,1349199935.293 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2012-10-02T17:45:35.294Z,1349199935.294 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2012-10-02T17:45:35.619Z,1349199935.619 [controlThread](DEBUG): Uninitializing ControlThread 2012-10-02T17:45:35.619Z,1349199935.619 [AHRS_sp3003D](INFO): Powering down 2012-10-02T17:45:35.709Z,1349199935.709 [NAL9602](INFO): Powering down 2012-10-02T17:45:35.711Z,1349199935.711 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-10-02T17:45:35.711Z,1349199935.711 [NavChart](DEBUG): Uninitialize NavChart Derivation. 2012-10-02T17:45:35.713Z,1349199935.713 [Default] Stopped 2012-10-02T17:45:35.713Z,1349199935.713 [Default](INFO): Aggregate::uninitialize Default 2012-10-02T17:45:35.713Z,1349199935.713 [Default:Iridium] Stopped 2012-10-02T17:45:35.713Z,1349199935.713 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-10-02T17:45:35.713Z,1349199935.713 [Default:Iridium:A.SetSpeed] Stopped 2012-10-02T17:45:35.713Z,1349199935.713 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-10-02T17:45:35.713Z,1349199935.713 [Default:Iridium:Read_Iridium] Stopped 2012-10-02T17:45:35.713Z,1349199935.713 [Default:D.SetSpeed] Stopped 2012-10-02T17:45:35.713Z,1349199935.713 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-10-02T17:45:35.713Z,1349199935.713 [Default:E.GoToSurface] Stopped 2012-10-02T17:45:35.714Z,1349199935.714 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-02T17:45:35.718Z,1349199935.718 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2012-10-02T17:45:35.718Z,1349199935.718 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2012-10-02T17:45:35.718Z,1349199935.718 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2012-10-02T17:45:35.719Z,1349199935.719 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2012-10-02T17:45:35.719Z,1349199935.719 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2012-10-02T17:45:35.719Z,1349199935.719 [BuoyancyServo](INFO): Powering down 2012-10-02T17:45:35.724Z,1349199935.724 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2012-10-02T17:45:35.724Z,1349199935.724 [ElevatorServo](INFO): Powering down 2012-10-02T17:45:35.725Z,1349199935.725 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-10-02T17:45:35.725Z,1349199935.725 [MassServo](INFO): Powering down 2012-10-02T17:45:35.726Z,1349199935.726 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2012-10-02T17:45:35.726Z,1349199935.726 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2012-10-02T17:45:35.726Z,1349199935.726 [ThrusterServo](INFO): Powering down 2012-10-02T17:45:35.727Z,1349199935.727 [SBIT](DEBUG): Uninitialize SBIT Component. 2012-10-02T17:45:35.727Z,1349199935.727 [IBIT](DEBUG): Uninitialize IBIT Component. 2012-10-02T17:45:35.728Z,1349199935.728 [CBIT](DEBUG): Uninitialize CBIT Component.