2012-10-09T00:59:41.144Z,1349744381.144 [Supervisor](DEBUG): Initializing supervisor. 2012-10-09T00:59:41.148Z,1349744381.148 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2012-10-09T00:59:41.149Z,1349744381.149 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-10-09T00:59:41.150Z,1349744381.150 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2012-10-09T00:59:41.154Z,1349744381.154 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-10-09T00:59:41.165Z,1349744381.165 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-10-09T00:59:41.166Z,1349744381.166 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2012-10-09T00:59:41.167Z,1349744381.167 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread. 2012-10-09T00:59:41.168Z,1349744381.168 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-10-09T00:59:41.171Z,1349744381.171 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-10-09T00:59:41.486Z,1349744381.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-10-09T00:59:41.488Z,1349744381.488 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-10-09T00:59:41.687Z,1349744381.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-10-09T00:59:41.688Z,1349744381.688 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-10-09T00:59:41.782Z,1349744381.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-10-09T00:59:41.784Z,1349744381.784 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-10-09T00:59:42.011Z,1349744382.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-10-09T00:59:42.012Z,1349744382.012 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-10-09T00:59:42.158Z,1349744382.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-10-09T00:59:42.160Z,1349744382.160 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-10-09T00:59:42.419Z,1349744382.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-10-09T00:59:42.420Z,1349744382.420 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-10-09T00:59:42.610Z,1349744382.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-10-09T00:59:42.612Z,1349744382.612 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-10-09T00:59:42.896Z,1349744382.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-10-09T00:59:42.898Z,1349744382.898 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-10-09T00:59:43.011Z,1349744383.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-10-09T00:59:43.012Z,1349744383.012 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-10-09T00:59:43.464Z,1349744383.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-10-09T00:59:43.465Z,1349744383.464 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-10-09T00:59:43.590Z,1349744383.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-10-09T00:59:43.591Z,1349744383.591 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-10-09T00:59:43.688Z,1349744383.688 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-10-09T00:59:43.691Z,1349744383.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-10-09T00:59:43.806Z,1349744383.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-10-09T00:59:43.952Z,1349744383.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-10-09T00:59:44.050Z,1349744384.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-10-09T00:59:44.166Z,1349744384.166 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-10-09T00:59:44.280Z,1349744384.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-10-09T00:59:44.416Z,1349744384.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-10-09T00:59:44.521Z,1349744384.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-10-09T00:59:44.627Z,1349744384.627 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2012-10-09T00:59:44.717Z,1349744384.717 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-10-09T00:59:44.990Z,1349744384.990 [InternalSim] Loaded 2012-10-09T00:59:44.990Z,1349744384.990 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-10-09T00:59:44.991Z,1349744384.991 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-10-09T00:59:44.992Z,1349744384.992 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-10-09T00:59:45.080Z,1349744385.080 [SBIT](DEBUG): Construct Startup Built In Test. 2012-10-09T00:59:45.094Z,1349744385.094 [SBIT] Loaded 2012-10-09T00:59:45.098Z,1349744385.098 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-10-09T00:59:45.099Z,1349744385.099 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-10-09T00:59:45.128Z,1349744385.128 [IBIT] Loaded 2012-10-09T00:59:45.129Z,1349744385.129 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-10-09T00:59:45.132Z,1349744385.132 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-10-09T00:59:45.271Z,1349744385.271 [CBIT] Loaded 2012-10-09T00:59:45.271Z,1349744385.272 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-10-09T00:59:45.272Z,1349744385.272 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-10-09T00:59:45.273Z,1349744385.273 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-10-09T00:59:45.449Z,1349744385.448 [BuoyancyServo] Loaded 2012-10-09T00:59:45.449Z,1349744385.449 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-10-09T00:59:45.458Z,1349744385.458 [ElevatorServo] Loaded 2012-10-09T00:59:45.458Z,1349744385.458 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-10-09T00:59:45.470Z,1349744385.470 [MassServo] Loaded 2012-10-09T00:59:45.471Z,1349744385.471 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-10-09T00:59:45.487Z,1349744385.487 [RudderServo] Loaded 2012-10-09T00:59:45.487Z,1349744385.487 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-10-09T00:59:45.488Z,1349744385.488 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-10-09T00:59:45.489Z,1349744385.489 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-10-09T00:59:45.525Z,1349744385.526 [DepthRateCalculator] Loaded 2012-10-09T00:59:45.526Z,1349744385.526 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-10-09T00:59:51.167Z,1349744391.167 [HFRadarModelCalc] Loaded 2012-10-09T00:59:51.167Z,1349744391.167 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2012-10-09T00:59:51.188Z,1349744391.188 [NavChart] Loaded 2012-10-09T00:59:51.188Z,1349744391.188 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-10-09T00:59:51.194Z,1349744391.194 [PitchRateCalculator] Loaded 2012-10-09T00:59:51.195Z,1349744391.195 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-10-09T00:59:51.201Z,1349744391.201 [SpeedCalculator] Loaded 2012-10-09T00:59:51.202Z,1349744391.202 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-10-09T00:59:51.217Z,1349744391.217 [TempGradientCalculator] Loaded 2012-10-09T00:59:51.217Z,1349744391.217 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-10-09T00:59:51.223Z,1349744391.223 [YawRateCalculator] Loaded 2012-10-09T00:59:51.223Z,1349744391.223 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-10-09T00:59:51.247Z,1349744391.247 [Navigation] Loaded 2012-10-09T00:59:51.247Z,1349744391.247 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-10-09T00:59:51.248Z,1349744391.248 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-10-09T00:59:51.248Z,1349744391.248 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-10-09T00:59:51.558Z,1349744391.558 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-10-09T00:59:51.558Z,1349744391.558 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-10-09T00:59:51.604Z,1349744391.604 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-10-09T00:59:51.605Z,1349744391.605 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-10-09T00:59:51.673Z,1349744391.673 [VerticalControl](DEBUG): Construct VerticalControl. 2012-10-09T00:59:51.721Z,1349744391.721 [VerticalControl] Loaded 2012-10-09T00:59:51.721Z,1349744391.721 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-10-09T00:59:51.722Z,1349744391.722 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-10-09T00:59:51.746Z,1349744391.746 [HorizontalControl] Loaded 2012-10-09T00:59:51.747Z,1349744391.747 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-10-09T00:59:51.748Z,1349744391.748 [SpeedControl](DEBUG): Construct SpeedControl. 2012-10-09T00:59:51.749Z,1349744391.750 [SpeedControl] Loaded 2012-10-09T00:59:51.750Z,1349744391.750 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-10-09T00:59:51.751Z,1349744391.751 [LoopControl](DEBUG): Construct LoopControl. 2012-10-09T00:59:51.751Z,1349744391.751 [LoopControl] Loaded 2012-10-09T00:59:51.752Z,1349744391.751 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-10-09T00:59:51.752Z,1349744391.752 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-10-09T00:59:51.753Z,1349744391.753 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-10-09T00:59:51.761Z,1349744391.761 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-10-09T00:59:51.766Z,1349744391.766 [AsyncPiEstimator] Loaded 2012-10-09T00:59:51.766Z,1349744391.766 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-10-09T00:59:51.770Z,1349744391.770 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406724E0 2012-10-09T00:59:51.771Z,1349744391.771 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-10-09T00:59:51.772Z,1349744391.772 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-10-09T00:59:52.075Z,1349744392.075 [AHRS_sp3003D] Loaded 2012-10-09T00:59:52.075Z,1349744392.075 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-10-09T00:59:52.098Z,1349744392.098 [Depth_Keller] Loaded 2012-10-09T00:59:52.098Z,1349744392.098 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-10-09T00:59:52.104Z,1349744392.104 [DropWeight] Loaded 2012-10-09T00:59:52.105Z,1349744392.105 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-10-09T00:59:52.271Z,1349744392.271 [DVL_micro] Loaded 2012-10-09T00:59:52.271Z,1349744392.271 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2012-10-09T00:59:52.287Z,1349744392.287 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F34E0 2012-10-09T00:59:52.402Z,1349744392.402 [NAL9602] Loaded 2012-10-09T00:59:52.403Z,1349744392.403 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2012-10-09T00:59:52.497Z,1349744392.497 [Onboard] Loaded 2012-10-09T00:59:52.497Z,1349744392.497 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-10-09T00:59:52.505Z,1349744392.505 [Radio_Freewave] Loaded 2012-10-09T00:59:52.505Z,1349744392.505 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-10-09T00:59:52.506Z,1349744392.506 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-10-09T00:59:52.507Z,1349744392.507 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-10-09T00:59:52.701Z,1349744392.701 [CTD_NeilBrown] Loaded 2012-10-09T00:59:52.702Z,1349744392.702 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-10-09T00:59:52.715Z,1349744392.714 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4074B4E0 2012-10-09T00:59:52.725Z,1349744392.726 [PAR_Licor] Loaded 2012-10-09T00:59:52.726Z,1349744392.726 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2012-10-09T00:59:52.751Z,1349744392.751 [Turbulence_NPS] Loaded 2012-10-09T00:59:52.751Z,1349744392.751 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread. 2012-10-09T00:59:52.763Z,1349744392.763 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 4077B4E0 2012-10-09T00:59:52.802Z,1349744392.802 [WetLabsBB2FL] Loaded 2012-10-09T00:59:52.802Z,1349744392.802 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2012-10-09T00:59:52.814Z,1349744392.814 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AB4E0 2012-10-09T00:59:52.815Z,1349744392.815 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-10-09T00:59:52.817Z,1349744392.818 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-10-09T00:59:52.818Z,1349744392.818 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-10-09T00:59:52.828Z,1349744392.829 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-10-09T00:59:52.839Z,1349744392.839 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DB4E0 2012-10-09T00:59:52.843Z,1349744392.843 [Supervisor](DEBUG): Running supervisor. 2012-10-09T00:59:52.844Z,1349744392.844 [CommandLine](INFO): Thread ID is 750 2012-10-09T00:59:52.847Z,1349744392.847 [controlThread](INFO): Thread ID is 749 2012-10-09T00:59:52.847Z,1349744392.847 [controlThread](DEBUG): Initializing ControlThread 2012-10-09T00:59:52.848Z,1349744392.848 [CycleStarter](INFO): Thread ID is 748 2012-10-09T00:59:52.848Z,1349744392.848 [InternalSim](DEBUG): InternalSim initializing... 2012-10-09T00:59:53.243Z,1349744393.243 [AsyncPiEstimator](INFO): Thread ID is 811 2012-10-09T00:59:53.243Z,1349744393.243 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-10-09T00:59:53.364Z,1349744393.364 [DVL_micro](INFO): Thread ID is 815 2012-10-09T00:59:53.577Z,1349744393.578 [CTD_NeilBrown](INFO): Thread ID is 816 2012-10-09T00:59:53.578Z,1349744393.578 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-10-09T00:59:53.584Z,1349744393.584 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-09T00:59:53.605Z,1349744393.605 [DVL_micro](INFO): Initializing 2012-10-09T00:59:53.606Z,1349744393.606 [DVL_micro](INFO): start:Powering up 2012-10-09T00:59:53.613Z,1349744393.613 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T00:59:53.613Z,1349744393.614 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T00:59:53.679Z,1349744393.679 [Turbulence_NPS](INFO): Thread ID is 817 2012-10-09T00:59:53.680Z,1349744393.680 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-10-09T00:59:53.680Z,1349744393.680 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-09T00:59:53.691Z,1349744393.691 [WetLabsBB2FL](INFO): Thread ID is 818 2012-10-09T00:59:53.692Z,1349744393.692 [WetLabsBB2FL](INFO): Powering down 2012-10-09T00:59:53.714Z,1349744393.714 [SBIT](INFO): Initialize SBIT Component. 2012-10-09T00:59:53.714Z,1349744393.714 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 9939 2012-10-09T00:59:53.739Z,1349744393.739 [IBIT](INFO): Initialize IBIT Component. 2012-10-09T00:59:53.740Z,1349744393.740 [CBIT](DEBUG): Initialize CBIT Component. 2012-10-09T00:59:53.740Z,1349744393.740 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2012-10-09T00:59:53.740Z,1349744393.740 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-10-09T00:59:53.741Z,1349744393.741 [NavChartDb](INFO): Thread ID is 819 2012-10-09T00:59:53.748Z,1349744393.748 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-10-09T00:59:53.749Z,1349744393.749 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-10-09T00:59:53.749Z,1349744393.749 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-10-09T00:59:53.749Z,1349744393.750 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-10-09T00:59:53.750Z,1349744393.750 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-10-09T00:59:53.812Z,1349744393.812 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-10-09T00:59:53.812Z,1349744393.812 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-10-09T00:59:53.812Z,1349744393.812 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-10-09T00:59:53.813Z,1349744393.813 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-10-09T00:59:53.813Z,1349744393.813 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-10-09T00:59:53.815Z,1349744393.815 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-10-09T00:59:53.815Z,1349744393.815 [Navigation](DEBUG): Initializing Navigation. 2012-10-09T00:59:53.816Z,1349744393.816 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-10-09T00:59:53.817Z,1349744393.817 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-10-09T00:59:53.818Z,1349744393.818 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-10-09T00:59:53.818Z,1349744393.819 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-10-09T00:59:53.831Z,1349744393.831 [Turbulence_NPS](INFO): Pause powering down 2012-10-09T00:59:53.835Z,1349744393.835 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-10-09T00:59:53.876Z,1349744393.876 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-09T00:59:53.891Z,1349744393.891 [MissionManager](DEBUG): 2012-10-09T00:59:53.892Z,1349744393.892 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-10-09T00:59:53.984Z,1349744393.984 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-10-09T00:59:53.987Z,1349744393.987 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-10-09T00:59:53.990Z,1349744393.990 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-09T00:59:53.998Z,1349744393.998 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-10-09T00:59:54.001Z,1349744394.001 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-09T00:59:54.008Z,1349744394.008 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-10-09T00:59:54.016Z,1349744394.016 [Default:D.SetSpeed](DEBUG): Construct. 2012-10-09T00:59:54.023Z,1349744394.023 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-09T00:59:54.028Z,1349744394.028 [Default:F.Wait](DEBUG): Construct Wait. 2012-10-09T00:59:54.032Z,1349744394.032 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-10-09T00:59:54.036Z,1349744394.036 [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,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-09T00:59:54.056Z,1349744394.056 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-10-09T00:59:54.207Z,1349744394.207 [Depth_Keller](INFO): A/D timeout, 2 tries over 138 ms 2012-10-09T00:59:54.207Z,1349744394.207 [Depth_Keller] Data Fault, FailCount= 1 2012-10-09T00:59:54.207Z,1349744394.207 [Depth_Keller](ERROR): Data Fault 2012-10-09T00:59:54.235Z,1349744394.235 [Radio_Freewave](INFO): Powering up 2012-10-09T00:59:54.336Z,1349744394.336 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-09T00:59:54.436Z,1349744394.436 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-10-09T00:59:54.439Z,1349744394.439 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-10-09T00:59:54.445Z,1349744394.445 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-10-09T00:59:54.447Z,1349744394.447 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-10-09T00:59:54.453Z,1349744394.453 [MassServo](DEBUG): Initializing EZServoServo. 2012-10-09T00:59:54.455Z,1349744394.455 [MassServo](DEBUG): Initializing MassServo. 2012-10-09T00:59:54.461Z,1349744394.461 [RudderServo](DEBUG): Initializing EZServoServo. 2012-10-09T00:59:54.462Z,1349744394.462 [RudderServo](DEBUG): Initializing RudderServo. 2012-10-09T00:59:54.486Z,1349744394.486 [CBIT](ERROR): Data Fault in component: Depth_Keller 2012-10-09T00:59:54.486Z,1349744394.486 [CBIT](INFO): Clearing failed state for component Depth_Keller 2012-10-09T00:59:54.486Z,1349744394.486 [Depth_Keller] No Fault, FailCount= 1 2012-10-09T00:59:55.763Z,1349744395.763 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T00:59:55.763Z,1349744395.763 [DVL_micro](INFO): Querying output modes 2012-10-09T00:59:55.763Z,1349744395.763 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T00:59:55.767Z,1349744395.767 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T00:59:55.767Z,1349744395.767 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T00:59:55.767Z,1349744395.767 [DVL_micro](INFO): RSSI output enabled 2012-10-09T00:59:55.767Z,1349744395.767 [DVL_micro](INFO): ADCP output enabled 2012-10-09T00:59:55.767Z,1349744395.767 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T00:59:55.775Z,1349744395.775 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T00:59:55.780Z,1349744395.780 [DVL_micro](INFO): pause:Powering down 2012-10-09T00:59:57.786Z,1349744397.786 [NAL9602](INFO): Powering up NAL9602 2012-10-09T01:00:04.065Z,1349744404.065 [CBIT](CRITICAL): Environmental Failure. Press:15.513202 PSI. Humidity:17%. Temp:20 C. ABORTING MISSION 2012-10-09T01:00:09.531Z,1349744409.531 [NAL9602](INFO): NAL9602 initialized 2012-10-09T01:00:10.255Z,1349744410.255 [SBIT](IMPORTANT): Beginning Startup BIT 2012-10-09T01:00:10.257Z,1349744410.257 [CBIT](IMPORTANT): Beginning GF scan 2012-10-09T01:00:11.163Z,1349744411.163 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.823200 degree 2012-10-09T01:00:11.165Z,1349744411.165 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2012-10-09T01:00:11.165Z,1349744411.165 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121827.000000 degree 2012-10-09T01:00:11.167Z,1349744411.167 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2012-10-09T01:00:11.167Z,1349744411.167 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-09T01:00:11.168Z,1349744411.168 [CommandLine](IMPORTANT): got command report touch Turbulence_NPS.packetNum 2012-10-09T01:00:11.389Z,1349744411.389 [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,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-09T01:00:14.891Z,1349744414.891 [CommandLine](IMPORTANT): got command show variable drop 2012-10-09T01:00:14.900Z,1349744414.900 [CommandLine](INFO): Config/Sensor.DropWeight_enable (bool) 2012-10-09T01:00:14.901Z,1349744414.901 [CommandLine](INFO): Config/Sensor.DropWeight_useHardware (bool) 2012-10-09T01:00:14.907Z,1349744414.907 [CommandLine](INFO): VerticalControl.dropWtDepthExcursion (meter) 2012-10-09T01:00:14.908Z,1349744414.908 [CommandLine](INFO): VerticalControl.dropWtOverrideDelay (minute) 2012-10-09T01:00:14.910Z,1349744414.910 [CommandLine](INFO): Config/Simulator.dropWt1Volume (cubic_meter) 2012-10-09T01:00:14.913Z,1349744414.913 [CommandLine](INFO): Config/Simulator.dropWt1Mass (kilogram) 2012-10-09T01:00:14.914Z,1349744414.914 [CommandLine](INFO): Config/Simulator.dropWt1X (meter) 2012-10-09T01:00:14.914Z,1349744414.914 [CommandLine](INFO): Config/Simulator.dropWt1Y (meter) 2012-10-09T01:00:14.914Z,1349744414.914 [CommandLine](INFO): Config/Simulator.dropWt1Z (meter) 2012-10-09T01:00:14.921Z,1349744414.921 [CommandLine](INFO): DropWeight.dropWeightState (bool) 2012-10-09T01:00:14.925Z,1349744414.925 [CommandLine](INFO): DropWeight.durationOfLastRun (second) 2012-10-09T01:00:21.367Z,1349744421.367 [CommandLine](IMPORTANT): got command get DropWeight.dropWeightState 2012-10-09T01:00:21.368Z,1349744421.368 [CommandLine](IMPORTANT): DropWeight.dropWeightState 1.000000 bool 2012-10-09T01:00:22.284Z,1349744422.284 [NAL9602](INFO): Powering down 2012-10-09T01:00:29.479Z,1349744429.479 [CommandLine](IMPORTANT): got command get Config/Sensor.DropWeight_enable 2012-10-09T01:00:29.480Z,1349744429.480 [CommandLine](IMPORTANT): Config/Sensor.DropWeight_enable 1.000000 bool 2012-10-09T01:00:34.343Z,1349744434.343 [CommandLine](IMPORTANT): got command get Config/Sensor.DropWeight_useHardware 2012-10-09T01:00:34.343Z,1349744434.343 [CommandLine](IMPORTANT): Config/Sensor.DropWeight_useHardware 1.000000 bool 2012-10-09T01:00:37.762Z,1349744437.763 [CBIT](IMPORTANT): No ground fault detected 2012-10-09T01:01:05.379Z,1349744465.379 [SBIT](IMPORTANT): SBIT PASSED 2012-10-09T01:01:05.772Z,1349744465.772 [MissionManager](IMPORTANT): Started mission Startup 2012-10-09T01:01:05.772Z,1349744465.772 [Startup] Running Loop=1 2012-10-09T01:01:05.772Z,1349744465.772 [Startup](INFO): Aggregate::initialize Startup 2012-10-09T01:01:05.772Z,1349744465.773 [Startup:A.GoToSurface] Running Loop=1 2012-10-09T01:01:05.773Z,1349744465.773 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:01:05.778Z,1349744465.778 [Startup:StartupSatComms] Running Loop=1 2012-10-09T01:01:05.778Z,1349744465.778 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-10-09T01:01:05.778Z,1349744465.778 [Startup:StartupSatComms:A] Running Loop=1 2012-10-09T01:01:05.789Z,1349744465.789 [CBIT](CRITICAL): Environmental Failure. Press:15.513202 PSI. Humidity:17%. Temp:21 C. ABORTING MISSION 2012-10-09T01:01:06.173Z,1349744466.173 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-09T01:01:06.174Z,1349744466.174 [Startup:StartupSatComms:A] Stopped 2012-10-09T01:01:06.174Z,1349744466.174 [Startup:StartupSatComms:B] Running Loop=1 2012-10-09T01:01:06.573Z,1349744466.573 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-09T01:01:06.964Z,1349744466.964 [NAL9602](INFO): Powering up 2012-10-09T01:01:17.983Z,1349744477.983 [NAL9602](INFO): NAL9602 initialized 2012-10-09T01:01:36.631Z,1349744496.631 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-09T01:01:37.094Z,1349744497.094 [Startup:StartupSatComms:B] Stopped 2012-10-09T01:01:37.094Z,1349744497.094 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2012-10-09T01:01:37.094Z,1349744497.095 [Startup:StartupSatComms] Stopped 2012-10-09T01:01:37.095Z,1349744497.095 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2012-10-09T01:01:37.095Z,1349744497.095 [Startup](INFO): Completed Startup 2012-10-09T01:01:37.095Z,1349744497.095 [Startup] Stopped 2012-10-09T01:01:37.096Z,1349744497.096 [Startup](INFO): Aggregate::uninitialize Startup 2012-10-09T01:01:37.096Z,1349744497.096 [Startup:A.GoToSurface] Stopped 2012-10-09T01:01:37.096Z,1349744497.096 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:01:37.333Z,1349744497.333 [MissionManager](IMPORTANT): Started mission Default 2012-10-09T01:01:37.333Z,1349744497.333 [Default] Running Loop=1 2012-10-09T01:01:37.333Z,1349744497.333 [Default](INFO): Aggregate::initialize Default 2012-10-09T01:01:37.334Z,1349744497.334 [Default:D.SetSpeed] Running Loop=1 2012-10-09T01:01:37.334Z,1349744497.334 [Default:D.SetSpeed](DEBUG): Initialize. 2012-10-09T01:01:37.334Z,1349744497.334 [Default:E.GoToSurface] Running Loop=1 2012-10-09T01:01:37.334Z,1349744497.334 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:01:37.334Z,1349744497.334 [Default:Iridium] Running Loop=1 2012-10-09T01:01:37.334Z,1349744497.334 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-10-09T01:01:37.335Z,1349744497.335 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-09T01:01:37.335Z,1349744497.335 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-10-09T01:01:37.335Z,1349744497.335 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-10-09T01:01:37.335Z,1349744497.335 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:01:37.335Z,1349744497.335 [Default:E.GoToSurface] Running Loop=1 2012-10-09T01:01:37.341Z,1349744497.341 [Default:D.SetSpeed] Running Loop=1 2012-10-09T01:01:37.351Z,1349744497.351 [Default:Iridium:B.GoToSurface] Stopped 2012-10-09T01:01:37.351Z,1349744497.351 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:01:37.351Z,1349744497.351 [Default:Iridium:Read_Iridium] Running Loop=1 2012-10-09T01:01:37.351Z,1349744497.351 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-09T01:01:37.356Z,1349744497.356 [Default:GPS] Running Loop=1 2012-10-09T01:01:37.356Z,1349744497.356 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-10-09T01:01:37.356Z,1349744497.356 [Default:GPS:A.SetSpeed] Running Loop=1 2012-10-09T01:01:37.356Z,1349744497.356 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-10-09T01:01:37.357Z,1349744497.357 [Default:GPS:B.GoToSurface] Running Loop=1 2012-10-09T01:01:37.357Z,1349744497.357 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:01:37.362Z,1349744497.362 [Default:GPS:B.GoToSurface] Stopped 2012-10-09T01:01:37.362Z,1349744497.362 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:01:37.362Z,1349744497.362 [Default:GPS:Read_GPS] Running Loop=1 2012-10-09T01:01:37.363Z,1349744497.362 [Default:GPS:A.SetSpeed] Running Loop=1 2012-10-09T01:01:37.730Z,1349744497.730 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-09T01:01:37.733Z,1349744497.733 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-09T01:01:37.734Z,1349744497.734 [Default:GPS:Read_GPS] Stopped 2012-10-09T01:01:37.734Z,1349744497.734 [Default:GPS:D] Running Loop=1 2012-10-09T01:01:38.132Z,1349744498.132 [Default:GPS:D] Stopped 2012-10-09T01:01:38.132Z,1349744498.132 [Default:GPS](INFO): Completed Default:GPS 2012-10-09T01:01:38.132Z,1349744498.132 [Default:GPS] Stopped 2012-10-09T01:01:38.133Z,1349744498.133 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-10-09T01:01:38.133Z,1349744498.133 [Default:GPS:A.SetSpeed] Stopped 2012-10-09T01:01:38.133Z,1349744498.133 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:01:45.399Z,1349744505.399 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml 2012-10-09T01:01:45.399Z,1349744505.399 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml 2012-10-09T01:01:45.479Z,1349744505.479 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m 2012-10-09T01:01:45.481Z,1349744505.481 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m 2012-10-09T01:01:45.485Z,1349744505.485 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m 2012-10-09T01:01:45.488Z,1349744505.488 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m 2012-10-09T01:01:45.491Z,1349744505.491 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m 2012-10-09T01:01:45.494Z,1349744505.494 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg 2012-10-09T01:01:45.498Z,1349744505.498 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg 2012-10-09T01:01:45.501Z,1349744505.501 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg 2012-10-09T01:01:45.504Z,1349744505.504 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg 2012-10-09T01:01:45.507Z,1349744505.507 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min 2012-10-09T01:01:45.511Z,1349744505.511 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s 2012-10-09T01:01:45.513Z,1349744505.513 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h 2012-10-09T01:01:45.517Z,1349744505.517 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a 2012-10-09T01:01:45.520Z,1349744505.520 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-10-09T01:01:45.528Z,1349744505.528 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait. 2012-10-09T01:01:45.529Z,1349744505.529 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-10-09T01:01:45.543Z,1349744505.543 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-10-09T01:01:45.550Z,1349744505.550 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait. 2012-10-09T01:01:45.551Z,1349744505.551 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-10-09T01:01:45.572Z,1349744505.572 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-10-09T01:01:45.575Z,1349744505.575 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-10-09T01:01:45.577Z,1349744505.578 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s 2012-10-09T01:01:45.593Z,1349744505.593 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min 2012-10-09T01:01:45.594Z,1349744505.594 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-09T01:01:45.608Z,1349744505.608 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg 2012-10-09T01:01:45.614Z,1349744505.614 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-10-09T01:01:45.766Z,1349744505.766 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool 2012-10-09T01:01:45.769Z,1349744505.769 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool 2012-10-09T01:01:45.776Z,1349744505.776 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool 2012-10-09T01:01:45.783Z,1349744505.783 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool 2012-10-09T01:01:45.785Z,1349744505.785 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool 2012-10-09T01:01:45.792Z,1349744505.792 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectRSSIActive = 0 bool 2012-10-09T01:01:45.800Z,1349744505.800 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledAanderaaO2 = 0.000000 n/a 2012-10-09T01:01:45.807Z,1349744505.807 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledNeilBrown = 1.000000 n/a 2012-10-09T01:01:45.822Z,1349744505.822 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledTurbulence_NPS = 1.000000 n/a 2012-10-09T01:01:45.826Z,1349744505.826 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledWetLabsBB2FL = 1.000000 n/a 2012-10-09T01:01:45.829Z,1349744505.829 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a 2012-10-09T01:01:45.832Z,1349744505.832 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledISUS = 0.000000 n/a 2012-10-09T01:01:45.847Z,1349744505.847 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l 2012-10-09T01:01:45.850Z,1349744505.850 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m 2012-10-09T01:01:45.853Z,1349744505.853 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg 2012-10-09T01:01:45.856Z,1349744505.856 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg 2012-10-09T01:01:45.871Z,1349744505.871 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l 2012-10-09T01:01:45.874Z,1349744505.874 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m 2012-10-09T01:01:45.877Z,1349744505.877 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg 2012-10-09T01:01:45.880Z,1349744505.880 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg 2012-10-09T01:01:45.896Z,1349744505.895 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSI = 0.000000 dB 2012-10-09T01:01:45.899Z,1349744505.899 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSIDepth = 0.000000 m 2012-10-09T01:01:45.901Z,1349744505.901 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILatitude = nan arcdeg 2012-10-09T01:01:45.904Z,1349744505.904 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILongitude = nan arcdeg 2012-10-09T01:01:45.935Z,1349744505.935 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-09T01:01:45.956Z,1349744505.956 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-09T01:01:45.978Z,1349744505.978 [science_to_67_70:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-09T01:01:45.985Z,1349744505.985 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool 2012-10-09T01:01:45.986Z,1349744505.986 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool 2012-10-09T01:01:45.999Z,1349744506.000 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy. 2012-10-09T01:01:46.003Z,1349744506.003 [science_to_67_70:H.SetSpeed](DEBUG): Construct. 2012-10-09T01:01:46.006Z,1349744506.006 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-10-09T01:01:46.019Z,1349744506.019 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo. 2012-10-09T01:01:46.051Z,1349744506.051 [science_to_67_70:Wpt.Point](DEBUG): Construct. 2012-10-09T01:01:46.082Z,1349744506.082 [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-09T01:01:46.095Z,1349744506.095 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml 2012-10-09T01:01:46.366Z,1349744506.366 [Default] Stopped 2012-10-09T01:01:46.366Z,1349744506.366 [Default](INFO): Aggregate::uninitialize Default 2012-10-09T01:01:46.366Z,1349744506.366 [Default:Iridium] Stopped 2012-10-09T01:01:46.366Z,1349744506.366 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-10-09T01:01:46.366Z,1349744506.366 [Default:Iridium:A.SetSpeed] Stopped 2012-10-09T01:01:46.366Z,1349744506.366 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:01:46.366Z,1349744506.366 [Default:Iridium:Read_Iridium] Stopped 2012-10-09T01:01:46.366Z,1349744506.366 [Default:D.SetSpeed] Stopped 2012-10-09T01:01:46.366Z,1349744506.366 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:01:46.367Z,1349744506.367 [Default:E.GoToSurface] Stopped 2012-10-09T01:01:46.367Z,1349744506.367 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:01:46.367Z,1349744506.367 [MissionManager](IMPORTANT): Started mission science_to_67_70 2012-10-09T01:01:46.367Z,1349744506.367 [science_to_67_70] Running Loop=1 2012-10-09T01:01:46.367Z,1349744506.367 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70 2012-10-09T01:01:46.367Z,1349744506.367 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-10-09T01:01:46.367Z,1349744506.367 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-10-09T01:01:46.368Z,1349744506.368 [science_to_67_70:Science] Running Loop=1 2012-10-09T01:01:46.368Z,1349744506.368 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:A] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:B] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:C] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:D] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:E] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:F] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:Science:G] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:H.SetSpeed](DEBUG): Initialize. 2012-10-09T01:01:46.369Z,1349744506.369 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-09T01:01:46.370Z,1349744506.369 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-10-09T01:01:46.371Z,1349744506.371 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-09T01:01:46.371Z,1349744506.371 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent. 2012-10-09T01:01:46.371Z,1349744506.371 [science_to_67_70:SURFACECOMMS] Running Loop=1 2012-10-09T01:01:46.371Z,1349744506.371 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS 2012-10-09T01:01:46.371Z,1349744506.371 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-10-09T01:01:46.371Z,1349744506.371 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:01:46.372Z,1349744506.372 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-09T01:01:46.377Z,1349744506.377 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-09T01:01:46.383Z,1349744506.383 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-09T01:01:46.388Z,1349744506.388 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-09T01:01:46.392Z,1349744506.392 [science_to_67_70:Science] Running Loop=1 2012-10-09T01:01:46.393Z,1349744506.393 [science_to_67_70:Science:PeakDetectChl] Running Loop=1 2012-10-09T01:01:46.393Z,1349744506.393 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl 2012-10-09T01:01:46.393Z,1349744506.393 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2012-10-09T01:01:46.394Z,1349744506.394 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize. 2012-10-09T01:01:46.400Z,1349744506.399 [science_to_67_70:Science:G] Running Loop=1 2012-10-09T01:01:46.400Z,1349744506.400 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2012-10-09T01:01:46.401Z,1349744506.401 [science_to_67_70:Science:F] Running Loop=1 2012-10-09T01:01:46.402Z,1349744506.402 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI 2012-10-09T01:01:46.403Z,1349744506.403 [science_to_67_70:Science:E] Running Loop=1 2012-10-09T01:01:46.404Z,1349744506.404 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity 2012-10-09T01:01:46.405Z,1349744506.405 [science_to_67_70:Science:D] Running Loop=1 2012-10-09T01:01:46.406Z,1349744506.406 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond 2012-10-09T01:01:46.407Z,1349744506.407 [science_to_67_70:Science:C] Running Loop=1 2012-10-09T01:01:46.408Z,1349744506.408 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-10-09T01:01:46.408Z,1349744506.408 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:SURFACECOMMS:B] Running Loop=1 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:OffshoreWrapper] Running Loop=1 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-10-09T01:01:46.415Z,1349744506.415 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1 2012-10-09T01:01:46.416Z,1349744506.416 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-10-09T01:01:46.420Z,1349744506.420 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-10-09T01:01:46.425Z,1349744506.425 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-10-09T01:01:46.430Z,1349744506.430 [science_to_67_70:AltEnvWrapper] Running Loop=1 2012-10-09T01:01:46.430Z,1349744506.430 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper 2012-10-09T01:01:46.430Z,1349744506.430 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-10-09T01:01:46.430Z,1349744506.430 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-10-09T01:01:46.431Z,1349744506.431 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1 2012-10-09T01:01:46.431Z,1349744506.431 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-10-09T01:01:46.435Z,1349744506.435 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-10-09T01:01:46.507Z,1349744506.507 [Turbulence_NPS](INFO): Resume powering up 2012-10-09T01:01:46.508Z,1349744506.508 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-09T01:01:46.510Z,1349744506.510 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:01:46.519Z,1349744506.519 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:01:46.520Z,1349744506.520 [DVL_micro](INFO): resume:Powering up 2012-10-09T01:01:46.520Z,1349744506.520 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:01:46.521Z,1349744506.521 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:01:46.521Z,1349744506.522 [WetLabsBB2FL](INFO): Powering up 2012-10-09T01:01:47.338Z,1349744507.338 [science_to_67_70:J.YoYo] Preempted 2012-10-09T01:01:47.338Z,1349744507.338 [science_to_67_70:I.DepthEnvelope] Preempted 2012-10-09T01:01:47.338Z,1349744507.338 [science_to_67_70:H.SetSpeed] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:G.Buoyancy] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:Science] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:Science:C] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:Science:D] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:Science:E] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:Science:F] Preempted 2012-10-09T01:01:47.339Z,1349744507.339 [science_to_67_70:Science:G] Preempted 2012-10-09T01:01:47.340Z,1349744507.340 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-09T01:01:47.341Z,1349744507.341 [science_to_67_70:SURFACECOMMS:B:A] Stopped 2012-10-09T01:01:47.341Z,1349744507.341 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1 2012-10-09T01:01:47.344Z,1349744507.344 [Turbulence_NPS](INFO): Pause powering down 2012-10-09T01:01:47.593Z,1349744507.593 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-09T01:01:47.771Z,1349744507.770 [WetLabsBB2FL](INFO): Powering down 2012-10-09T01:01:48.387Z,1349744508.386 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2012-10-09T01:01:48.527Z,1349744508.527 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:01:48.527Z,1349744508.527 [DVL_micro](INFO): Querying output modes 2012-10-09T01:01:48.527Z,1349744508.527 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:01:48.535Z,1349744508.535 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T01:01:48.535Z,1349744508.535 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T01:01:48.535Z,1349744508.535 [DVL_micro](INFO): RSSI output enabled 2012-10-09T01:01:48.535Z,1349744508.535 [DVL_micro](INFO): ADCP output enabled 2012-10-09T01:01:48.535Z,1349744508.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:01:48.543Z,1349744508.543 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T01:01:48.544Z,1349744508.544 [DVL_micro](INFO): RSSI unrequested 2012-10-09T01:01:48.544Z,1349744508.544 [DVL_micro](INFO): ADCP unrequested 2012-10-09T01:01:48.545Z,1349744508.545 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:01:50.551Z,1349744510.551 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:01:50.551Z,1349744510.551 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-09T01:01:50.568Z,1349744510.568 [DVL_micro](DEBUG): cmdResponse: >ā„āŸāƒāādā{āpāJā6ā'ā-ā`āārāZāUāqājācāXānā„ā‰āšā†āwā}ācāFāBājānā`ājāmāwā§ā”āvāxā{āgāQāZāWājāĒāĖā 2012-10-09T01:01:50.568Z,1349744510.569 [DVL_micro](INFO): Enabling NQ1 output 2012-10-09T01:01:50.569Z,1349744510.569 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-09T01:01:50.579Z,1349744510.579 [DVL_micro](DEBUG): cmdResponse: ‘ā—ā}āā®ā¤āā—āā^ābājāŒāzāsā’āÖā×ā¦āā³ā±āqā[ā`ārāyā\ā]āNā@ārā¬āŌāµā‡āoāaāYālā‰ā¦āā]āeāˆā‹ā|āØā 2012-10-09T01:01:50.579Z,1349744510.579 [DVL_micro](INFO): Querying output modes 2012-10-09T01:01:50.580Z,1349744510.579 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:01:50.595Z,1349744510.595 [DVL_micro](DEBUG): cmdResponse: yā”āzāiāLāŠā©āØāČāģāĮāŖā£āˆā]āāŽāĒā¦āØā’āsāWāVāVāTāpā…ā„ā¦āŒā†ā‹āŅāā†ā^ā[ā\āeāaāpāpābāuā†āˆāqā¦ā 2012-10-09T01:01:50.595Z,1349744510.595 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:01:50.607Z,1349744510.607 [DVL_micro](DEBUG): cmdResponse: ˜ā`ā`āPākāUāCāaācāˆānā|ātā}āiā³ā¦ā¤ā“āāsā|ā‹ā‘āgāVānāYāwāpāgāSā`āmābāUā^āeāgāŒāaāBā’ā£āāƒā~ā¢āÕā 2012-10-09T01:01:51.159Z,1349744511.159 [DVL_micro](INFO): NQ1 requested 2012-10-09T01:01:51.159Z,1349744511.159 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:01:53.167Z,1349744513.167 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:01:53.167Z,1349744513.167 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-09T01:01:53.351Z,1349744513.351 [DVL_micro](DEBUG): cmdResponse: 00 2012-10-09T01:01:53.351Z,1349744513.351 [DVL_micro](INFO): Enabling NQ1 output 2012-10-09T01:01:53.351Z,1349744513.351 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-09T01:01:53.535Z,1349744513.534 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-09T01:01:53.535Z,1349744513.535 [DVL_micro](INFO): Querying output modes 2012-10-09T01:01:53.535Z,1349744513.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:01:53.540Z,1349744513.540 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-09T01:01:53.540Z,1349744513.540 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T01:01:53.540Z,1349744513.540 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:01:53.547Z,1349744513.547 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T01:01:54.243Z,1349744514.243 [CommandLine](IMPORTANT): got command failComponent 2012-10-09T01:01:54.243Z,1349744514.243 [CommandLine](IMPORTANT): Failed components: 2012-10-09T01:01:54.243Z,1349744514.243 [CommandLine](IMPORTANT): No failed Components. 2012-10-09T01:01:56.063Z,1349744516.063 [DVL_micro](DEBUG): NQ1 2012-10-09T01:01:57.475Z,1349744517.475 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-09T01:01:58.359Z,1349744518.359 [science_to_67_70:SURFACECOMMS:B:B] Stopped 2012-10-09T01:01:58.359Z,1349744518.359 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1 2012-10-09T01:01:58.590Z,1349744518.590 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-09T01:01:58.592Z,1349744518.592 [science_to_67_70:SURFACECOMMS:B:C] Stopped 2012-10-09T01:01:58.592Z,1349744518.592 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B 2012-10-09T01:01:58.592Z,1349744518.592 [science_to_67_70:SURFACECOMMS:B] Stopped 2012-10-09T01:01:58.592Z,1349744518.592 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B 2012-10-09T01:01:58.593Z,1349744518.593 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS 2012-10-09T01:01:58.593Z,1349744518.593 [science_to_67_70:SURFACECOMMS] Stopped 2012-10-09T01:01:58.593Z,1349744518.593 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS 2012-10-09T01:01:58.593Z,1349744518.593 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped 2012-10-09T01:01:58.593Z,1349744518.593 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:01:58.594Z,1349744518.593 [science_to_67_70:Wpt.Point] Running Loop=1 2012-10-09T01:01:58.594Z,1349744518.594 [science_to_67_70:Wpt.Point](DEBUG): Initialize. 2012-10-09T01:01:58.599Z,1349744518.599 [DVL_micro](DEBUG): NQ1 2012-10-09T01:01:58.996Z,1349744518.996 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-09T01:01:58.996Z,1349744518.996 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-09T01:01:58.997Z,1349744518.997 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-09T01:01:58.997Z,1349744518.997 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-09T01:01:58.998Z,1349744518.997 [science_to_67_70:Science] Running Loop=1 2012-10-09T01:01:58.999Z,1349744518.999 [science_to_67_70:Science:G] Running Loop=1 2012-10-09T01:01:58.999Z,1349744518.999 [science_to_67_70:Science:F] Running Loop=1 2012-10-09T01:01:58.999Z,1349744518.999 [science_to_67_70:Science:E] Running Loop=1 2012-10-09T01:01:58.999Z,1349744518.999 [science_to_67_70:Science:D] Running Loop=1 2012-10-09T01:01:58.999Z,1349744518.999 [science_to_67_70:Science:C] Running Loop=1 2012-10-09T01:01:59.007Z,1349744519.007 [DVL_micro](INFO): RSSI requested 2012-10-09T01:01:59.007Z,1349744519.007 [DVL_micro](INFO): ADCP requested 2012-10-09T01:01:59.007Z,1349744519.007 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:01:59.059Z,1349744519.059 [Turbulence_NPS](INFO): Resume powering up 2012-10-09T01:01:59.059Z,1349744519.059 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-09T01:01:59.084Z,1349744519.084 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:01:59.084Z,1349744519.084 [WetLabsBB2FL](INFO): Powering up 2012-10-09T01:01:59.180Z,1349744519.180 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:02:01.012Z,1349744521.012 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.406731 psu 2012-10-09T01:02:01.015Z,1349744521.015 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:02:01.015Z,1349744521.015 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-09T01:02:01.199Z,1349744521.199 [DVL_micro](DEBUG): cmdResponse: 00 2012-10-09T01:02:01.199Z,1349744521.199 [DVL_micro](INFO): Enabling RSSI output 2012-10-09T01:02:01.199Z,1349744521.200 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7 2012-10-09T01:02:01.216Z,1349744521.216 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.433130 psu 2012-10-09T01:02:01.383Z,1349744521.383 [DVL_micro](DEBUG): cmdResponse: 16 2012-10-09T01:02:01.383Z,1349744521.383 [DVL_micro](INFO): Enabling ADCP output 2012-10-09T01:02:01.383Z,1349744521.383 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3 2012-10-09T01:02:01.424Z,1349744521.424 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.354490 psu 2012-10-09T01:02:01.567Z,1349744521.567 [DVL_micro](DEBUG): cmdResponse: 03 16 2012-10-09T01:02:01.567Z,1349744521.567 [DVL_micro](INFO): Enabling NQ1 output 2012-10-09T01:02:01.567Z,1349744521.567 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-09T01:02:01.632Z,1349744521.632 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.386061 psu 2012-10-09T01:02:01.751Z,1349744521.751 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T01:02:01.751Z,1349744521.751 [DVL_micro](INFO): Querying output modes 2012-10-09T01:02:01.751Z,1349744521.751 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:02:01.755Z,1349744521.755 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T01:02:01.755Z,1349744521.755 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T01:02:01.755Z,1349744521.755 [DVL_micro](INFO): RSSI output enabled 2012-10-09T01:02:01.755Z,1349744521.755 [DVL_micro](INFO): ADCP output enabled 2012-10-09T01:02:01.756Z,1349744521.756 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:02:01.763Z,1349744521.763 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T01:02:01.840Z,1349744521.840 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.835449 psu 2012-10-09T01:02:01.899Z,1349744521.899 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=41 2012-10-09T01:02:01.955Z,1349744521.955 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=61 2012-10-09T01:02:02.007Z,1349744522.007 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17 2012-10-09T01:02:02.060Z,1349744522.060 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:02:02.228Z,1349744522.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.322371 psu 2012-10-09T01:02:02.345Z,1349744522.345 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:02:02.435Z,1349744522.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.988621 psu 2012-10-09T01:02:02.444Z,1349744522.444 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:02:02.544Z,1349744522.544 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:02:02.644Z,1349744522.644 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.737810 psu 2012-10-09T01:02:02.652Z,1349744522.652 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:02:02.783Z,1349744522.783 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2012-10-09T01:02:02.840Z,1349744522.840 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-09T01:02:02.852Z,1349744522.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.252041 psu 2012-10-09T01:02:02.895Z,1349744522.895 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2012-10-09T01:02:02.947Z,1349744522.947 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:02:03.059Z,1349744523.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.946320 psu 2012-10-09T01:02:03.227Z,1349744523.227 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:02:03.268Z,1349744523.268 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.064489 psu 2012-10-09T01:02:03.331Z,1349744523.331 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:02:03.431Z,1349744523.431 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:02:03.475Z,1349744523.475 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.495680 psu 2012-10-09T01:02:03.535Z,1349744523.535 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:02:03.684Z,1349744523.684 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.935249 psu 2012-10-09T01:02:03.879Z,1349744523.879 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2012-10-09T01:02:03.891Z,1349744523.891 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.728781 psu 2012-10-09T01:02:04.027Z,1349744524.027 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=60 2012-10-09T01:02:04.173Z,1349744524.173 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2012-10-09T01:02:04.196Z,1349744524.196 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.035490 psu 2012-10-09T01:02:04.315Z,1349744524.315 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:02:04.404Z,1349744524.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.060659 psu 2012-10-09T01:02:04.596Z,1349744524.596 [NAL9602](INFO): Powering down 2012-10-09T01:02:04.612Z,1349744524.612 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.938370 psu 2012-10-09T01:02:04.637Z,1349744524.637 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-09T01:02:04.751Z,1349744524.751 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36 2012-10-09T01:02:04.820Z,1349744524.820 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.340731 psu 2012-10-09T01:02:04.868Z,1349744524.868 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-09T01:02:04.983Z,1349744524.983 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:02:05.027Z,1349744525.027 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.648609 psu 2012-10-09T01:02:05.237Z,1349744525.237 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.005301 psu 2012-10-09T01:02:05.443Z,1349744525.443 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.917810 psu 2012-10-09T01:02:05.508Z,1349744525.508 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=29 2012-10-09T01:02:05.652Z,1349744525.652 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.312160 psu 2012-10-09T01:02:05.744Z,1349744525.744 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=37 2012-10-09T01:02:05.859Z,1349744525.859 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.913601 psu 2012-10-09T01:02:05.979Z,1349744525.979 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=13 2012-10-09T01:02:06.069Z,1349744526.069 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.814581 psu 2012-10-09T01:02:06.215Z,1349744526.215 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:02:06.272Z,1349744526.272 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.778521 psu 2012-10-09T01:02:06.479Z,1349744526.479 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.413540 psu 2012-10-09T01:02:06.687Z,1349744526.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.218870 psu 2012-10-09T01:02:06.991Z,1349744526.991 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.227350 psu 2012-10-09T01:02:07.135Z,1349744527.135 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28 2012-10-09T01:02:07.199Z,1349744527.199 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.799259 psu 2012-10-09T01:02:07.405Z,1349744527.405 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.232901 psu 2012-10-09T01:02:07.608Z,1349744527.608 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:02:07.615Z,1349744527.615 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.637650 psu 2012-10-09T01:02:07.823Z,1349744527.823 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.915039 psu 2012-10-09T01:02:08.032Z,1349744528.032 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.560221 psu 2012-10-09T01:02:08.083Z,1349744528.083 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13 2012-10-09T01:02:08.236Z,1349744528.237 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.253080 psu 2012-10-09T01:02:08.444Z,1349744528.444 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.525299 psu 2012-10-09T01:02:08.558Z,1349744528.558 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:02:08.652Z,1349744528.652 [CTD_NeilBrown](ERROR): Salinity reading out of range: 14.662710 psu 2012-10-09T01:02:08.859Z,1349744528.859 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.108259 psu 2012-10-09T01:02:09.068Z,1349744529.068 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.903530 psu 2012-10-09T01:02:09.276Z,1349744529.276 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.372770 psu 2012-10-09T01:02:09.440Z,1349744529.440 [DVL_micro](DEBUG): NQ1 2012-10-09T01:02:09.479Z,1349744529.479 [DVL_micro](DEBUG): ADCP 2012-10-09T01:02:09.484Z,1349744529.484 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.337330 psu 2012-10-09T01:02:09.787Z,1349744529.787 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.355600 psu 2012-10-09T01:02:09.996Z,1349744529.996 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.283939 psu 2012-10-09T01:02:10.203Z,1349744530.203 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.705521 psu 2012-10-09T01:02:10.299Z,1349744530.299 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=0 2012-10-09T01:02:10.412Z,1349744530.411 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.003941 psu 2012-10-09T01:02:10.619Z,1349744530.619 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.742220 psu 2012-10-09T01:02:10.828Z,1349744530.829 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.396641 psu 2012-10-09T01:02:11.036Z,1349744531.036 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.492640 psu 2012-10-09T01:02:11.141Z,1349744531.141 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=40 2012-10-09T01:02:11.241Z,1349744531.240 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.206869 psu 2012-10-09T01:02:11.451Z,1349744531.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.129259 psu 2012-10-09T01:02:11.659Z,1349744531.659 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.527349 psu 2012-10-09T01:02:11.867Z,1349744531.867 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.464880 psu 2012-10-09T01:02:11.975Z,1349744531.975 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=20 2012-10-09T01:02:12.075Z,1349744532.075 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.084080 psu 2012-10-09T01:02:12.383Z,1349744532.383 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.582279 psu 2012-10-09T01:02:12.588Z,1349744532.588 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.176359 psu 2012-10-09T01:02:12.795Z,1349744532.796 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.438580 psu 2012-10-09T01:02:12.811Z,1349744532.811 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-09T01:02:13.007Z,1349744533.007 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.105700 psu 2012-10-09T01:02:13.150Z,1349744533.150 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1864 2012-10-09T01:02:13.151Z,1349744533.151 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1244 2012-10-09T01:02:13.152Z,1349744533.152 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=624 2012-10-09T01:02:13.152Z,1349744533.152 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:02:13.212Z,1349744533.212 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.078480 psu 2012-10-09T01:02:13.392Z,1349744533.392 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2012-10-09T01:02:13.420Z,1349744533.420 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.883909 psu 2012-10-09T01:02:13.495Z,1349744533.495 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2012-10-09T01:02:13.595Z,1349744533.595 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:02:13.628Z,1349744533.628 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.026030 psu 2012-10-09T01:02:13.697Z,1349744533.697 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:02:13.835Z,1349744533.835 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2012-10-09T01:02:13.836Z,1349744533.836 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.827020 psu 2012-10-09T01:02:13.891Z,1349744533.891 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2012-10-09T01:02:13.942Z,1349744533.942 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2012-10-09T01:02:13.995Z,1349744533.995 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:02:14.044Z,1349744534.044 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.173059 psu 2012-10-09T01:02:14.102Z,1349744534.102 [Turbulence_NPS](ERROR): No communication! Re-initializing 2012-10-09T01:02:14.103Z,1349744534.103 [Turbulence_NPS] Communications Fault, FailCount= 1 2012-10-09T01:02:14.103Z,1349744534.103 [Turbulence_NPS](ERROR): Communications Fault 2012-10-09T01:02:14.105Z,1349744534.105 [Turbulence_NPS](INFO): Uninitialize Powering down 2012-10-09T01:02:14.233Z,1349744534.233 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS 2012-10-09T01:02:14.233Z,1349744534.233 [CBIT](INFO): Clearing failed state for component Turbulence_NPS 2012-10-09T01:02:14.233Z,1349744534.233 [Turbulence_NPS] No Fault, FailCount= 1 2012-10-09T01:02:14.253Z,1349744534.253 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.097719 psu 2012-10-09T01:02:14.279Z,1349744534.279 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2012-10-09T01:02:14.380Z,1349744534.380 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=36 2012-10-09T01:02:14.459Z,1349744534.459 [CTD_NeilBrown](ERROR): Salinity reading out of range: 14.556220 psu 2012-10-09T01:02:14.479Z,1349744534.479 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=8 2012-10-09T01:02:14.507Z,1349744534.507 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS. 2012-10-09T01:02:14.507Z,1349744534.507 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-09T01:02:14.582Z,1349744534.582 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:02:14.667Z,1349744534.667 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.284719 psu 2012-10-09T01:02:14.875Z,1349744534.875 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.621861 psu 2012-10-09T01:02:14.927Z,1349744534.927 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2012-10-09T01:02:15.075Z,1349744535.075 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=60 2012-10-09T01:02:15.176Z,1349744535.176 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.834681 psu 2012-10-09T01:02:15.216Z,1349744535.216 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2012-10-09T01:02:15.364Z,1349744535.364 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:02:15.383Z,1349744535.383 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.029131 psu 2012-10-09T01:02:15.592Z,1349744535.592 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.317869 psu 2012-10-09T01:02:15.683Z,1349744535.683 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-09T01:02:15.799Z,1349744535.799 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.610359 psu 2012-10-09T01:02:15.801Z,1349744535.801 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36 2012-10-09T01:02:15.925Z,1349744535.925 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-09T01:02:16.008Z,1349744536.008 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.138620 psu 2012-10-09T01:02:16.031Z,1349744536.031 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:02:16.216Z,1349744536.216 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.270590 psu 2012-10-09T01:02:16.424Z,1349744536.424 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.295139 psu 2012-10-09T01:02:16.587Z,1349744536.587 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=316 2012-10-09T01:02:16.632Z,1349744536.632 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.497801 psu 2012-10-09T01:02:16.791Z,1349744536.791 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36 2012-10-09T01:02:16.837Z,1349744536.837 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.533820 psu 2012-10-09T01:02:17.027Z,1349744537.027 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12 2012-10-09T01:02:17.043Z,1349744537.043 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.599079 psu 2012-10-09T01:02:17.251Z,1349744537.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.854010 psu 2012-10-09T01:02:17.263Z,1349744537.263 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:02:17.460Z,1349744537.460 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.306271 psu 2012-10-09T01:02:17.667Z,1349744537.667 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.379801 psu 2012-10-09T01:02:17.972Z,1349744537.972 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.248560 psu 2012-10-09T01:02:18.179Z,1349744538.179 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.869431 psu 2012-10-09T01:02:18.187Z,1349744538.187 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=60 2012-10-09T01:02:18.387Z,1349744538.387 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.887030 psu 2012-10-09T01:02:18.595Z,1349744538.595 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.798340 psu 2012-10-09T01:02:18.660Z,1349744538.660 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36 2012-10-09T01:02:18.804Z,1349744538.804 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.257510 psu 2012-10-09T01:02:19.012Z,1349744539.012 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.286360 psu 2012-10-09T01:02:19.131Z,1349744539.131 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12 2012-10-09T01:02:19.216Z,1349744539.216 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.387920 psu 2012-10-09T01:02:19.424Z,1349744539.424 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.964380 psu 2012-10-09T01:02:19.608Z,1349744539.608 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:02:19.632Z,1349744539.632 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.562450 psu 2012-10-09T01:02:19.839Z,1349744539.839 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.163321 psu 2012-10-09T01:02:20.048Z,1349744540.048 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.533440 psu 2012-10-09T01:02:20.255Z,1349744540.255 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.130199 psu 2012-10-09T01:02:20.463Z,1349744540.464 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.226021 psu 2012-10-09T01:02:20.491Z,1349744540.491 [DVL_micro](DEBUG): NQ1 2012-10-09T01:02:20.522Z,1349744540.522 [DVL_micro](DEBUG): ADCP 2012-10-09T01:02:20.767Z,1349744540.767 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.052950 psu 2012-10-09T01:02:20.975Z,1349744540.975 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.655560 psu 2012-10-09T01:02:21.183Z,1349744541.183 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.937679 psu 2012-10-09T01:02:21.351Z,1349744541.351 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=0 2012-10-09T01:02:21.392Z,1349744541.392 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.375370 psu 2012-10-09T01:02:21.600Z,1349744541.600 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.485701 psu 2012-10-09T01:02:21.804Z,1349744541.804 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.235319 psu 2012-10-09T01:02:22.011Z,1349744542.011 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.806620 psu 2012-10-09T01:02:22.187Z,1349744542.187 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=11 2012-10-09T01:02:22.220Z,1349744542.220 [CTD_NeilBrown](ERROR): Salinity reading out of range: 27.273649 psu 2012-10-09T01:02:22.427Z,1349744542.427 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.311140 psu 2012-10-09T01:02:22.635Z,1349744542.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.123810 psu 2012-10-09T01:02:22.843Z,1349744542.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.399561 psu 2012-10-09T01:02:23.027Z,1349744543.027 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=51 2012-10-09T01:02:23.051Z,1349744543.051 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.400909 psu 2012-10-09T01:02:23.260Z,1349744543.260 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.458521 psu 2012-10-09T01:02:23.560Z,1349744543.560 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.923929 psu 2012-10-09T01:02:23.768Z,1349744543.768 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.576521 psu 2012-10-09T01:02:23.865Z,1349744543.865 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-09T01:02:23.976Z,1349744543.976 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.345329 psu 2012-10-09T01:02:24.097Z,1349744544.097 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1128 2012-10-09T01:02:24.098Z,1349744544.098 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=508 2012-10-09T01:02:24.110Z,1349744544.110 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2012-10-09T01:02:24.160Z,1349744544.159 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:02:24.184Z,1349744544.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.970940 psu 2012-10-09T01:02:24.392Z,1349744544.392 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.362301 psu 2012-10-09T01:02:24.439Z,1349744544.439 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:02:24.543Z,1349744544.543 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=29 2012-10-09T01:02:24.600Z,1349744544.600 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.999980 psu 2012-10-09T01:02:24.643Z,1349744544.643 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=3 2012-10-09T01:02:24.747Z,1349744544.747 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:02:24.808Z,1349744544.808 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.938919 psu 2012-10-09T01:02:24.883Z,1349744544.883 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2012-10-09T01:02:24.939Z,1349744544.939 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-09T01:02:24.996Z,1349744544.996 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2012-10-09T01:02:25.017Z,1349744545.017 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.967590 psu 2012-10-09T01:02:25.047Z,1349744545.047 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:02:25.223Z,1349744545.224 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.558411 psu 2012-10-09T01:02:25.327Z,1349744545.327 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:02:25.431Z,1349744545.431 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:02:25.433Z,1349744545.432 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.886499 psu 2012-10-09T01:02:25.531Z,1349744545.531 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:02:25.636Z,1349744545.636 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:02:25.640Z,1349744545.640 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.276880 psu 2012-10-09T01:02:25.848Z,1349744545.848 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.283319 psu 2012-10-09T01:02:25.983Z,1349744545.983 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56 2012-10-09T01:02:26.055Z,1349744546.055 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.101709 psu 2012-10-09T01:02:26.128Z,1349744546.128 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2012-10-09T01:02:26.271Z,1349744546.271 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=33 2012-10-09T01:02:26.359Z,1349744546.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.839010 psu 2012-10-09T01:02:26.415Z,1349744546.415 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:02:26.563Z,1349744546.563 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.368481 psu 2012-10-09T01:02:26.736Z,1349744546.736 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-09T01:02:26.772Z,1349744546.772 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.138929 psu 2012-10-09T01:02:26.851Z,1349744546.851 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:02:26.967Z,1349744546.967 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-09T01:02:26.980Z,1349744546.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.265280 psu 2012-10-09T01:02:27.022Z,1349744547.022 [CBIT](CRITICAL): Environmental Failure. Press:13.920176 PSI. Humidity:18%. Temp:21 C. ABORTING MISSION 2012-10-09T01:02:27.083Z,1349744547.083 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:02:27.187Z,1349744547.187 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.283270 psu 2012-10-09T01:02:27.357Z,1349744547.358 [science_to_67_70] Stopped 2012-10-09T01:02:27.358Z,1349744547.358 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70 2012-10-09T01:02:27.358Z,1349744547.358 [science_to_67_70:AltEnvWrapper] Stopped 2012-10-09T01:02:27.358Z,1349744547.358 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper 2012-10-09T01:02:27.358Z,1349744547.358 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped 2012-10-09T01:02:27.358Z,1349744547.358 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-10-09T01:02:27.363Z,1349744547.363 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped 2012-10-09T01:02:27.363Z,1349744547.363 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-10-09T01:02:27.363Z,1349744547.363 [science_to_67_70:B.DepthEnvelope] Stopped 2012-10-09T01:02:27.363Z,1349744547.363 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize. 2012-10-09T01:02:27.363Z,1349744547.363 [science_to_67_70:OffshoreWrapper] Stopped 2012-10-09T01:02:27.363Z,1349744547.363 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science:A] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science:B] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science:C] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science:D] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science:E] Stopped 2012-10-09T01:02:27.364Z,1349744547.364 [science_to_67_70:Science:F] Stopped 2012-10-09T01:02:27.364Z,1349744547.365 [science_to_67_70:Science:G] Stopped 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:Science:PeakDetectChl] Stopped 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:G.Buoyancy] Stopped 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:H.SetSpeed] Stopped 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:02:27.365Z,1349744547.365 [science_to_67_70:I.DepthEnvelope] Stopped 2012-10-09T01:02:27.366Z,1349744547.365 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize. 2012-10-09T01:02:27.366Z,1349744547.365 [science_to_67_70:J.YoYo] Stopped 2012-10-09T01:02:27.366Z,1349744547.366 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent. 2012-10-09T01:02:27.366Z,1349744547.366 [science_to_67_70:Wpt.Point] Stopped 2012-10-09T01:02:27.384Z,1349744547.384 [Turbulence_NPS](INFO): Pause powering down 2012-10-09T01:02:27.396Z,1349744547.396 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.780609 psu 2012-10-09T01:02:27.471Z,1349744547.471 [WetLabsBB2FL](INFO): Powering down 2012-10-09T01:02:27.518Z,1349744547.518 [DVL_micro](INFO): pause:Powering down 2012-10-09T01:02:27.891Z,1349744547.891 [MissionManager](IMPORTANT): Started mission Default 2012-10-09T01:02:27.891Z,1349744547.891 [Default] Running Loop=1 2012-10-09T01:02:27.891Z,1349744547.891 [Default](INFO): Aggregate::initialize Default 2012-10-09T01:02:27.891Z,1349744547.891 [Default:D.SetSpeed] Running Loop=1 2012-10-09T01:02:27.892Z,1349744547.892 [Default:D.SetSpeed](DEBUG): Initialize. 2012-10-09T01:02:27.892Z,1349744547.892 [Default:E.GoToSurface] Running Loop=1 2012-10-09T01:02:27.892Z,1349744547.892 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:02:27.892Z,1349744547.892 [Default:Iridium] Running Loop=1 2012-10-09T01:02:27.892Z,1349744547.892 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-10-09T01:02:27.892Z,1349744547.892 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-09T01:02:27.892Z,1349744547.892 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-10-09T01:02:27.892Z,1349744547.892 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-10-09T01:02:27.892Z,1349744547.892 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:02:27.895Z,1349744547.895 [Default:Iridium:B.GoToSurface] Stopped 2012-10-09T01:02:27.895Z,1349744547.895 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:02:27.895Z,1349744547.895 [Default:Iridium:Read_Iridium] Running Loop=1 2012-10-09T01:02:28.676Z,1349744548.676 [NAL9602](INFO): Powering up 2012-10-09T01:02:39.495Z,1349744559.495 [NAL9602](INFO): NAL9602 initialized 2012-10-09T01:02:59.054Z,1349744579.054 [CBIT](CRITICAL): Environmental Failure. Press:14.982194 PSI. Humidity:18%. Temp:21 C. ABORTING MISSION 2012-10-09T01:03:38.855Z,1349744618.855 [CBIT](CRITICAL): Environmental Failure. Press:15.513202 PSI. Humidity:17%. Temp:21 C. ABORTING MISSION 2012-10-09T01:04:02.595Z,1349744642.595 [CommandLine](IMPORTANT): got command show stack 2012-10-09T01:04:02.595Z,1349744642.595 [CommandLine](INFO): Behavior Stack: 2012-10-09T01:04:02.595Z,1349744642.595 [Default:Iridium](INFO): Priority 0: Default:Iridium:A.SetSpeed 2012-10-09T01:04:02.596Z,1349744642.596 [Default:Iridium](INFO): Priority 1: Default:Iridium:Read_Iridium 2012-10-09T01:04:02.596Z,1349744642.596 [Default](INFO): Priority 2: Default:D.SetSpeed 2012-10-09T01:04:02.596Z,1349744642.596 [Default](INFO): Priority 3: Default:E.GoToSurface 2012-10-09T01:04:10.983Z,1349744650.983 [CommandLine](IMPORTANT): got command show variable threshold 2012-10-09T01:04:10.998Z,1349744650.999 [CommandLine](INFO): CBIT.humidityThreshold (percent) 2012-10-09T01:04:10.999Z,1349744650.999 [CommandLine](INFO): CBIT.pressureThreshold (pound_per_square_inch) 2012-10-09T01:04:10.999Z,1349744650.999 [CommandLine](INFO): CBIT.tempThreshold (fahrenheit) 2012-10-09T01:04:11.000Z,1349744651.000 [CommandLine](INFO): CBIT.battTempThreshold (celsius) 2012-10-09T01:04:11.001Z,1349744651.000 [CommandLine](INFO): CBIT.gfChan0_Threshold (milliampere) 2012-10-09T01:04:11.001Z,1349744651.001 [CommandLine](INFO): CBIT.gfChan1_Threshold (milliampere) 2012-10-09T01:04:11.001Z,1349744651.001 [CommandLine](INFO): CBIT.gfChan2_Threshold (milliampere) 2012-10-09T01:04:11.002Z,1349744651.002 [CommandLine](INFO): CBIT.gfChan4_Threshold (milliampere) 2012-10-09T01:04:11.002Z,1349744651.002 [CommandLine](INFO): CBIT.gfChan5_Threshold (milliampere) 2012-10-09T01:04:11.004Z,1349744651.003 [CommandLine](INFO): IBIT.batteryCapacityThreshold (ampere_hour) 2012-10-09T01:04:11.004Z,1349744651.004 [CommandLine](INFO): IBIT.batteryVoltageThreshold (volt) 2012-10-09T01:04:11.017Z,1349744651.017 [CommandLine](INFO): VerticalControl.surfaceThreshold (meter) 2012-10-09T01:04:28.751Z,1349744668.751 [CommandLine](IMPORTANT): got command set CBIT.pressureThreshold 0.005000 pound_per_square_inch 2012-10-09T01:04:35.295Z,1349744675.295 [CommandLine](IMPORTANT): got command get CBIT.pressureThreshold 2012-10-09T01:04:35.295Z,1349744675.295 [CommandLine](IMPORTANT): CBIT.pressureThreshold 1.500000 pound_per_square_inch 2012-10-09T01:04:39.348Z,1349744679.348 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:17%. Temp:21 C. ABORTING MISSION 2012-10-09T01:04:44.771Z,1349744684.771 [CommandLine](IMPORTANT): got command configSet CBIT.pressureThreshold 0.001000 pound_per_square_inch 2012-10-09T01:04:48.223Z,1349744688.223 [CommandLine](IMPORTANT): got command get CBIT.pressureThreshold 2012-10-09T01:04:48.223Z,1349744688.223 [CommandLine](IMPORTANT): CBIT.pressureThreshold 0.001000 pound_per_square_inch 2012-10-09T01:05:12.675Z,1349744712.675 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml 2012-10-09T01:05:12.675Z,1349744712.675 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml 2012-10-09T01:05:12.738Z,1349744712.738 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m 2012-10-09T01:05:12.743Z,1349744712.743 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m 2012-10-09T01:05:12.745Z,1349744712.745 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m 2012-10-09T01:05:12.748Z,1349744712.748 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m 2012-10-09T01:05:12.751Z,1349744712.751 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m 2012-10-09T01:05:12.753Z,1349744712.753 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg 2012-10-09T01:05:12.757Z,1349744712.757 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg 2012-10-09T01:05:12.759Z,1349744712.759 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg 2012-10-09T01:05:12.762Z,1349744712.761 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg 2012-10-09T01:05:12.765Z,1349744712.765 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min 2012-10-09T01:05:12.768Z,1349744712.768 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s 2012-10-09T01:05:12.770Z,1349744712.770 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h 2012-10-09T01:05:12.773Z,1349744712.773 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a 2012-10-09T01:05:12.776Z,1349744712.776 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-10-09T01:05:12.781Z,1349744712.781 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait. 2012-10-09T01:05:12.782Z,1349744712.782 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-10-09T01:05:12.789Z,1349744712.789 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-10-09T01:05:12.808Z,1349744712.808 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait. 2012-10-09T01:05:12.809Z,1349744712.809 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-10-09T01:05:12.857Z,1349744712.857 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-10-09T01:05:12.864Z,1349744712.864 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-10-09T01:05:12.866Z,1349744712.866 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s 2012-10-09T01:05:12.870Z,1349744712.870 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min 2012-10-09T01:05:12.879Z,1349744712.879 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-10-09T01:05:12.893Z,1349744712.893 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg 2012-10-09T01:05:12.906Z,1349744712.906 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2012-10-09T01:05:13.037Z,1349744713.037 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool 2012-10-09T01:05:13.040Z,1349744713.040 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool 2012-10-09T01:05:13.043Z,1349744713.043 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool 2012-10-09T01:05:13.045Z,1349744713.045 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool 2012-10-09T01:05:13.047Z,1349744713.047 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool 2012-10-09T01:05:13.049Z,1349744713.049 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectRSSIActive = 0 bool 2012-10-09T01:05:13.052Z,1349744713.052 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledAanderaaO2 = 0.000000 n/a 2012-10-09T01:05:13.054Z,1349744713.054 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledNeilBrown = 1.000000 n/a 2012-10-09T01:05:13.057Z,1349744713.057 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledTurbulence_NPS = 1.000000 n/a 2012-10-09T01:05:13.059Z,1349744713.059 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledWetLabsBB2FL = 1.000000 n/a 2012-10-09T01:05:13.062Z,1349744713.062 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a 2012-10-09T01:05:13.065Z,1349744713.065 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledISUS = 0.000000 n/a 2012-10-09T01:05:13.068Z,1349744713.068 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l 2012-10-09T01:05:13.071Z,1349744713.071 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m 2012-10-09T01:05:13.073Z,1349744713.073 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg 2012-10-09T01:05:13.075Z,1349744713.076 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg 2012-10-09T01:05:13.078Z,1349744713.078 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l 2012-10-09T01:05:13.080Z,1349744713.080 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m 2012-10-09T01:05:13.083Z,1349744713.083 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg 2012-10-09T01:05:13.085Z,1349744713.085 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg 2012-10-09T01:05:13.088Z,1349744713.088 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSI = 0.000000 dB 2012-10-09T01:05:13.091Z,1349744713.091 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSIDepth = 0.000000 m 2012-10-09T01:05:13.093Z,1349744713.093 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILatitude = nan arcdeg 2012-10-09T01:05:13.097Z,1349744713.097 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILongitude = nan arcdeg 2012-10-09T01:05:13.108Z,1349744713.108 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-09T01:05:13.116Z,1349744713.116 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-09T01:05:13.123Z,1349744713.124 [science_to_67_70:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2012-10-09T01:05:13.132Z,1349744713.132 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool 2012-10-09T01:05:13.133Z,1349744713.133 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool 2012-10-09T01:05:13.134Z,1349744713.134 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy. 2012-10-09T01:05:13.138Z,1349744713.138 [science_to_67_70:H.SetSpeed](DEBUG): Construct. 2012-10-09T01:05:13.143Z,1349744713.143 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-10-09T01:05:13.149Z,1349744713.149 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo. 2012-10-09T01:05:13.155Z,1349744713.155 [science_to_67_70:Wpt.Point](DEBUG): Construct. 2012-10-09T01:05:13.175Z,1349744713.175 [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-09T01:05:13.176Z,1349744713.176 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml 2012-10-09T01:05:13.197Z,1349744713.197 [Default] Stopped 2012-10-09T01:05:13.197Z,1349744713.197 [Default](INFO): Aggregate::uninitialize Default 2012-10-09T01:05:13.197Z,1349744713.197 [Default:Iridium] Stopped 2012-10-09T01:05:13.197Z,1349744713.197 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-10-09T01:05:13.197Z,1349744713.197 [Default:Iridium:A.SetSpeed] Stopped 2012-10-09T01:05:13.197Z,1349744713.197 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:05:13.197Z,1349744713.197 [Default:Iridium:Read_Iridium] Stopped 2012-10-09T01:05:13.197Z,1349744713.197 [Default:D.SetSpeed] Stopped 2012-10-09T01:05:13.198Z,1349744713.197 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:05:13.198Z,1349744713.198 [Default:E.GoToSurface] Stopped 2012-10-09T01:05:13.198Z,1349744713.198 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:05:13.198Z,1349744713.198 [MissionManager](IMPORTANT): Started mission science_to_67_70 2012-10-09T01:05:13.198Z,1349744713.198 [science_to_67_70] Running Loop=1 2012-10-09T01:05:13.198Z,1349744713.198 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70 2012-10-09T01:05:13.198Z,1349744713.198 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-10-09T01:05:13.198Z,1349744713.198 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science:A] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science:B] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science:C] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science:D] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.199 [science_to_67_70:Science:E] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.200 [science_to_67_70:Science:F] Running Loop=1 2012-10-09T01:05:13.199Z,1349744713.200 [science_to_67_70:Science:G] Running Loop=1 2012-10-09T01:05:13.200Z,1349744713.200 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-09T01:05:13.200Z,1349744713.200 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-10-09T01:05:13.200Z,1349744713.200 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-09T01:05:13.200Z,1349744713.200 [science_to_67_70:H.SetSpeed](DEBUG): Initialize. 2012-10-09T01:05:13.200Z,1349744713.200 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-09T01:05:13.200Z,1349744713.200 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-10-09T01:05:13.201Z,1349744713.201 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-09T01:05:13.201Z,1349744713.201 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent. 2012-10-09T01:05:13.201Z,1349744713.201 [science_to_67_70:SURFACECOMMS] Running Loop=1 2012-10-09T01:05:13.201Z,1349744713.201 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS 2012-10-09T01:05:13.201Z,1349744713.201 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-10-09T01:05:13.201Z,1349744713.201 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:05:13.202Z,1349744713.202 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-09T01:05:13.203Z,1349744713.203 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-09T01:05:13.205Z,1349744713.205 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-09T01:05:13.206Z,1349744713.206 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-09T01:05:13.207Z,1349744713.207 [science_to_67_70:Science] Running Loop=1 2012-10-09T01:05:13.207Z,1349744713.207 [science_to_67_70:Science:PeakDetectChl] Running Loop=1 2012-10-09T01:05:13.207Z,1349744713.207 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl 2012-10-09T01:05:13.208Z,1349744713.208 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2012-10-09T01:05:13.208Z,1349744713.208 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize. 2012-10-09T01:05:13.209Z,1349744713.209 [science_to_67_70:Science:G] Running Loop=1 2012-10-09T01:05:13.210Z,1349744713.210 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2012-10-09T01:05:13.211Z,1349744713.211 [science_to_67_70:Science:F] Running Loop=1 2012-10-09T01:05:13.211Z,1349744713.211 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI 2012-10-09T01:05:13.212Z,1349744713.212 [science_to_67_70:Science:E] Running Loop=1 2012-10-09T01:05:13.213Z,1349744713.213 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity 2012-10-09T01:05:13.213Z,1349744713.214 [science_to_67_70:Science:D] Running Loop=1 2012-10-09T01:05:13.214Z,1349744713.214 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond 2012-10-09T01:05:13.215Z,1349744713.215 [science_to_67_70:Science:C] Running Loop=1 2012-10-09T01:05:13.215Z,1349744713.215 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-10-09T01:05:13.216Z,1349744713.216 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-10-09T01:05:13.218Z,1349744713.218 [science_to_67_70:SURFACECOMMS:B] Running Loop=1 2012-10-09T01:05:13.218Z,1349744713.218 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B 2012-10-09T01:05:13.218Z,1349744713.218 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1 2012-10-09T01:05:13.218Z,1349744713.218 [science_to_67_70:OffshoreWrapper] Running Loop=1 2012-10-09T01:05:13.218Z,1349744713.218 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper 2012-10-09T01:05:13.218Z,1349744713.218 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-10-09T01:05:13.219Z,1349744713.218 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-10-09T01:05:13.219Z,1349744713.219 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1 2012-10-09T01:05:13.219Z,1349744713.219 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-10-09T01:05:13.220Z,1349744713.220 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1 2012-10-09T01:05:13.221Z,1349744713.220 [science_to_67_70:B.DepthEnvelope] Running Loop=1 2012-10-09T01:05:13.222Z,1349744713.222 [science_to_67_70:AltEnvWrapper] Running Loop=1 2012-10-09T01:05:13.222Z,1349744713.222 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper 2012-10-09T01:05:13.222Z,1349744713.222 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-10-09T01:05:13.222Z,1349744713.222 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-10-09T01:05:13.222Z,1349744713.222 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1 2012-10-09T01:05:13.222Z,1349744713.223 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component. 2012-10-09T01:05:13.223Z,1349744713.223 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1 2012-10-09T01:05:13.443Z,1349744713.443 [Turbulence_NPS](INFO): Resume powering up 2012-10-09T01:05:13.443Z,1349744713.443 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-09T01:05:13.532Z,1349744713.532 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:05:13.532Z,1349744713.532 [WetLabsBB2FL](INFO): Powering up 2012-10-09T01:05:13.564Z,1349744713.564 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:05:13.571Z,1349744713.571 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:05:13.572Z,1349744713.572 [DVL_micro](INFO): resume:Powering up 2012-10-09T01:05:13.572Z,1349744713.572 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:05:14.111Z,1349744714.111 [science_to_67_70:J.YoYo] Preempted 2012-10-09T01:05:14.111Z,1349744714.111 [science_to_67_70:I.DepthEnvelope] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:H.SetSpeed] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:G.Buoyancy] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:Science] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:Science:C] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:Science:D] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:Science:E] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:Science:F] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:Science:G] Preempted 2012-10-09T01:05:14.112Z,1349744714.112 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-09T01:05:14.113Z,1349744714.113 [science_to_67_70:SURFACECOMMS:B:A] Stopped 2012-10-09T01:05:14.113Z,1349744714.114 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1 2012-10-09T01:05:14.155Z,1349744714.155 [Turbulence_NPS](INFO): Pause powering down 2012-10-09T01:05:14.506Z,1349744714.506 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-10-09T01:05:14.727Z,1349744714.727 [WetLabsBB2FL](INFO): Powering down 2012-10-09T01:05:15.313Z,1349744715.313 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2012-10-09T01:05:15.581Z,1349744715.581 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:05:15.582Z,1349744715.582 [DVL_micro](INFO): Querying output modes 2012-10-09T01:05:15.582Z,1349744715.582 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:05:15.591Z,1349744715.591 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T01:05:15.591Z,1349744715.591 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T01:05:15.591Z,1349744715.591 [DVL_micro](INFO): RSSI output enabled 2012-10-09T01:05:15.591Z,1349744715.591 [DVL_micro](INFO): ADCP output enabled 2012-10-09T01:05:15.591Z,1349744715.591 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:05:15.599Z,1349744715.599 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T01:05:15.601Z,1349744715.601 [DVL_micro](INFO): RSSI unrequested 2012-10-09T01:05:15.601Z,1349744715.601 [DVL_micro](INFO): ADCP unrequested 2012-10-09T01:05:15.601Z,1349744715.601 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:05:17.611Z,1349744717.611 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:05:17.611Z,1349744717.611 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-09T01:05:17.795Z,1349744717.795 [DVL_micro](DEBUG): cmdResponse: 00 2012-10-09T01:05:17.795Z,1349744717.795 [DVL_micro](INFO): Enabling NQ1 output 2012-10-09T01:05:17.795Z,1349744717.795 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-09T01:05:17.966Z,1349744717.967 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-09T01:05:17.967Z,1349744717.967 [DVL_micro](INFO): Querying output modes 2012-10-09T01:05:17.967Z,1349744717.967 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:05:17.970Z,1349744717.970 [DVL_micro](DEBUG): cmdResponse: 01 2012-10-09T01:05:17.971Z,1349744717.971 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T01:05:17.971Z,1349744717.971 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:05:17.975Z,1349744717.975 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T01:05:20.499Z,1349744720.499 [DVL_micro](DEBUG): NQ1 2012-10-09T01:05:21.539Z,1349744721.539 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2012-10-09T01:05:21.568Z,1349744721.568 [science_to_67_70:SURFACECOMMS:B:B] Stopped 2012-10-09T01:05:21.568Z,1349744721.568 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1 2012-10-09T01:05:21.960Z,1349744721.960 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-10-09T01:05:21.961Z,1349744721.961 [science_to_67_70:SURFACECOMMS:B:C] Stopped 2012-10-09T01:05:21.961Z,1349744721.961 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B 2012-10-09T01:05:21.961Z,1349744721.961 [science_to_67_70:SURFACECOMMS:B] Stopped 2012-10-09T01:05:21.961Z,1349744721.961 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:SURFACECOMMS] Stopped 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:Wpt.Point] Running Loop=1 2012-10-09T01:05:21.963Z,1349744721.963 [science_to_67_70:Wpt.Point](DEBUG): Initialize. 2012-10-09T01:05:22.366Z,1349744722.365 [science_to_67_70:J.YoYo] Running Loop=1 2012-10-09T01:05:22.366Z,1349744722.366 [science_to_67_70:I.DepthEnvelope] Running Loop=1 2012-10-09T01:05:22.367Z,1349744722.367 [science_to_67_70:H.SetSpeed] Running Loop=1 2012-10-09T01:05:22.367Z,1349744722.367 [science_to_67_70:G.Buoyancy] Running Loop=1 2012-10-09T01:05:22.368Z,1349744722.368 [science_to_67_70:Science] Running Loop=1 2012-10-09T01:05:22.369Z,1349744722.369 [science_to_67_70:Science:G] Running Loop=1 2012-10-09T01:05:22.369Z,1349744722.369 [science_to_67_70:Science:F] Running Loop=1 2012-10-09T01:05:22.369Z,1349744722.369 [science_to_67_70:Science:E] Running Loop=1 2012-10-09T01:05:22.369Z,1349744722.369 [science_to_67_70:Science:D] Running Loop=1 2012-10-09T01:05:22.369Z,1349744722.369 [science_to_67_70:Science:C] Running Loop=1 2012-10-09T01:05:22.375Z,1349744722.375 [DVL_micro](INFO): RSSI requested 2012-10-09T01:05:22.375Z,1349744722.375 [DVL_micro](INFO): ADCP requested 2012-10-09T01:05:22.375Z,1349744722.375 [DVL_micro](INFO): Cycling power to configure device. 2012-10-09T01:05:22.404Z,1349744722.404 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:05:22.404Z,1349744722.404 [WetLabsBB2FL](INFO): Powering up 2012-10-09T01:05:22.639Z,1349744722.639 [Turbulence_NPS](INFO): Resume powering up 2012-10-09T01:05:22.640Z,1349744722.640 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1 2012-10-09T01:05:22.748Z,1349744722.748 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2012-10-09T01:05:24.227Z,1349744724.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.007370 psu 2012-10-09T01:05:24.383Z,1349744724.383 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2012-10-09T01:05:24.383Z,1349744724.383 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0 2012-10-09T01:05:24.435Z,1349744724.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.994431 psu 2012-10-09T01:05:24.568Z,1349744724.568 [DVL_micro](DEBUG): cmdResponse: 00 2012-10-09T01:05:24.568Z,1349744724.568 [DVL_micro](INFO): Enabling RSSI output 2012-10-09T01:05:24.568Z,1349744724.568 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7 2012-10-09T01:05:24.644Z,1349744724.644 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.806890 psu 2012-10-09T01:05:24.740Z,1349744724.740 [DVL_micro](DEBUG): cmdResponse: 16 2012-10-09T01:05:24.740Z,1349744724.740 [DVL_micro](INFO): Enabling ADCP output 2012-10-09T01:05:24.741Z,1349744724.741 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3 2012-10-09T01:05:24.852Z,1349744724.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.341970 psu 2012-10-09T01:05:24.913Z,1349744724.913 [DVL_micro](DEBUG): cmdResponse: 03 16 2012-10-09T01:05:24.913Z,1349744724.913 [DVL_micro](INFO): Enabling NQ1 output 2012-10-09T01:05:24.914Z,1349744724.914 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1 2012-10-09T01:05:25.060Z,1349744725.060 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.832621 psu 2012-10-09T01:05:25.084Z,1349744725.084 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T01:05:25.084Z,1349744725.084 [DVL_micro](INFO): Querying output modes 2012-10-09T01:05:25.084Z,1349744725.084 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2012-10-09T01:05:25.091Z,1349744725.091 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2012-10-09T01:05:25.092Z,1349744725.092 [DVL_micro](INFO): NQ1 output enabled 2012-10-09T01:05:25.092Z,1349744725.092 [DVL_micro](INFO): RSSI output enabled 2012-10-09T01:05:25.092Z,1349744725.092 [DVL_micro](INFO): ADCP output enabled 2012-10-09T01:05:25.092Z,1349744725.092 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2012-10-09T01:05:25.099Z,1349744725.099 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2012-10-09T01:05:25.244Z,1349744725.244 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104 2012-10-09T01:05:25.264Z,1349744725.264 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.004330 psu 2012-10-09T01:05:25.291Z,1349744725.291 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=61 2012-10-09T01:05:25.343Z,1349744725.343 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17 2012-10-09T01:05:25.395Z,1349744725.395 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:25.515Z,1349744725.515 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037650 psu 2012-10-09T01:05:25.676Z,1349744725.676 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:05:25.768Z,1349744725.768 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.154381 psu 2012-10-09T01:05:25.779Z,1349744725.779 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:05:25.879Z,1349744725.879 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:05:25.976Z,1349744725.976 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.034901 psu 2012-10-09T01:05:25.984Z,1349744725.984 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:26.119Z,1349744726.119 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2012-10-09T01:05:26.176Z,1349744726.176 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-09T01:05:26.184Z,1349744726.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.600389 psu 2012-10-09T01:05:26.231Z,1349744726.231 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2012-10-09T01:05:26.283Z,1349744726.283 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:26.392Z,1349744726.392 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.144581 psu 2012-10-09T01:05:26.564Z,1349744726.564 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:05:26.599Z,1349744726.599 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.694389 psu 2012-10-09T01:05:26.667Z,1349744726.667 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:05:26.768Z,1349744726.768 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:05:26.807Z,1349744726.807 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.680960 psu 2012-10-09T01:05:26.872Z,1349744726.872 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:27.004Z,1349744727.004 [NAL9602](INFO): Powering down 2012-10-09T01:05:27.015Z,1349744727.015 [CTD_NeilBrown](ERROR): Salinity reading out of range: 12.987030 psu 2012-10-09T01:05:27.223Z,1349744727.223 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.257759 psu 2012-10-09T01:05:27.254Z,1349744727.254 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=408 2012-10-09T01:05:27.364Z,1349744727.364 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2012-10-09T01:05:27.431Z,1349744727.431 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.224140 psu 2012-10-09T01:05:27.503Z,1349744727.503 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2012-10-09T01:05:27.639Z,1349744727.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.923420 psu 2012-10-09T01:05:27.651Z,1349744727.651 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:05:27.847Z,1349744727.847 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.743731 psu 2012-10-09T01:05:27.971Z,1349744727.971 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-09T01:05:28.056Z,1349744728.056 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.900101 psu 2012-10-09T01:05:28.087Z,1349744728.088 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36 2012-10-09T01:05:28.203Z,1349744728.203 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-09T01:05:28.260Z,1349744728.260 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.138170 psu 2012-10-09T01:05:28.319Z,1349744728.319 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:05:28.563Z,1349744728.563 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.303560 psu 2012-10-09T01:05:28.772Z,1349744728.772 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.716141 psu 2012-10-09T01:05:28.843Z,1349744728.843 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28 2012-10-09T01:05:28.980Z,1349744728.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.324011 psu 2012-10-09T01:05:29.079Z,1349744729.079 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36 2012-10-09T01:05:29.184Z,1349744729.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.167620 psu 2012-10-09T01:05:29.315Z,1349744729.315 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44 2012-10-09T01:05:29.391Z,1349744729.391 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.392910 psu 2012-10-09T01:05:29.552Z,1349744729.552 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:05:29.599Z,1349744729.599 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.408260 psu 2012-10-09T01:05:29.807Z,1349744729.807 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.958139 psu 2012-10-09T01:05:30.015Z,1349744730.015 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.101009 psu 2012-10-09T01:05:30.223Z,1349744730.223 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.836571 psu 2012-10-09T01:05:30.431Z,1349744730.431 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.882071 psu 2012-10-09T01:05:30.471Z,1349744730.471 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28 2012-10-09T01:05:30.639Z,1349744730.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.322321 psu 2012-10-09T01:05:30.848Z,1349744730.848 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.297359 psu 2012-10-09T01:05:30.943Z,1349744730.943 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:05:31.356Z,1349744731.356 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.466169 psu 2012-10-09T01:05:31.419Z,1349744731.419 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=44 2012-10-09T01:05:31.564Z,1349744731.564 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.472231 psu 2012-10-09T01:05:31.772Z,1349744731.772 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.418831 psu 2012-10-09T01:05:31.891Z,1349744731.891 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:05:31.980Z,1349744731.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.772221 psu 2012-10-09T01:05:32.188Z,1349744732.188 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.651020 psu 2012-10-09T01:05:32.395Z,1349744732.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.449829 psu 2012-10-09T01:05:32.603Z,1349744732.603 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.595169 psu 2012-10-09T01:05:32.775Z,1349744732.775 [DVL_micro](DEBUG): NQ1 2012-10-09T01:05:32.811Z,1349744732.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.034760 psu 2012-10-09T01:05:32.816Z,1349744732.816 [DVL_micro](DEBUG): ADCP 2012-10-09T01:05:33.019Z,1349744733.019 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.838840 psu 2012-10-09T01:05:33.228Z,1349744733.228 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.863630 psu 2012-10-09T01:05:33.432Z,1349744733.432 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.924299 psu 2012-10-09T01:05:33.635Z,1349744733.635 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=30 2012-10-09T01:05:33.643Z,1349744733.644 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.059120 psu 2012-10-09T01:05:33.848Z,1349744733.848 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.317110 psu 2012-10-09T01:05:34.359Z,1349744734.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.551420 psu 2012-10-09T01:05:34.471Z,1349744734.471 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=41 2012-10-09T01:05:34.568Z,1349744734.568 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.821860 psu 2012-10-09T01:05:34.773Z,1349744734.773 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.202789 psu 2012-10-09T01:05:34.984Z,1349744734.984 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.695419 psu 2012-10-09T01:05:35.188Z,1349744735.188 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.565880 psu 2012-10-09T01:05:35.307Z,1349744735.307 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18 2012-10-09T01:05:35.395Z,1349744735.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.685169 psu 2012-10-09T01:05:35.603Z,1349744735.603 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.918131 psu 2012-10-09T01:05:35.811Z,1349744735.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.130791 psu 2012-10-09T01:05:36.019Z,1349744736.019 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.060820 psu 2012-10-09T01:05:36.143Z,1349744736.143 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-09T01:05:36.227Z,1349744736.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.588440 psu 2012-10-09T01:05:36.378Z,1349744736.378 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1096 2012-10-09T01:05:36.383Z,1349744736.383 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=572 2012-10-09T01:05:36.391Z,1349744736.391 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2012-10-09T01:05:36.436Z,1349744736.436 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.125810 psu 2012-10-09T01:05:36.443Z,1349744736.443 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:36.644Z,1349744736.644 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.210649 psu 2012-10-09T01:05:36.723Z,1349744736.723 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:05:36.832Z,1349744736.832 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92 2012-10-09T01:05:36.931Z,1349744736.931 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:05:36.948Z,1349744736.948 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.190880 psu 2012-10-09T01:05:37.032Z,1349744737.032 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:37.156Z,1349744737.156 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.671530 psu 2012-10-09T01:05:37.167Z,1349744737.167 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8 2012-10-09T01:05:37.223Z,1349744737.223 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28 2012-10-09T01:05:37.275Z,1349744737.275 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2012-10-09T01:05:37.328Z,1349744737.328 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:37.364Z,1349744737.365 [CTD_NeilBrown](ERROR): Salinity reading out of range: 14.423500 psu 2012-10-09T01:05:37.571Z,1349744737.571 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.885889 psu 2012-10-09T01:05:37.611Z,1349744737.611 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:05:37.715Z,1349744737.715 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:05:37.775Z,1349744737.776 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.233431 psu 2012-10-09T01:05:37.815Z,1349744737.815 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:05:37.917Z,1349744737.917 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:37.984Z,1349744737.984 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.562710 psu 2012-10-09T01:05:38.192Z,1349744738.192 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.086691 psu 2012-10-09T01:05:38.263Z,1349744738.263 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24 2012-10-09T01:05:38.400Z,1349744738.399 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.967730 psu 2012-10-09T01:05:38.411Z,1349744738.411 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=60 2012-10-09T01:05:38.552Z,1349744738.552 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0 2012-10-09T01:05:38.607Z,1349744738.607 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.638910 psu 2012-10-09T01:05:38.695Z,1349744738.695 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:05:38.815Z,1349744738.815 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.960541 psu 2012-10-09T01:05:39.019Z,1349744739.019 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60 2012-10-09T01:05:39.024Z,1349744739.024 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.559290 psu 2012-10-09T01:05:39.131Z,1349744739.131 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:05:39.232Z,1349744739.232 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.671749 psu 2012-10-09T01:05:39.255Z,1349744739.255 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=76 2012-10-09T01:05:39.367Z,1349744739.367 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:05:39.740Z,1349744739.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.614790 psu 2012-10-09T01:05:39.891Z,1349744739.891 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60 2012-10-09T01:05:39.948Z,1349744739.948 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.234659 psu 2012-10-09T01:05:40.124Z,1349744740.124 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:05:40.155Z,1349744740.155 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.670240 psu 2012-10-09T01:05:40.364Z,1349744740.364 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44 2012-10-09T01:05:40.365Z,1349744740.365 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.449711 psu 2012-10-09T01:05:40.571Z,1349744740.571 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.064430 psu 2012-10-09T01:05:40.599Z,1349744740.599 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:05:40.779Z,1349744740.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.583691 psu 2012-10-09T01:05:40.987Z,1349744740.987 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.807770 psu 2012-10-09T01:05:41.197Z,1349744741.197 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.762550 psu 2012-10-09T01:05:41.403Z,1349744741.403 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.566010 psu 2012-10-09T01:05:41.520Z,1349744741.520 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=29 2012-10-09T01:05:41.611Z,1349744741.611 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.884359 psu 2012-10-09T01:05:41.820Z,1349744741.820 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.829380 psu 2012-10-09T01:05:41.995Z,1349744741.995 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5 2012-10-09T01:05:42.028Z,1349744742.028 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.306400 psu 2012-10-09T01:05:42.231Z,1349744742.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.323021 psu 2012-10-09T01:05:42.467Z,1349744742.467 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13 2012-10-09T01:05:42.540Z,1349744742.540 [CTD_NeilBrown](ERROR): Salinity reading out of range: 14.982420 psu 2012-10-09T01:05:42.749Z,1349744742.749 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.775909 psu 2012-10-09T01:05:42.939Z,1349744742.939 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:05:42.955Z,1349744742.955 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.765230 psu 2012-10-09T01:05:43.160Z,1349744743.160 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.615999 psu 2012-10-09T01:05:43.368Z,1349744743.368 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.719160 psu 2012-10-09T01:05:43.576Z,1349744743.576 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.995440 psu 2012-10-09T01:05:43.783Z,1349744743.783 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.004801 psu 2012-10-09T01:05:43.823Z,1349744743.823 [DVL_micro](DEBUG): NQ1 2012-10-09T01:05:43.863Z,1349744743.863 [DVL_micro](DEBUG): ADCP 2012-10-09T01:05:43.991Z,1349744743.991 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.624861 psu 2012-10-09T01:05:44.199Z,1349744744.199 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.395420 psu 2012-10-09T01:05:44.404Z,1349744744.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.321329 psu 2012-10-09T01:05:44.613Z,1349744744.613 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.405300 psu 2012-10-09T01:05:44.683Z,1349744744.683 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=3 2012-10-09T01:05:44.819Z,1349744744.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.336399 psu 2012-10-09T01:05:45.027Z,1349744745.027 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.719170 psu 2012-10-09T01:05:45.332Z,1349744745.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.041491 psu 2012-10-09T01:05:45.519Z,1349744745.519 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=11 2012-10-09T01:05:45.541Z,1349744745.541 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.659610 psu 2012-10-09T01:05:45.747Z,1349744745.747 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.929291 psu 2012-10-09T01:05:45.955Z,1349744745.955 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.706129 psu 2012-10-09T01:05:46.163Z,1349744746.163 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.059700 psu 2012-10-09T01:05:46.359Z,1349744746.359 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=20 2012-10-09T01:05:46.371Z,1349744746.371 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.016951 psu 2012-10-09T01:05:46.576Z,1349744746.576 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.547920 psu 2012-10-09T01:05:46.784Z,1349744746.784 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.419201 psu 2012-10-09T01:05:46.991Z,1349744746.991 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.238041 psu 2012-10-09T01:05:47.195Z,1349744747.195 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-09T01:05:47.200Z,1349744747.200 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.578430 psu 2012-10-09T01:05:47.460Z,1349744747.460 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1480 2012-10-09T01:05:47.461Z,1349744747.461 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=860 2012-10-09T01:05:47.461Z,1349744747.461 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=240 2012-10-09T01:05:47.491Z,1349744747.491 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:47.616Z,1349744747.616 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.279869 psu 2012-10-09T01:05:47.775Z,1349744747.775 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=57 2012-10-09T01:05:47.823Z,1349744747.823 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.375250 psu 2012-10-09T01:05:47.875Z,1349744747.875 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=29 2012-10-09T01:05:47.979Z,1349744747.979 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=33 2012-10-09T01:05:48.079Z,1349744748.079 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:48.129Z,1349744748.129 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.841181 psu 2012-10-09T01:05:48.219Z,1349744748.219 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2012-10-09T01:05:48.275Z,1349744748.276 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2012-10-09T01:05:48.328Z,1349744748.328 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48 2012-10-09T01:05:48.335Z,1349744748.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.120220 psu 2012-10-09T01:05:48.379Z,1349744748.379 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:48.544Z,1349744748.544 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.753639 psu 2012-10-09T01:05:48.659Z,1349744748.659 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24 2012-10-09T01:05:48.751Z,1349744748.751 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.560930 psu 2012-10-09T01:05:48.763Z,1349744748.763 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28 2012-10-09T01:05:48.863Z,1349744748.863 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:05:48.959Z,1349744748.959 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.652740 psu 2012-10-09T01:05:48.967Z,1349744748.967 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:49.167Z,1349744749.167 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.882401 psu 2012-10-09T01:05:49.315Z,1349744749.315 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56 2012-10-09T01:05:49.375Z,1349744749.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.167191 psu 2012-10-09T01:05:49.459Z,1349744749.459 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2012-10-09T01:05:49.583Z,1349744749.583 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.234570 psu 2012-10-09T01:05:49.603Z,1349744749.603 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32 2012-10-09T01:05:49.747Z,1349744749.747 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:05:49.791Z,1349744749.791 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.394131 psu 2012-10-09T01:05:49.000Z,1349744750.000 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.315359 psu 2012-10-09T01:05:50.068Z,1349744750.068 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-09T01:05:50.179Z,1349744750.179 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=5 2012-10-09T01:05:50.204Z,1349744750.204 [CTD_NeilBrown](ERROR): Salinity reading out of range: 15.942920 psu 2012-10-09T01:05:50.300Z,1349744750.300 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=45 2012-10-09T01:05:50.412Z,1349744750.411 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.644230 psu 2012-10-09T01:05:50.415Z,1349744750.415 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:05:50.620Z,1349744750.620 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.924360 psu 2012-10-09T01:05:50.920Z,1349744750.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.802679 psu 2012-10-09T01:05:50.939Z,1349744750.939 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28 2012-10-09T01:05:51.128Z,1349744751.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.081551 psu 2012-10-09T01:05:51.175Z,1349744751.175 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36 2012-10-09T01:05:51.335Z,1349744751.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.669600 psu 2012-10-09T01:05:51.411Z,1349744751.411 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12 2012-10-09T01:05:51.541Z,1349744751.542 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.873840 psu 2012-10-09T01:05:51.647Z,1349744751.647 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:05:51.752Z,1349744751.752 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.308331 psu 2012-10-09T01:05:51.959Z,1349744751.959 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.845860 psu 2012-10-09T01:05:52.168Z,1349744752.168 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.973560 psu 2012-10-09T01:05:52.375Z,1349744752.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.484501 psu 2012-10-09T01:05:52.567Z,1349744752.567 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28 2012-10-09T01:05:52.583Z,1349744752.583 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.593691 psu 2012-10-09T01:05:52.792Z,1349744752.792 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.445990 psu 2012-10-09T01:05:52.000Z,1349744753.000 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.729010 psu 2012-10-09T01:05:53.039Z,1349744753.039 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:05:53.207Z,1349744753.207 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.136120 psu 2012-10-09T01:05:53.412Z,1349744753.412 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.549721 psu 2012-10-09T01:05:53.515Z,1349744753.515 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=44 2012-10-09T01:05:53.719Z,1349744753.719 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.844021 psu 2012-10-09T01:05:53.927Z,1349744753.927 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.702971 psu 2012-10-09T01:05:53.988Z,1349744753.988 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:05:54.132Z,1349744754.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: 12.284010 psu 2012-10-09T01:05:54.343Z,1349744754.343 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.015970 psu 2012-10-09T01:05:54.548Z,1349744754.548 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.107269 psu 2012-10-09T01:05:54.755Z,1349744754.755 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.126450 psu 2012-10-09T01:05:54.871Z,1349744754.871 [DVL_micro](DEBUG): NQ1 2012-10-09T01:05:54.903Z,1349744754.903 [DVL_micro](DEBUG): ADCP 2012-10-09T01:05:54.963Z,1349744754.963 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.481430 psu 2012-10-09T01:05:55.171Z,1349744755.171 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.468519 psu 2012-10-09T01:05:55.375Z,1349744755.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.943480 psu 2012-10-09T01:05:55.584Z,1349744755.584 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.987869 psu 2012-10-09T01:05:55.731Z,1349744755.731 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=12 2012-10-09T01:05:55.792Z,1349744755.792 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.244020 psu 2012-10-09T01:05:55.000Z,1349744756.000 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.871929 psu 2012-10-09T01:05:56.213Z,1349744756.213 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.144239 psu 2012-10-09T01:05:56.511Z,1349744756.511 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.259081 psu 2012-10-09T01:05:56.571Z,1349744756.571 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=52 2012-10-09T01:05:56.720Z,1349744756.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.801901 psu 2012-10-09T01:05:56.927Z,1349744756.927 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.499950 psu 2012-10-09T01:05:57.135Z,1349744757.135 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.859961 psu 2012-10-09T01:05:57.343Z,1349744757.343 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.094549 psu 2012-10-09T01:05:57.407Z,1349744757.407 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=28 2012-10-09T01:05:57.551Z,1349744757.551 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.542870 psu 2012-10-09T01:05:57.759Z,1349744757.759 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.915001 psu 2012-10-09T01:05:57.963Z,1349744757.963 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.370430 psu 2012-10-09T01:05:58.172Z,1349744758.172 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.969419 psu 2012-10-09T01:05:58.243Z,1349744758.243 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-09T01:05:58.380Z,1349744758.380 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.446220 psu 2012-10-09T01:05:58.538Z,1349744758.538 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1800 2012-10-09T01:05:58.543Z,1349744758.543 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1180 2012-10-09T01:05:58.543Z,1349744758.543 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=624 2012-10-09T01:05:58.544Z,1349744758.544 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:58.588Z,1349744758.588 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.404869 psu 2012-10-09T01:05:58.796Z,1349744758.796 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.614040 psu 2012-10-09T01:05:58.823Z,1349744758.823 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=57 2012-10-09T01:05:58.927Z,1349744758.927 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=61 2012-10-09T01:05:59.003Z,1349744759.003 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.596081 psu 2012-10-09T01:05:59.027Z,1349744759.027 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=33 2012-10-09T01:05:59.132Z,1349744759.132 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:05:59.269Z,1349744759.269 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40 2012-10-09T01:05:59.307Z,1349744759.307 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.559570 psu 2012-10-09T01:05:59.323Z,1349744759.323 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60 2012-10-09T01:05:59.375Z,1349744759.375 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16 2012-10-09T01:05:59.427Z,1349744759.427 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4 2012-10-09T01:05:59.515Z,1349744759.515 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.125410 psu 2012-10-09T01:05:59.711Z,1349744759.711 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56 2012-10-09T01:05:59.723Z,1349744759.723 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.521620 psu 2012-10-09T01:05:59.815Z,1349744759.815 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60 2012-10-09T01:05:59.915Z,1349744759.915 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0 2012-10-09T01:05:59.931Z,1349744759.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.586309 psu 2012-10-09T01:06:00.019Z,1349744760.019 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4 2012-10-09T01:06:00.139Z,1349744760.139 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.310490 psu 2012-10-09T01:06:00.343Z,1349744760.343 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.599939 psu 2012-10-09T01:06:00.364Z,1349744760.365 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56 2012-10-09T01:06:00.507Z,1349744760.507 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28 2012-10-09T01:06:00.552Z,1349744760.552 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.815310 psu 2012-10-09T01:06:00.651Z,1349744760.651 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32 2012-10-09T01:06:00.760Z,1349744760.760 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.380180 psu 2012-10-09T01:06:00.796Z,1349744760.796 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4 2012-10-09T01:06:00.969Z,1349744760.969 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.629101 psu 2012-10-09T01:06:01.115Z,1349744761.115 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28 2012-10-09T01:06:01.176Z,1349744761.176 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.458401 psu 2012-10-09T01:06:01.231Z,1349744761.231 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:06:01.347Z,1349744761.347 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12 2012-10-09T01:06:01.384Z,1349744761.384 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.857149 psu 2012-10-09T01:06:01.463Z,1349744761.464 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4 2012-10-09T01:06:01.592Z,1349744761.592 [CTD_NeilBrown](ERROR): Salinity reading out of range: 22.987360 psu 2012-10-09T01:06:01.800Z,1349744761.800 [CTD_NeilBrown](ERROR): Salinity reading out of range: 17.022890 psu 2012-10-09T01:06:01.991Z,1349744761.991 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60 2012-10-09T01:06:02.103Z,1349744762.103 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.010429 psu 2012-10-09T01:06:02.223Z,1349744762.223 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:06:02.311Z,1349744762.311 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.013750 psu 2012-10-09T01:06:02.463Z,1349744762.463 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44 2012-10-09T01:06:02.516Z,1349744762.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.260639 psu 2012-10-09T01:06:02.700Z,1349744762.700 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4 2012-10-09T01:06:02.723Z,1349744762.723 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.994539 psu 2012-10-09T01:06:02.933Z,1349744762.933 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.171360 psu 2012-10-09T01:06:03.141Z,1349744763.141 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.257710 psu 2012-10-09T01:06:03.347Z,1349744763.348 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.188440 psu 2012-10-09T01:06:03.560Z,1349744763.560 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.271589 psu 2012-10-09T01:06:03.619Z,1349744763.619 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28 2012-10-09T01:06:03.764Z,1349744763.764 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.274660 psu 2012-10-09T01:06:03.971Z,1349744763.971 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.134649 psu 2012-10-09T01:06:04.091Z,1349744764.091 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:06:04.180Z,1349744764.180 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.433260 psu 2012-10-09T01:06:04.384Z,1349744764.384 [CTD_NeilBrown](ERROR): Salinity reading out of range: 27.955721 psu 2012-10-09T01:06:04.568Z,1349744764.568 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12 2012-10-09T01:06:04.592Z,1349744764.592 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.333300 psu 2012-10-09T01:06:04.899Z,1349744764.899 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.518459 psu 2012-10-09T01:06:05.042Z,1349744765.042 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4 2012-10-09T01:06:05.107Z,1349744765.107 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.152769 psu 2012-10-09T01:06:05.315Z,1349744765.315 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.980511 psu 2012-10-09T01:06:05.523Z,1349744765.523 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.078659 psu 2012-10-09T01:06:05.727Z,1349744765.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.788610 psu 2012-10-09T01:06:05.923Z,1349744765.923 [DVL_micro](DEBUG): NQ1 2012-10-09T01:06:05.937Z,1349744765.937 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.225229 psu 2012-10-09T01:06:05.977Z,1349744765.977 [DVL_micro](DEBUG): ADCP 2012-10-09T01:06:06.143Z,1349744766.143 [CTD_NeilBrown](ERROR): Salinity reading out of range: 19.393570 psu 2012-10-09T01:06:06.352Z,1349744766.352 [CTD_NeilBrown](ERROR): Salinity reading out of range: 18.073370 psu 2012-10-09T01:06:06.559Z,1349744766.559 [CTD_NeilBrown](ERROR): Salinity reading out of range: 16.103590 psu 2012-10-09T01:06:06.768Z,1349744766.768 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.356230 psu 2012-10-09T01:06:06.783Z,1349744766.783 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=31 2012-10-09T01:06:06.975Z,1349744766.975 [CTD_NeilBrown](ERROR): Salinity reading out of range: 20.929020 psu 2012-10-09T01:06:07.179Z,1349744767.180 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.928209 psu 2012-10-09T01:06:07.351Z,1349744767.351 [CommandLine](IMPORTANT): got command stop 2012-10-09T01:06:07.387Z,1349744767.387 [CTD_NeilBrown](ERROR): Salinity reading out of range: 26.715420 psu 2012-10-09T01:06:07.619Z,1349744767.619 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=39 2012-10-09T01:06:07.619Z,1349744767.619 [science_to_67_70] Stopped 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:AltEnvWrapper] Stopped 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:B.DepthEnvelope] Stopped 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize. 2012-10-09T01:06:07.620Z,1349744767.620 [science_to_67_70:OffshoreWrapper] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component. 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:A] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:B] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:C] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:D] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:E] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:F] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:G] Stopped 2012-10-09T01:06:07.621Z,1349744767.621 [science_to_67_70:Science:PeakDetectChl] Stopped 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:G.Buoyancy] Stopped 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:H.SetSpeed] Stopped 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:I.DepthEnvelope] Stopped 2012-10-09T01:06:07.622Z,1349744767.622 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize. 2012-10-09T01:06:07.623Z,1349744767.623 [science_to_67_70:J.YoYo] Stopped 2012-10-09T01:06:07.623Z,1349744767.623 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent. 2012-10-09T01:06:07.623Z,1349744767.623 [science_to_67_70:Wpt.Point] Stopped 2012-10-09T01:06:07.779Z,1349744767.779 [WetLabsBB2FL](INFO): Powering down 2012-10-09T01:06:08.455Z,1349744768.455 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=16 2012-10-09T01:06:08.556Z,1349744768.556 [MissionManager](IMPORTANT): Started mission Default 2012-10-09T01:06:08.556Z,1349744768.556 [Default] Running Loop=1 2012-10-09T01:06:08.556Z,1349744768.556 [Default](INFO): Aggregate::initialize Default 2012-10-09T01:06:08.556Z,1349744768.556 [Default:D.SetSpeed] Running Loop=1 2012-10-09T01:06:08.556Z,1349744768.556 [Default:D.SetSpeed](DEBUG): Initialize. 2012-10-09T01:06:08.556Z,1349744768.556 [Default:E.GoToSurface] Running Loop=1 2012-10-09T01:06:08.556Z,1349744768.556 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:06:08.556Z,1349744768.556 [Default:Iridium] Running Loop=1 2012-10-09T01:06:08.557Z,1349744768.556 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-10-09T01:06:08.557Z,1349744768.557 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-10-09T01:06:08.557Z,1349744768.557 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-10-09T01:06:08.557Z,1349744768.557 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-10-09T01:06:08.557Z,1349744768.557 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:06:08.559Z,1349744768.559 [Default:Iridium:B.GoToSurface] Stopped 2012-10-09T01:06:08.559Z,1349744768.559 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:06:08.560Z,1349744768.560 [Default:Iridium:Read_Iridium] Running Loop=1 2012-10-09T01:06:09.291Z,1349744769.291 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4 2012-10-09T01:06:09.334Z,1349744769.334 [NAL9602](INFO): Powering up 2012-10-09T01:06:09.491Z,1349744769.491 [DVL_micro](INFO): pause:Powering down 2012-10-09T01:06:19.899Z,1349744779.899 [NAL9602](INFO): NAL9602 initialized 2012-10-09T01:09:42.927Z,1349744982.927 [CommandLine](IMPORTANT): got command show variable mass 2012-10-09T01:09:42.928Z,1349744982.928 [CommandLine](INFO): mass_concentration_of_chlorophyll_in_sea_water (unknown) 2012-10-09T01:09:42.928Z,1349744982.928 [CommandLine](INFO): mass_concentration_of_oxygen_in_sea_water (unknown) 2012-10-09T01:09:42.929Z,1349744982.930 [CommandLine](INFO): platform_mass_position (meter) 2012-10-09T01:09:42.932Z,1349744982.932 [CommandLine](INFO): Config/vehicle.MassServo_card (none) 2012-10-09T01:09:42.933Z,1349744982.933 [CommandLine](INFO): Config/vehicle.MassServo_uart (none) 2012-10-09T01:09:42.933Z,1349744982.933 [CommandLine](INFO): Config/vehicle.MassServo_baud (bit_per_second) 2012-10-09T01:09:42.938Z,1349744982.938 [CommandLine](INFO): Config/Servo.MassServo_enable (bool) 2012-10-09T01:09:42.938Z,1349744982.938 [CommandLine](INFO): Config/Servo.MassServo_useHardware (bool) 2012-10-09T01:09:42.939Z,1349744982.939 [CommandLine](INFO): MassServo.powerOnTimeout (second) 2012-10-09T01:09:42.939Z,1349744982.939 [CommandLine](INFO): MassServo.currLimit (percent) 2012-10-09T01:09:42.939Z,1349744982.940 [CommandLine](INFO): MassServo.limitHi (count) 2012-10-09T01:09:42.940Z,1349744982.940 [CommandLine](INFO): MassServo.limitLo (count) 2012-10-09T01:09:42.940Z,1349744982.940 [CommandLine](INFO): MassServo.overloadTimeout (millisecond) 2012-10-09T01:09:42.941Z,1349744982.941 [CommandLine](INFO): MassServo.accel (none) 2012-10-09T01:09:42.941Z,1349744982.941 [CommandLine](INFO): MassServo.velocity (none) 2012-10-09T01:09:42.941Z,1349744982.941 [CommandLine](INFO): MassServo.totalTks (count) 2012-10-09T01:09:42.942Z,1349744982.942 [CommandLine](INFO): MassServo.tksPerMM (count_per_millimeter) 2012-10-09T01:09:42.942Z,1349744982.942 [CommandLine](INFO): MassServo.deviationDistance (millimeter) 2012-10-09T01:09:42.945Z,1349744982.945 [CommandLine](INFO): VerticalControl.kdPitchMass (second) 2012-10-09T01:09:42.945Z,1349744982.945 [CommandLine](INFO): VerticalControl.kiPitchMass (reciprocal_second) 2012-10-09T01:09:42.946Z,1349744982.945 [CommandLine](INFO): VerticalControl.kpPitchMass (none) 2012-10-09T01:09:42.946Z,1349744982.946 [CommandLine](INFO): VerticalControl.massDeadband (millimeter) 2012-10-09T01:09:42.947Z,1349744982.947 [CommandLine](INFO): VerticalControl.massDefault (centimeter) 2012-10-09T01:09:42.947Z,1349744982.947 [CommandLine](INFO): VerticalControl.massFilterLimit (degree) 2012-10-09T01:09:42.947Z,1349744982.947 [CommandLine](INFO): VerticalControl.massFilterWidth (second) 2012-10-09T01:09:42.948Z,1349744982.948 [CommandLine](INFO): VerticalControl.massPositionLimitFwd (millimeter) 2012-10-09T01:09:42.948Z,1349744982.948 [CommandLine](INFO): VerticalControl.massPositionLimitAft (millimeter) 2012-10-09T01:09:42.948Z,1349744982.948 [CommandLine](INFO): VerticalControl.massTurnTime (second) 2012-10-09T01:09:42.949Z,1349744982.949 [CommandLine](INFO): VerticalControl.maxPitchMassInt (millimeter) 2012-10-09T01:09:42.950Z,1349744982.950 [CommandLine](INFO): Config/Simulator.mass (kilogram) 2012-10-09T01:09:42.951Z,1349744982.951 [CommandLine](INFO): Config/Simulator.centerOfMassX (meter) 2012-10-09T01:09:42.951Z,1349744982.951 [CommandLine](INFO): Config/Simulator.centerOfMassY (meter) 2012-10-09T01:09:42.951Z,1349744982.951 [CommandLine](INFO): Config/Simulator.centerOfMassZ (meter) 2012-10-09T01:09:42.952Z,1349744982.952 [CommandLine](INFO): Config/Simulator.dropWt1Mass (kilogram) 2012-10-09T01:09:42.953Z,1349744982.953 [CommandLine](INFO): Config/Simulator.movableMass (kilogram) 2012-10-09T01:09:42.953Z,1349744982.953 [CommandLine](INFO): Config/Simulator.centerOfMovableMassX (meter) 2012-10-09T01:09:42.954Z,1349744982.954 [CommandLine](INFO): Config/Simulator.centerOfMovableMassY (meter) 2012-10-09T01:09:42.954Z,1349744982.954 [CommandLine](INFO): Config/Simulator.centerOfMovableMassZ (meter) 2012-10-09T01:09:42.957Z,1349744982.957 [CommandLine](INFO): Config/Simulator.initMassPosition (centimeter) 2012-10-09T01:09:42.958Z,1349744982.958 [CommandLine](INFO): Config/Simulator.massPositionOffset (millimeter) 2012-10-09T01:09:42.959Z,1349744982.959 [CommandLine](INFO): Config/Simulator.wideHystMovableMass (millimeter) 2012-10-09T01:09:42.960Z,1349744982.960 [CommandLine](INFO): Config/Simulator.centerHystMovableMass (millimeter) 2012-10-09T01:09:42.960Z,1349744982.960 [CommandLine](INFO): Config/Simulator.speedMovableMass (millimeter_per_second) 2012-10-09T01:09:42.962Z,1349744982.962 [CommandLine](INFO): VerticalControl.massPositionAction (meter) 2012-10-09T01:09:42.963Z,1349744982.963 [CommandLine](INFO): InternalSim.platform_mass_position (meter) 2012-10-09T01:09:42.963Z,1349744982.963 [CommandLine](INFO): VerticalControl.massPositionCmd (meter) 2012-10-09T01:09:42.964Z,1349744982.964 [CommandLine](INFO): MassServo.platform_mass_position (meter) 2012-10-09T01:09:42.965Z,1349744982.965 [CommandLine](INFO): VerticalControl.massIntegralInternal (meter) 2012-10-09T01:09:42.966Z,1349744982.966 [CommandLine](INFO): VerticalControl.massPitchErrorInternal (radian) 2012-10-09T01:09:42.970Z,1349744982.970 [CommandLine](INFO): WetLabsBB2FL.mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter) 2012-10-09T01:09:42.972Z,1349744982.972 [CommandLine](INFO): MassServo.durationOfLastRun (second) 2012-10-09T01:09:42.973Z,1349744982.973 [CommandLine](INFO): MassServo.component_voltage (volt) 2012-10-09T01:09:42.973Z,1349744982.973 [CommandLine](INFO): MassServo.component_avgVoltage (volt) 2012-10-09T01:09:42.974Z,1349744982.974 [CommandLine](INFO): MassServo.component_current (milliampere) 2012-10-09T01:09:42.974Z,1349744982.974 [CommandLine](INFO): MassServo.component_avgCurrent (milliampere) 2012-10-09T01:10:17.451Z,1349745017.451 [CommandLine](IMPORTANT): got command report mod MassServo.platform_mass_position centimeter 2012-10-09T01:10:17.567Z,1349745017.567 [Reporter](INFO): MassServo.platform_mass_position 0.000033 m 2012-10-09T01:10:21.870Z,1349745021.870 [Default:CallIridium] Running Loop=1 2012-10-09T01:10:21.870Z,1349745021.870 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-10-09T01:10:21.870Z,1349745021.870 [Default:CallIridium:A] Running Loop=1 2012-10-09T01:10:21.872Z,1349745021.872 [Default:CallIridium:A] Stopped 2012-10-09T01:10:21.872Z,1349745021.872 [Default:CallIridium:B] Running Loop=1 2012-10-09T01:10:21.873Z,1349745021.872 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-10-09T01:10:22.774Z,1349745022.774 [Default:GPS] Running Loop=1 2012-10-09T01:10:22.775Z,1349745022.775 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-10-09T01:10:22.775Z,1349745022.775 [Default:GPS:A.SetSpeed] Running Loop=1 2012-10-09T01:10:22.775Z,1349745022.775 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-10-09T01:10:22.775Z,1349745022.775 [Default:GPS:B.GoToSurface] Running Loop=1 2012-10-09T01:10:22.775Z,1349745022.775 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-10-09T01:10:22.776Z,1349745022.776 [Default:GPS:B.GoToSurface] Stopped 2012-10-09T01:10:22.776Z,1349745022.776 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-10-09T01:10:22.776Z,1349745022.776 [Default:GPS:Read_GPS] Running Loop=1 2012-10-09T01:10:23.003Z,1349745023.003 [Default:GPS:Read_GPS] Stopped 2012-10-09T01:10:23.003Z,1349745023.003 [Default:GPS:D] Running Loop=1 2012-10-09T01:10:23.398Z,1349745023.398 [Default:GPS:D] Stopped 2012-10-09T01:10:23.399Z,1349745023.399 [Default:GPS](INFO): Completed Default:GPS 2012-10-09T01:10:23.399Z,1349745023.399 [Default:GPS] Stopped 2012-10-09T01:10:23.399Z,1349745023.399 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-10-09T01:10:23.399Z,1349745023.399 [Default:GPS:A.SetSpeed] Stopped 2012-10-09T01:10:23.399Z,1349745023.399 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-10-09T01:10:25.263Z,1349745025.263 [CommandLine](IMPORTANT): got command report clear 2012-10-09T01:10:34.983Z,1349745034.983 [CommandLine](IMPORTANT): got command get MassServo.platform_mass_position centimeter 2012-10-09T01:10:34.983Z,1349745034.983 [CommandLine](IMPORTANT): MassServo.platform_mass_position 0.003285 centimeter 2012-10-09T01:10:43.707Z,1349745043.707 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 3.000000 centimeter 2012-10-09T01:10:43.709Z,1349745043.708 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2012-10-09T01:10:43.756Z,1349745043.756 [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,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-09T01:10:45.727Z,1349745045.727 [CommandLine](IMPORTANT): got command get MassServo.platform_mass_position centimeter 2012-10-09T01:10:45.728Z,1349745045.728 [CommandLine](IMPORTANT): MassServo.platform_mass_position 0.003285 centimeter 2012-10-09T01:10:47.711Z,1349745047.711 [CommandLine](IMPORTANT): got command get MassServo.platform_mass_position centimeter 2012-10-09T01:10:47.711Z,1349745047.711 [CommandLine](IMPORTANT): MassServo.platform_mass_position 0.073888 centimeter 2012-10-09T01:10:49.515Z,1349745049.515 [CommandLine](IMPORTANT): got command get MassServo.platform_mass_position centimeter 2012-10-09T01:10:49.515Z,1349745049.515 [CommandLine](IMPORTANT): MassServo.platform_mass_position 0.199441 centimeter 2012-10-09T01:10:56.991Z,1349745056.991 [CommandLine](IMPORTANT): got command report mod MassServo.platform_mass_position centimeter 2012-10-09T01:10:57.131Z,1349745057.131 [Reporter](INFO): MassServo.platform_mass_position 0.007736 m 2012-10-09T01:10:57.530Z,1349745057.530 [Reporter](INFO): MassServo.platform_mass_position 0.008041 m 2012-10-09T01:10:58.418Z,1349745058.418 [Reporter](INFO): MassServo.platform_mass_position 0.008700 m 2012-10-09T01:10:58.666Z,1349745058.666 [Reporter](INFO): MassServo.platform_mass_position 0.008875 m 2012-10-09T01:10:59.058Z,1349745059.059 [Reporter](INFO): MassServo.platform_mass_position 0.009181 m 2012-10-09T01:10:59.470Z,1349745059.470 [Reporter](INFO): MassServo.platform_mass_position 0.009480 m 2012-10-09T01:10:59.863Z,1349745059.862 [Reporter](INFO): MassServo.platform_mass_position 0.009764 m 2012-10-09T01:11:00.259Z,1349745060.259 [Reporter](INFO): MassServo.platform_mass_position 0.010075 m 2012-10-09T01:11:00.658Z,1349745060.658 [Reporter](INFO): MassServo.platform_mass_position 0.010365 m 2012-10-09T01:11:01.067Z,1349745061.066 [Reporter](INFO): MassServo.platform_mass_position 0.010671 m 2012-10-09T01:11:01.471Z,1349745061.471 [Reporter](INFO): MassServo.platform_mass_position 0.010970 m 2012-10-09T01:11:01.866Z,1349745061.866 [Reporter](INFO): MassServo.platform_mass_position 0.011257 m 2012-10-09T01:11:02.771Z,1349745062.771 [Reporter](INFO): MassServo.platform_mass_position 0.011926 m 2012-10-09T01:11:03.023Z,1349745063.023 [Reporter](INFO): MassServo.platform_mass_position 0.012126 m 2012-10-09T01:11:03.426Z,1349745063.427 [Reporter](INFO): MassServo.platform_mass_position 0.012414 m 2012-10-09T01:11:03.822Z,1349745063.822 [Reporter](INFO): MassServo.platform_mass_position 0.012715 m 2012-10-09T01:11:04.226Z,1349745064.226 [Reporter](INFO): MassServo.platform_mass_position 0.013008 m 2012-10-09T01:11:05.114Z,1349745065.114 [Reporter](INFO): MassServo.platform_mass_position 0.013665 m 2012-10-09T01:11:05.367Z,1349745065.367 [Reporter](INFO): MassServo.platform_mass_position 0.013862 m 2012-10-09T01:11:05.766Z,1349745065.766 [Reporter](INFO): MassServo.platform_mass_position 0.014156 m 2012-10-09T01:11:06.158Z,1349745066.158 [Reporter](INFO): MassServo.platform_mass_position 0.014451 m 2012-10-09T01:11:06.558Z,1349745066.559 [Reporter](INFO): MassServo.platform_mass_position 0.014740 m 2012-10-09T01:11:06.959Z,1349745066.958 [Reporter](INFO): MassServo.platform_mass_position 0.015051 m 2012-10-09T01:11:07.366Z,1349745067.366 [Reporter](INFO): MassServo.platform_mass_position 0.015341 m 2012-10-09T01:11:07.754Z,1349745067.754 [Reporter](INFO): MassServo.platform_mass_position 0.015639 m 2012-10-09T01:11:08.167Z,1349745068.167 [Reporter](INFO): MassServo.platform_mass_position 0.015937 m 2012-10-09T01:11:08.554Z,1349745068.554 [Reporter](INFO): MassServo.platform_mass_position 0.016232 m 2012-10-09T01:11:09.463Z,1349745069.463 [Reporter](INFO): MassServo.platform_mass_position 0.016910 m 2012-10-09T01:11:09.723Z,1349745069.723 [Reporter](INFO): MassServo.platform_mass_position 0.017102 m 2012-10-09T01:11:10.119Z,1349745070.119 [Reporter](INFO): MassServo.platform_mass_position 0.017393 m 2012-10-09T01:11:10.507Z,1349745070.507 [Reporter](INFO): MassServo.platform_mass_position 0.017682 m 2012-10-09T01:11:10.918Z,1349745070.918 [Reporter](INFO): MassServo.platform_mass_position 0.017996 m 2012-10-09T01:11:11.806Z,1349745071.806 [Reporter](INFO): MassServo.platform_mass_position 0.018638 m 2012-10-09T01:11:12.054Z,1349745072.055 [Reporter](INFO): MassServo.platform_mass_position 0.018807 m 2012-10-09T01:11:12.454Z,1349745072.454 [Reporter](INFO): MassServo.platform_mass_position 0.019106 m 2012-10-09T01:11:12.846Z,1349745072.846 [Reporter](INFO): MassServo.platform_mass_position 0.019284 m 2012-10-09T01:11:13.250Z,1349745073.250 [Reporter](INFO): MassServo.platform_mass_position 0.019302 m 2012-10-09T01:11:13.642Z,1349745073.642 [MassServo](FAULT): Overload Error 2012-10-09T01:11:13.643Z,1349745073.643 [MassServo] Hardware Fault, FailCount= 1 2012-10-09T01:11:13.643Z,1349745073.643 [MassServo](ERROR): Hardware Fault 2012-10-09T01:11:13.673Z,1349745073.673 [CBIT](ERROR): Hardware Fault in component: MassServo 2012-10-09T01:11:13.674Z,1349745073.674 [Reporter](INFO): MassServo.platform_mass_position no_value 2012-10-09T01:11:14.015Z,1349745074.016 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-10-09T01:11:14.016Z,1349745074.016 [MassServo](INFO): Powering down 2012-10-09T01:11:14.019Z,1349745074.019 [CBIT](INFO): Clearing failed state for component MassServo 2012-10-09T01:11:14.019Z,1349745074.019 [MassServo] No Fault, FailCount= 1 2012-10-09T01:11:14.426Z,1349745074.426 [MassServo](DEBUG): Initializing EZServoServo. 2012-10-09T01:11:14.548Z,1349745074.548 [MassServo](DEBUG): Initialization error opening port: port not yet opened 2012-10-09T01:11:14.548Z,1349745074.548 [MassServo](DEBUG): Initializing MassServo. 2012-10-09T01:11:17.142Z,1349745077.142 [Reporter](INFO): MassServo.platform_mass_position 0.019295 m 2012-10-09T01:11:26.243Z,1349745086.243 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -3.000000 centimeter 2012-10-09T01:11:29.039Z,1349745089.039 [CommandLine](IMPORTANT): got command failComponent 2012-10-09T01:11:29.039Z,1349745089.039 [CommandLine](IMPORTANT): Failed components: 2012-10-09T01:11:29.039Z,1349745089.039 [CommandLine](IMPORTANT): No failed Components. 2012-10-09T01:11:43.626Z,1349745103.626 [Reporter](INFO): MassServo.platform_mass_position 0.000000 m 2012-10-09T01:11:43.983Z,1349745103.983 [Reporter](INFO): MassServo.platform_mass_position -0.000089 m 2012-10-09T01:11:44.395Z,1349745104.395 [Reporter](INFO): MassServo.platform_mass_position -0.000389 m 2012-10-09T01:11:45.294Z,1349745105.294 [Reporter](INFO): MassServo.platform_mass_position -0.001058 m 2012-10-09T01:11:45.544Z,1349745105.544 [Reporter](INFO): MassServo.platform_mass_position -0.001244 m 2012-10-09T01:11:45.934Z,1349745105.934 [Reporter](INFO): MassServo.platform_mass_position -0.001543 m 2012-10-09T01:11:46.334Z,1349745106.334 [Reporter](INFO): MassServo.platform_mass_position -0.001844 m 2012-10-09T01:11:46.744Z,1349745106.744 [Reporter](INFO): MassServo.platform_mass_position -0.002136 m 2012-10-09T01:11:47.134Z,1349745107.135 [Reporter](INFO): MassServo.platform_mass_position -0.002436 m 2012-10-09T01:11:47.534Z,1349745107.534 [Reporter](INFO): MassServo.platform_mass_position -0.002733 m 2012-10-09T01:11:47.934Z,1349745107.934 [Reporter](INFO): MassServo.platform_mass_position -0.003031 m 2012-10-09T01:11:48.335Z,1349745108.335 [Reporter](INFO): MassServo.platform_mass_position -0.003321 m 2012-10-09T01:11:48.734Z,1349745108.734 [Reporter](INFO): MassServo.platform_mass_position -0.003628 m 2012-10-09T01:11:49.647Z,1349745109.647 [Reporter](INFO): MassServo.platform_mass_position -0.004302 m 2012-10-09T01:11:49.899Z,1349745109.899 [Reporter](INFO): MassServo.platform_mass_position -0.004493 m 2012-10-09T01:11:50.295Z,1349745110.295 [Reporter](INFO): MassServo.platform_mass_position -0.004787 m 2012-10-09T01:11:50.691Z,1349745110.691 [Reporter](INFO): MassServo.platform_mass_position -0.005085 m 2012-10-09T01:11:51.095Z,1349745111.095 [Reporter](INFO): MassServo.platform_mass_position -0.005371 m 2012-10-09T01:11:51.995Z,1349745111.995 [Reporter](INFO): MassServo.platform_mass_position -0.006050 m 2012-10-09T01:11:52.249Z,1349745112.249 [Reporter](INFO): MassServo.platform_mass_position -0.006242 m 2012-10-09T01:11:52.647Z,1349745112.647 [Reporter](INFO): MassServo.platform_mass_position -0.006536 m 2012-10-09T01:11:53.095Z,1349745113.095 [Reporter](INFO): MassServo.platform_mass_position -0.006864 m 2012-10-09T01:11:53.563Z,1349745113.563 [Reporter](INFO): MassServo.platform_mass_position -0.007216 m 2012-10-09T01:11:53.835Z,1349745113.835 [Reporter](INFO): MassServo.platform_mass_position -0.007420 m 2012-10-09T01:11:54.234Z,1349745114.234 [Reporter](INFO): MassServo.platform_mass_position -0.007727 m 2012-10-09T01:11:54.639Z,1349745114.639 [Reporter](INFO): MassServo.platform_mass_position -0.008016 m 2012-10-09T01:11:55.035Z,1349745115.035 [Reporter](INFO): MassServo.platform_mass_position -0.008311 m 2012-10-09T01:11:55.434Z,1349745115.434 [Reporter](INFO): MassServo.platform_mass_position -0.008614 m 2012-10-09T01:11:56.345Z,1349745116.345 [Reporter](INFO): MassServo.platform_mass_position -0.009280 m 2012-10-09T01:11:56.595Z,1349745116.595 [Reporter](INFO): MassServo.platform_mass_position -0.009479 m 2012-10-09T01:11:56.987Z,1349745116.987 [Reporter](INFO): MassServo.platform_mass_position -0.009770 m 2012-10-09T01:11:57.395Z,1349745117.395 [Reporter](INFO): MassServo.platform_mass_position -0.010065 m 2012-10-09T01:11:57.803Z,1349745117.803 [Reporter](INFO): MassServo.platform_mass_position -0.010370 m 2012-10-09T01:11:58.686Z,1349745118.686 [Reporter](INFO): MassServo.platform_mass_position -0.011025 m 2012-10-09T01:11:58.947Z,1349745118.947 [Reporter](INFO): MassServo.platform_mass_position -0.011217 m 2012-10-09T01:11:59.335Z,1349745119.335 [Reporter](INFO): MassServo.platform_mass_position -0.011510 m 2012-10-09T01:11:59.730Z,1349745119.730 [Reporter](INFO): MassServo.platform_mass_position -0.011805 m 2012-10-09T01:12:00.139Z,1349745120.139 [Reporter](INFO): MassServo.platform_mass_position -0.012107 m 2012-10-09T01:12:00.531Z,1349745120.531 [Reporter](INFO): MassServo.platform_mass_position -0.012398 m 2012-10-09T01:12:00.930Z,1349745120.931 [Reporter](INFO): MassServo.platform_mass_position -0.012695 m 2012-10-09T01:12:01.339Z,1349745121.339 [Reporter](INFO): MassServo.platform_mass_position -0.013002 m 2012-10-09T01:12:01.731Z,1349745121.731 [Reporter](INFO): MassServo.platform_mass_position -0.013285 m 2012-10-09T01:12:02.131Z,1349745122.131 [Reporter](INFO): MassServo.platform_mass_position -0.013588 m 2012-10-09T01:12:03.039Z,1349745123.039 [Reporter](INFO): MassServo.platform_mass_position -0.014263 m 2012-10-09T01:12:03.295Z,1349745123.295 [Reporter](INFO): MassServo.platform_mass_position -0.014454 m 2012-10-09T01:12:03.683Z,1349745123.683 [Reporter](INFO): MassServo.platform_mass_position -0.014744 m 2012-10-09T01:12:04.087Z,1349745124.087 [Reporter](INFO): MassServo.platform_mass_position -0.015037 m 2012-10-09T01:12:04.495Z,1349745124.495 [Reporter](INFO): MassServo.platform_mass_position -0.015345 m 2012-10-09T01:12:05.382Z,1349745125.382 [Reporter](INFO): MassServo.platform_mass_position -0.016001 m 2012-10-09T01:12:05.631Z,1349745125.631 [Reporter](INFO): MassServo.platform_mass_position -0.016183 m 2012-10-09T01:12:06.023Z,1349745126.023 [Reporter](INFO): MassServo.platform_mass_position -0.016478 m 2012-10-09T01:12:06.422Z,1349745126.422 [Reporter](INFO): MassServo.platform_mass_position -0.016781 m 2012-10-09T01:12:06.831Z,1349745126.831 [Reporter](INFO): MassServo.platform_mass_position -0.017066 m 2012-10-09T01:12:07.223Z,1349745127.223 [Reporter](INFO): MassServo.platform_mass_position -0.017368 m 2012-10-09T01:12:07.626Z,1349745127.626 [Reporter](INFO): MassServo.platform_mass_position -0.017672 m 2012-10-09T01:12:08.023Z,1349745128.023 [Reporter](INFO): MassServo.platform_mass_position -0.017961 m 2012-10-09T01:12:08.423Z,1349745128.423 [Reporter](INFO): MassServo.platform_mass_position -0.018261 m 2012-10-09T01:12:08.822Z,1349745128.822 [Reporter](INFO): MassServo.platform_mass_position -0.018554 m 2012-10-09T01:12:09.735Z,1349745129.735 [Reporter](INFO): MassServo.platform_mass_position -0.019239 m 2012-10-09T01:12:09.987Z,1349745129.987 [Reporter](INFO): MassServo.platform_mass_position -0.019422 m 2012-10-09T01:12:10.379Z,1349745130.379 [Reporter](INFO): MassServo.platform_mass_position -0.019722 m 2012-10-09T01:12:10.783Z,1349745130.783 [Reporter](INFO): MassServo.platform_mass_position -0.020011 m 2012-10-09T01:12:11.187Z,1349745131.187 [Reporter](INFO): MassServo.platform_mass_position -0.020317 m 2012-10-09T01:12:12.078Z,1349745132.078 [Reporter](INFO): MassServo.platform_mass_position -0.020975 m 2012-10-09T01:12:12.327Z,1349745132.327 [Reporter](INFO): MassServo.platform_mass_position -0.021165 m 2012-10-09T01:12:12.719Z,1349745132.719 [Reporter](INFO): MassServo.platform_mass_position -0.021458 m 2012-10-09T01:12:13.130Z,1349745133.130 [Reporter](INFO): MassServo.platform_mass_position -0.021762 m 2012-10-09T01:12:13.527Z,1349745133.527 [Reporter](INFO): MassServo.platform_mass_position -0.022057 m 2012-10-09T01:12:13.918Z,1349745133.918 [Reporter](INFO): MassServo.platform_mass_position -0.022354 m 2012-10-09T01:12:14.318Z,1349745134.318 [Reporter](INFO): MassServo.platform_mass_position -0.022646 m 2012-10-09T01:12:14.719Z,1349745134.719 [Reporter](INFO): MassServo.platform_mass_position -0.022940 m 2012-10-09T01:12:15.123Z,1349745135.123 [Reporter](INFO): MassServo.platform_mass_position -0.023244 m 2012-10-09T01:12:15.519Z,1349745135.519 [Reporter](INFO): MassServo.platform_mass_position -0.023540 m 2012-10-09T01:12:16.427Z,1349745136.427 [Reporter](INFO): MassServo.platform_mass_position -0.024213 m 2012-10-09T01:12:16.675Z,1349745136.675 [Reporter](INFO): MassServo.platform_mass_position -0.024404 m 2012-10-09T01:12:17.072Z,1349745137.072 [Reporter](INFO): MassServo.platform_mass_position -0.024693 m 2012-10-09T01:12:17.479Z,1349745137.479 [Reporter](INFO): MassServo.platform_mass_position -0.024990 m 2012-10-09T01:12:17.883Z,1349745137.883 [Reporter](INFO): MassServo.platform_mass_position -0.025299 m 2012-10-09T01:12:18.775Z,1349745138.774 [Reporter](INFO): MassServo.platform_mass_position -0.025957 m 2012-10-09T01:12:19.026Z,1349745139.026 [Reporter](INFO): MassServo.platform_mass_position -0.026144 m 2012-10-09T01:12:19.423Z,1349745139.423 [Reporter](INFO): MassServo.platform_mass_position -0.026441 m 2012-10-09T01:12:19.818Z,1349745139.819 [Reporter](INFO): MassServo.platform_mass_position -0.026736 m 2012-10-09T01:12:20.223Z,1349745140.223 [Reporter](INFO): MassServo.platform_mass_position -0.027042 m 2012-10-09T01:12:20.627Z,1349745140.627 [Reporter](INFO): MassServo.platform_mass_position -0.027338 m 2012-10-09T01:12:21.019Z,1349745141.019 [Reporter](INFO): MassServo.platform_mass_position -0.027630 m 2012-10-09T01:12:21.419Z,1349745141.419 [Reporter](INFO): MassServo.platform_mass_position -0.027924 m 2012-10-09T01:12:21.819Z,1349745141.819 [Reporter](INFO): MassServo.platform_mass_position -0.028231 m 2012-10-09T01:12:22.218Z,1349745142.218 [Reporter](INFO): MassServo.platform_mass_position -0.028522 m 2012-10-09T01:12:23.127Z,1349745143.127 [Reporter](INFO): MassServo.platform_mass_position -0.029195 m 2012-10-09T01:12:23.387Z,1349745143.387 [Reporter](INFO): MassServo.platform_mass_position -0.029395 m 2012-10-09T01:12:23.771Z,1349745143.771 [Reporter](INFO): MassServo.platform_mass_position -0.029666 m 2012-10-09T01:12:24.207Z,1349745144.207 [Reporter](INFO): MassServo.platform_mass_position -0.029948 m 2012-10-09T01:12:24.611Z,1349745144.611 [Reporter](INFO): MassServo.platform_mass_position -0.029949 m 2012-10-09T01:12:32.179Z,1349745152.179 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -3.500000 centimeter 2012-10-09T01:12:32.499Z,1349745152.499 [Reporter](INFO): MassServo.platform_mass_position -0.030113 m 2012-10-09T01:12:32.891Z,1349745152.891 [Reporter](INFO): MassServo.platform_mass_position -0.030388 m 2012-10-09T01:12:33.294Z,1349745153.295 [Reporter](INFO): MassServo.platform_mass_position -0.030675 m 2012-10-09T01:12:33.699Z,1349745153.699 [Reporter](INFO): MassServo.platform_mass_position -0.030975 m 2012-10-09T01:12:34.095Z,1349745154.095 [Reporter](INFO): MassServo.platform_mass_position -0.031202 m 2012-10-09T01:12:34.491Z,1349745154.491 [Reporter](INFO): MassServo.platform_mass_position -0.031329 m 2012-10-09T01:12:34.890Z,1349745154.890 [MassServo](FAULT): Overload Error 2012-10-09T01:12:34.891Z,1349745154.891 [MassServo] Hardware Fault, FailCount= 2 2012-10-09T01:12:34.891Z,1349745154.891 [MassServo](ERROR): Hardware Fault 2012-10-09T01:12:34.921Z,1349745154.921 [CBIT](ERROR): Hardware Fault in component: MassServo 2012-10-09T01:12:34.922Z,1349745154.922 [Reporter](INFO): MassServo.platform_mass_position no_value 2012-10-09T01:12:35.264Z,1349745155.264 [MassServo](DEBUG): Uninitialize Mass Servo. 2012-10-09T01:12:35.264Z,1349745155.264 [MassServo](INFO): Powering down 2012-10-09T01:12:35.267Z,1349745155.267 [CBIT](INFO): Clearing failed state for component MassServo 2012-10-09T01:12:35.267Z,1349745155.267 [MassServo] No Fault, FailCount= 2 2012-10-09T01:12:35.660Z,1349745155.660 [MassServo](DEBUG): Initializing EZServoServo. 2012-10-09T01:12:35.784Z,1349745155.784 [MassServo](DEBUG): Initialization error opening port: port not yet opened 2012-10-09T01:12:35.784Z,1349745155.784 [MassServo](DEBUG): Initializing MassServo. 2012-10-09T01:12:37.651Z,1349745157.651 [CommandLine](IMPORTANT): got command maintain clear 2012-10-09T01:12:38.085Z,1349745158.085 [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,SBIT,IBIT,CBIT,Reporter,logger, 2012-10-09T01:12:38.921Z,1349745158.921 [Reporter](INFO): MassServo.platform_mass_position -0.031351 m 2012-10-09T01:12:39.375Z,1349745159.375 [CommandLine](IMPORTANT): got command failComponent 2012-10-09T01:12:39.375Z,1349745159.375 [CommandLine](IMPORTANT): Failed components: 2012-10-09T01:12:39.375Z,1349745159.375 [CommandLine](IMPORTANT): No failed Components. 2012-10-09T01:13:22.987Z,1349745202.987 [Reporter](INFO): MassServo.platform_mass_position 0.000000 m 2012-10-09T01:13:43.367Z,1349745223.367 [CommandLine](IMPORTANT): got command report clear 2012-10-09T01:13:45.687Z,1349745225.687 [CommandLine](IMPORTANT): got command quit 2012-10-09T01:13:46.706Z,1349745226.706 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2012-10-09T01:13:46.706Z,1349745226.707 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2012-10-09T01:13:46.747Z,1349745226.747 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2012-10-09T01:13:46.779Z,1349745226.779 [WetLabsBB2FL](INFO): Powering down 2012-10-09T01:13:46.787Z,1349745226.787 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler